Messages in this thread | | | Date | Tue, 24 Nov 2009 10:12:40 +0200 | From | Ozan Çağlayan <> | Subject | [BUG 2.6.30.9] Soft lockup CPU#X stuck for Y seconds! |
| |
Hi,
We've hit soft lockups on a 2.6.30.9 KVM guest over an HP DL580-G5 host running a 2.6.30.9 kernel as well. After the soft lockups, system time skewed back in time:
~ $ LC_ALL=C date Mon Feb 1 00:10:58 EET 1954
I'm attaching the dmesg containing the two consecutive soft lockups. One seems related to cpuidle and the other to the virtio network stuff.
Thanks. Ozan ------
[ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 2.6.30.9-128 (pardus@buildfarm) (gcc version 4.3.3 (Pardus Linux) ) #1 SMP Thu Nov 5 09:31:27 EET 2009 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] NSC Geode by NSC [ 0.000000] Cyrix CyrixInstead [ 0.000000] Centaur CentaurHauls [ 0.000000] Transmeta GenuineTMx86 [ 0.000000] Transmeta TransmetaCPU [ 0.000000] UMC UMC UMC UMC [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009f000 (usable) [ 0.000000] BIOS-e820: 000000000009f000 - 00000000000a0000 (reserved) [ 0.000000] BIOS-e820: 00000000000e8000 - 0000000000100000 (reserved) [ 0.000000] BIOS-e820: 0000000000100000 - 000000007fef0000 (usable) [ 0.000000] BIOS-e820: 000000007fef0000 - 000000007ff00000 (ACPI data) [ 0.000000] BIOS-e820: 00000000fffbc000 - 0000000100000000 (reserved) [ 0.000000] DMI 2.4 present. [ 0.000000] last_pfn = 0x7fef0 max_arch_pfn = 0x100000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-FFFFF uncachable [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 00E0000000 mask FFFFFFFFE0000000 uncachable [ 0.000000] 1 disabled [ 0.000000] 2 disabled [ 0.000000] 3 disabled [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] 7 disabled [ 0.000000] PAT not supported by CPU. [ 0.000000] e820 update range: 0000000000002000 - 0000000000006000 (usable) ==> (reserved) [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] modified physical RAM map: [ 0.000000] modified: 0000000000000000 - 0000000000002000 (usable) [ 0.000000] modified: 0000000000002000 - 0000000000006000 (reserved) [ 0.000000] modified: 0000000000006000 - 000000000009f000 (usable) [ 0.000000] modified: 000000000009f000 - 00000000000a0000 (reserved) [ 0.000000] modified: 00000000000e8000 - 0000000000100000 (reserved) [ 0.000000] modified: 0000000000100000 - 000000007fef0000 (usable) [ 0.000000] modified: 000000007fef0000 - 000000007ff00000 (ACPI data) [ 0.000000] modified: 00000000fffbc000 - 0000000100000000 (reserved) [ 0.000000] init_memory_mapping: 0000000000000000-00000000377fe000 [ 0.000000] 0000000000 - 0000400000 page 4k [ 0.000000] 0000400000 - 0037400000 page 2M [ 0.000000] 0037400000 - 00377fe000 page 4k [ 0.000000] kernel direct mapping tables up to 377fe000 @ 7000-c000 [ 0.000000] RAMDISK: 37b98000 - 37fefc72 [ 0.000000] Allocated new RAMDISK: 0070b000 - 00b62c72 [ 0.000000] Move RAMDISK from 0000000037b98000 - 0000000037fefc71 to 0070b000 - 00b62c71 [ 0.000000] ACPI: RSDP 000fbf90 00014 (v00 QEMU ) [ 0.000000] ACPI: RSDT 7fef0000 0002C (v01 QEMU QEMURSDT 00000001 QEMU 00000001) [ 0.000000] ACPI: FACP 7fef002c 00074 (v01 QEMU QEMUFACP 00000001 QEMU 00000001) [ 0.000000] ACPI: DSDT 7fef0100 02540 (v01 BXPC BXDSDT 00000001 INTL 20061109) [ 0.000000] ACPI: FACS 7fef00c0 00040 [ 0.000000] ACPI: APIC 7fef2640 000E0 (v01 QEMU QEMUAPIC 00000001 QEMU 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] 1158MB HIGHMEM available. [ 0.000000] 887MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 377fe000 [ 0.000000] low ram: 0 - 377fe000 [ 0.000000] node 0 low ram: 00000000 - 377fe000 [ 0.000000] node 0 bootmap 00008000 - 0000ef00 [ 0.000000] (9 early reservations) ==> bootmem [0000000000 - 00377fe000] [ 0.000000] #0 [0000000000 - 0000001000] BIOS data page ==> [0000000000 - 0000001000] [ 0.000000] #1 [0000001000 - 0000002000] EX TRAMPOLINE ==> [0000001000 - 0000002000] [ 0.000000] #2 [0000006000 - 0000007000] TRAMPOLINE ==> [0000006000 - 0000007000] [ 0.000000] #3 [0000100000 - 0000706fc4] TEXT DATA BSS ==> [0000100000 - 0000706fc4] [ 0.000000] #4 [000009fc00 - 0000100000] BIOS reserved ==> [000009fc00 - 0000100000] [ 0.000000] #5 [0000707000 - 000070a03d] BRK ==> [0000707000 - 000070a03d] [ 0.000000] #6 [0000007000 - 0000008000] PGTABLE ==> [0000007000 - 0000008000] [ 0.000000] #7 [000070b000 - 0000b62c72] NEW RAMDISK ==> [000070b000 - 0000b62c72] [ 0.000000] #8 [0000008000 - 000000f000] BOOTMAP ==> [0000008000 - 000000f000] [ 0.000000] found SMP MP-table at [c00fbd10] fbd10 [ 0.000000] kvm-clock: cpu 0, msr 0:64a3e1, boot clock [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000000 -> 0x00001000 [ 0.000000] Normal 0x00001000 -> 0x000377fe [ 0.000000] HighMem 0x000377fe -> 0x0007fef0 [ 0.000000] Movable zone start PFN for each node [ 0.000000] early_node_map[3] active PFN ranges [ 0.000000] 0: 0x00000000 -> 0x00000002 [ 0.000000] 0: 0x00000006 -> 0x0000009f [ 0.000000] 0: 0x00000100 -> 0x0007fef0 [ 0.000000] On node 0 totalpages: 523915 [ 0.000000] free_area_init_node: node 0, pgdat c059d6a0, node_mem_map c1000000 [ 0.000000] DMA zone: 32 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 3963 pages, LIFO batch:0 [ 0.000000] Normal zone: 1744 pages used for memmap [ 0.000000] Normal zone: 221486 pages, LIFO batch:31 [ 0.000000] HighMem zone: 2318 pages used for memmap [ 0.000000] HighMem zone: 294372 pages, LIFO batch:31 [ 0.000000] Using APIC driver default [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x04] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x05] lapic_id[0x05] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x06] lapic_id[0x06] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x07] lapic_id[0x07] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x08] lapic_id[0x08] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x09] lapic_id[0x09] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0a] lapic_id[0x0a] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0b] lapic_id[0x0b] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0c] lapic_id[0x0c] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0d] lapic_id[0x0d] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0e] lapic_id[0x0e] disabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x0f] lapic_id[0x0f] disabled) [ 0.000000] Overriding APIC driver with bigsmp [ 0.000000] ACPI: IOAPIC (id[0x0a] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 10, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] ACPI: IRQ5 used by override. [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] ACPI: IRQ10 used by override. [ 0.000000] ACPI: IRQ11 used by override. [ 0.000000] Enabling APIC mode: Physflat. Using 1 I/O APICs [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] SMP: Allowing 16 CPUs, 6 hotplug CPUs [ 0.000000] nr_irqs_gsi: 24 [ 0.000000] PM: Registered nosave memory: 0000000000002000 - 0000000000006000 [ 0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000e8000 [ 0.000000] PM: Registered nosave memory: 00000000000e8000 - 0000000000100000 [ 0.000000] Allocating PCI resources starting at 80000000 (gap: 7ff00000:800bc000) [ 0.000000] NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:16 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 12 pages at c200a000, static data 26972 bytes [ 0.000000] kvm-clock: cpu 0, msr 0:20103e1, primary cpu clock [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 519821 [ 0.000000] Kernel command line: root=LABEL=PARDUS_ROOT splash=silent quiet vga=0x314 mudur=virtio [ 0.000000] bootsplash: silent mode. [ 0.000000] Enabling fast FPU save and restore... done. [ 0.000000] Enabling unmasked SIMD FPU exception support... done. [ 0.000000] Initializing CPU#0 [ 0.000000] NR_IRQS:1280 [ 0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes) [ 0.000000] Detected 2667.088 MHz processor. [ 0.000999] Console: colour dummy device 80x25 [ 0.000999] console [tty0] enabled [ 0.000999] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000999] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000999] allocated 10480320 bytes of page_cgroup [ 0.000999] please try cgroup_disable=memory option if you don't want [ 0.000999] Initializing HighMem for node 0 (000377fe:0007fef0) [ 0.000999] Memory: 2055624k/2096064k available (3089k kernel code, 38892k reserved, 1849k data, 448k init, 1186760k highmem) [ 0.000999] virtual kernel memory layout: [ 0.000999] fixmap : 0xffe16000 - 0xfffff000 (1956 kB) [ 0.000999] pkmap : 0xff800000 - 0xffc00000 (4096 kB) [ 0.000999] vmalloc : 0xf7ffe000 - 0xff7fe000 ( 120 MB) [ 0.000999] lowmem : 0xc0000000 - 0xf77fe000 ( 887 MB) [ 0.000999] .init : 0xc05db000 - 0xc064b000 ( 448 kB) [ 0.000999] .data : 0xc04047f3 - 0xc05d2c6c (1849 kB) [ 0.000999] .text : 0xc0100000 - 0xc04047f3 (3089 kB) [ 0.000999] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000999] SLUB: Genslabs=13, HWalign=64, Order=0-3, MinObjects=0, CPUs=16, Nodes=1 [ 0.001019] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.001115] Security Framework initialized [ 0.001139] Mount-cache hash table entries: 512 [ 0.001622] Initializing cgroup subsys ns [ 0.001641] Initializing cgroup subsys cpuacct [ 0.001645] Initializing cgroup subsys memory [ 0.001660] Initializing cgroup subsys devices [ 0.001662] Initializing cgroup subsys freezer [ 0.001725] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.001727] CPU: L2 cache: 2048K [ 0.001736] Intel machine check architecture supported. [ 0.001742] Intel machine check reporting enabled on CPU#0. [ 0.009087] ACPI: Core revision 20090320 [ 0.011230] Leaving ESR disabled. [ 0.011710] ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 [ 0.021719] CPU0: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.025234] Booting processor 1 APIC 0x1 ip 0x6000 [ 0.000999] Initializing CPU#1 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#1. [ 0.036646] CPU1: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.060916] checking TSC synchronization [CPU#0 -> CPU#1]: passed. [ 0.000999] kvm-clock: cpu 1, msr 0:20203e1, secondary cpu clock [ 0.068055] Booting processor 2 APIC 0x2 ip 0x6000 [ 0.000999] Initializing CPU#2 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#2. [ 0.079512] CPU2: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.108326] checking TSC synchronization [CPU#0 -> CPU#2]: passed. [ 0.110071] kvm-clock: cpu 2, msr 0:20303e1, secondary cpu clock [ 0.117203] Booting processor 3 APIC 0x3 ip 0x6000 [ 0.000999] Initializing CPU#3 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#3. [ 0.128676] CPU3: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.156612] checking TSC synchronization [CPU#0 -> CPU#3]: passed. [ 0.000999] kvm-clock: cpu 3, msr 0:20403e1, secondary cpu clock [ 0.165913] Booting processor 4 APIC 0x4 ip 0x6000 [ 0.000999] Initializing CPU#4 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#4. [ 0.177404] CPU4: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.208195] checking TSC synchronization [CPU#0 -> CPU#4]: passed. [ 0.209984] kvm-clock: cpu 4, msr 0:20503e1, secondary cpu clock [ 0.219049] Booting processor 5 APIC 0x5 ip 0x6000 [ 0.000999] Initializing CPU#5 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#5. [ 0.230481] CPU5: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.262077] checking TSC synchronization [CPU#0 -> CPU#5]: passed. [ 0.263978] kvm-clock: cpu 5, msr 0:20603e1, secondary cpu clock [ 0.273348] Booting processor 6 APIC 0x6 ip 0x6000 [ 0.000999] Initializing CPU#6 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#6. [ 0.284801] CPU6: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.317759] checking TSC synchronization [CPU#0 -> CPU#6]: passed. [ 0.319015] kvm-clock: cpu 6, msr 0:20703e1, secondary cpu clock [ 0.329370] Booting processor 7 APIC 0x7 ip 0x6000 [ 0.000999] Initializing CPU#7 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#7. [ 0.340842] CPU7: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.375024] checking TSC synchronization [CPU#0 -> CPU#7]: passed. [ 0.377068] kvm-clock: cpu 7, msr 0:20803e1, secondary cpu clock [ 0.386494] Booting processor 8 APIC 0x8 ip 0x6000 [ 0.000999] Initializing CPU#8 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#8. [ 0.398150] CPU8: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.434186] checking TSC synchronization [CPU#0 -> CPU#8]: passed. [ 0.436016] kvm-clock: cpu 8, msr 0:20903e1, secondary cpu clock [ 0.445542] Booting processor 9 APIC 0x9 ip 0x6000 [ 0.000999] Initializing CPU#9 [ 0.000999] Leaving ESR disabled. [ 0.000999] Calibrating delay loop (skipped) preset value.. 5334.17 BogoMIPS (lpj=2667088) [ 0.000999] CPU: L1 I cache: 32K, L1 D cache: 32K [ 0.000999] CPU: L2 cache: 2048K [ 0.000999] Intel machine check architecture supported. [ 0.000999] Intel machine check reporting enabled on CPU#9. [ 0.456971] CPU9: Intel QEMU Virtual CPU version 0.10.0 stepping 03 [ 0.487732] checking TSC synchronization [CPU#0 -> CPU#9]: passed. [ 0.489064] kvm-clock: cpu 9, msr 0:20a03e1, secondary cpu clock [ 0.489091] Brought up 10 CPUs [ 0.489094] Total of 10 processors activated (53341.76 BogoMIPS). [ 0.490403] CPU0 attaching sched-domain: [ 0.490411] domain 0: span 0-9 level CPU [ 0.490413] groups: 0 1 2 3 4 5 6 7 8 9 [ 0.490422] CPU1 attaching sched-domain: [ 0.490423] domain 0: span 0-9 level CPU [ 0.490425] groups: 1 2 3 4 5 6 7 8 9 0 [ 0.490432] CPU2 attaching sched-domain: [ 0.490434] domain 0: span 0-9 level CPU [ 0.490435] groups: 2 3 4 5 6 7 8 9 0 1 [ 0.490442] CPU3 attaching sched-domain: [ 0.490444] domain 0: span 0-9 level CPU [ 0.490446] groups: 3 4 5 6 7 8 9 0 1 2 [ 0.490452] CPU4 attaching sched-domain: [ 0.490454] domain 0: span 0-9 level CPU [ 0.490456] groups: 4 5 6 7 8 9 0 1 2 3 [ 0.490462] CPU5 attaching sched-domain: [ 0.490464] domain 0: span 0-9 level CPU [ 0.490466] groups: 5 6 7 8 9 0 1 2 3 4 [ 0.490472] CPU6 attaching sched-domain: [ 0.490474] domain 0: span 0-9 level CPU [ 0.490476] groups: 6 7 8 9 0 1 2 3 4 5 [ 0.490483] CPU7 attaching sched-domain: [ 0.490484] domain 0: span 0-9 level CPU [ 0.490486] groups: 7 8 9 0 1 2 3 4 5 6 [ 0.490493] CPU8 attaching sched-domain: [ 0.490494] domain 0: span 0-9 level CPU [ 0.490496] groups: 8 9 0 1 2 3 4 5 6 7 [ 0.490503] CPU9 attaching sched-domain: [ 0.490504] domain 0: span 0-9 level CPU [ 0.490506] groups: 9 0 1 2 3 4 5 6 7 8 [ 0.492212] net_namespace: 1088 bytes [ 0.492261] Booting paravirtualized kernel on KVM [ 0.493088] regulator: core version 0.5 [ 0.493238] NET: Registered protocol family 16 [ 0.493456] EISA bus registered [ 0.493482] ACPI: bus type pci registered [ 0.493655] PCI: PCI BIOS revision 2.10 entry at 0xfb510, last bus=0 [ 0.493657] PCI: Using configuration type 1 for base access [ 0.494379] bio: create slab <bio-0> at 0 [ 0.497407] ACPI: EC: Look up EC in DSDT [ 0.500021] ACPI: Interpreter enabled [ 0.500024] ACPI: (supports S0 S3 S4 S5) [ 0.500080] ACPI: Using IOAPIC for interrupt routing [ 0.503151] ACPI: No dock devices found. [ 0.503162] ACPI: PCI Root Bridge [PCI0] (0000:00) [ 0.504027] pci 0000:00:01.1: reg 20 io port: [0xc000-0xc00f] [ 0.504451] pci 0000:00:01.3: quirk: region b000-b03f claimed by PIIX4 ACPI [ 0.504461] pci 0000:00:01.3: quirk: region b100-b10f claimed by PIIX4 SMB [ 0.508188] pci 0000:00:02.0: reg 10 32bit mmio: [0xf0000000-0xf1ffffff] [ 0.512144] pci 0000:00:02.0: reg 14 32bit mmio: [0xf2000000-0xf2000fff] [ 0.531391] pci 0000:00:03.0: reg 10 io port: [0xc020-0xc03f] [ 0.532040] pci 0000:00:04.0: reg 10 io port: [0xc040-0xc07f] [ 0.532359] pci 0000:00:05.0: reg 10 io port: [0xc080-0xc09f] [ 0.532783] pci_bus 0000:00: on NUMA node 0 [ 0.532857] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [ 0.535304] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.535396] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.535479] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.535561] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.536067] SCSI subsystem initialized [ 0.536324] usbcore: registered new interface driver usbfs [ 0.536324] usbcore: registered new interface driver hub [ 0.536324] usbcore: registered new device driver usb [ 0.536324] PCI: Using ACPI for IRQ routing [ 0.541061] Switched to high resolution mode on CPU 0 [ 0.541130] Switched to high resolution mode on CPU 1 [ 0.541206] Switched to high resolution mode on CPU 7 [ 0.541262] Switched to high resolution mode on CPU 5 [ 0.541312] Switched to high resolution mode on CPU 8 [ 0.541416] Switched to high resolution mode on CPU 4 [ 0.541703] Switched to high resolution mode on CPU 2 [ 0.541965] Switched to high resolution mode on CPU 6 [ 0.541985] Switched to high resolution mode on CPU 3 [ 0.542021] Switched to high resolution mode on CPU 9 [ 0.543042] pnp: PnP ACPI init [ 0.543068] ACPI: bus type pnp registered [ 0.543822] pnp: PnP ACPI: found 7 devices [ 0.543824] ACPI: ACPI bus type pnp unregistered [ 0.543830] PnPBIOS: Disabled [ 0.580161] pci_bus 0000:00: resource 0 io: [0x00-0xffff] [ 0.580164] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffff] [ 0.580194] NET: Registered protocol family 2 [ 0.589194] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.589686] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.591601] TCP bind hash table entries: 65536 (order: 7, 524288 bytes) [ 0.592553] TCP: Hash tables configured (established 131072 bind 65536) [ 0.592556] TCP reno registered [ 0.595287] NET: Registered protocol family 1 [ 0.595395] Unpacking initramfs... [ 0.750917] Freeing initrd memory: 4447k freed [ 0.752634] cpu0(16) debug files 3 [ 0.752649] cpu1(16) debug files 3 [ 0.752693] cpu2(16) debug files 3 [ 0.752706] cpu3(16) debug files 3 [ 0.752718] cpu4(16) debug files 3 [ 0.752738] cpu5(16) debug files 3 [ 0.752751] cpu6(16) debug files 3 [ 0.752763] cpu7(16) debug files 3 [ 0.752805] cpu8(16) debug files 3 [ 0.752825] cpu9(16) debug files 3 [ 0.752830] Machine check exception polling timer started. [ 0.752849] cpufreq-nforce2: No nForce2 chipset. [ 0.753197] Scanning for low memory corruption every 60 seconds [ 0.753363] audit: initializing netlink socket (disabled) [ 0.753384] type=2000 audit(1257855044.753:1): initialized [ 0.762053] highmem bounce pool size: 64 pages [ 0.762059] HugeTLB registered 4 MB page size, pre-allocated 0 pages [ 0.764836] VFS: Disk quotas dquot_6.5.2 [ 0.764879] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.765449] fuse init (API version 7.11) [ 0.766196] aufs 2-29 [ 0.766212] msgmni has been set to 1707 [ 0.769453] alg: No test for stdrng (krng) [ 0.769547] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253) [ 0.769557] io scheduler noop registered [ 0.769559] io scheduler anticipatory registered [ 0.769562] io scheduler deadline registered [ 0.769598] io scheduler cfq registered (default) [ 0.769626] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.769664] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.769683] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.769698] pci 0000:00:02.0: Boot video device [ 0.769752] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.769772] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.772248] vesafb: framebuffer at 0xf0000000, mapped to 0xf8080000, using 3750k, total 4096k [ 0.772250] vesafb: mode is 800x600x16, linelength=1600, pages=3 [ 0.772252] vesafb: scrolling: redraw [ 0.772255] vesafb: Truecolor: size=0:5:6:5, shift=0:11:5:0 [ 0.774287] bootsplash 3.1.6-2004/03/31: looking for picture... [ 0.781093] bootsplash: silentjpeg size 34362 bytes [ 0.787257] bootsplash: ...found (800x600, 26696 bytes, v3). [ 0.798984] Console: switching to colour frame buffer device 100x34 [ 0.810922] fb0: VESA VGA frame buffer device [ 0.811145] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 0.811147] ACPI: Power Button [PWRF] [ 0.811635] processor ACPI_CPU:00: registered as cooling_device0 [ 0.811748] processor ACPI_CPU:01: registered as cooling_device1 [ 0.811849] processor ACPI_CPU:02: registered as cooling_device2 [ 0.811986] processor ACPI_CPU:03: registered as cooling_device3 [ 0.812120] processor ACPI_CPU:04: registered as cooling_device4 [ 0.812227] processor ACPI_CPU:05: registered as cooling_device5 [ 0.812331] processor ACPI_CPU:06: registered as cooling_device6 [ 0.812438] processor ACPI_CPU:07: registered as cooling_device7 [ 0.812540] processor ACPI_CPU:08: registered as cooling_device8 [ 0.812642] processor ACPI_CPU:09: registered as cooling_device9 [ 0.814313] isapnp: Scanning for PnP cards... [ 1.132936] isapnp: No Plug & Play device found [ 1.133882] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 1.134199] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 1.134632] 00:06: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 1.145399] FDC 0 is a S82078B [ 1.145884] loop: module loaded [ 1.145918] input: Macintosh mouse button emulation as /devices/virtual/input/input1 [ 1.145944] Driver 'sd' needs updating - please use bus_type methods [ 1.145951] Driver 'sr' needs updating - please use bus_type methods [ 1.146036] Fixed MDIO Bus: probed [ 1.146057] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.146083] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.146102] uhci_hcd: USB Universal Host Controller Interface driver [ 1.146151] PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 1.146853] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.146875] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 1.146957] mice: PS/2 mouse device common for all mice [ 1.147723] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0 [ 1.147924] rtc0: alarms up to one day, 114 bytes nvram [ 1.147951] EISA: Probing bus 0 at eisa.0 [ 1.148150] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 [ 1.148211] EISA: Detected 0 cards. [ 1.150406] cpuidle: using governor ladder [ 1.150408] cpuidle: using governor menu [ 1.151291] usbcore: registered new interface driver hiddev [ 1.151301] usbcore: registered new interface driver usbhid [ 1.151302] usbhid: v2.6:USB HID core driver [ 1.152063] NET: Registered protocol family 17 [ 1.153068] Using IPI No-Shortcut mode [ 1.153305] TuxOnIce 3.0.1 (http://tuxonice.net) [ 1.153375] TuxOnIce: Ignoring late initcall, as requested. [ 1.153385] registered taskstats version 1 [ 1.153558] psmouse serio1: ID: 10 00 64 [ 1.153812] rtc_cmos 00:01: setting system clock to 2009-11-10 12:10:43 UTC (1257855043) [ 1.154442] Freeing unused kernel memory: 448k freed [ 1.167651] Write protecting the kernel text: 3092k [ 1.173187] Write protecting the kernel read-only data: 1444k [ 1.558224] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 2.089215] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 2.089258] virtio-pci 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11 [ 2.089500] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 2.089521] virtio-pci 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10 [ 2.089709] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 2.089712] virtio-pci 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 10 (level, high) -> IRQ 10 [ 2.114900] libata version 3.00 loaded. [ 2.117535] ata_piix 0000:00:01.1: version 2.13 [ 2.117687] ata_piix 0000:00:01.1: setting latency timer to 64 [ 2.118214] scsi0 : ata_piix [ 2.118514] scsi1 : ata_piix [ 2.118571] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14 [ 2.118573] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15 [ 2.421476] ata2.01: NODEV after polling detection [ 2.421787] ata2.00: ATAPI: QEMU DVD-ROM, 0.10.0, max UDMA/100 [ 2.422371] ata2.00: configured for MWDMA2 [ 2.423523] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 0.10 PQ: 0 ANSI: 5 [ 2.425747] sr0: scsi3-mmc drive: 4x/4x xa/form2 tray [ 2.425758] Uniform CD-ROM driver Revision: 3.20 [ 2.425860] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 2.425932] sr 1:0:0:0: Attached scsi generic sg0 type 5 [ 2.475231] vda: vda1 [ 2.519362] brd: module loaded [ 3.758402] EXT4-fs: barriers enabled [ 3.763918] kjournald2 starting: pid 229, dev vda1:8, commit interval 5 seconds [ 3.763948] EXT4-fs: delayed allocation enabled [ 3.763950] EXT4-fs: file extents enabled [ 3.773524] EXT4-fs: mballoc enabled [ 3.773537] EXT4-fs: mounted filesystem vda1 with ordered data mode [ 6.421525] udev: starting version 146 [ 6.943955] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0 [ 6.951640] input: PC Speaker as /devices/platform/pcspkr/input/input4 [ 8.174315] parport_pc 00:05: reported by Plug and Play ACPI [ 8.174489] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 8.261518] lp0: using parport0 (interrupt-driven). [ 8.339160] ppdev: user-space parallel port driver [ 9.338879] EXT4 FS on vda1, internal journal on vda1:8 [ 10.075897] Adding 307192k swap on /.swap. Priority:-1 extents:2 across:315384k SS [ 14.715951] JBD: barrier-based sync failed on vda1:8 - disabling barriers [ 16.748619] NET: Registered protocol family 10 [ 16.753682] lo: Disabled Privacy Extensions [ 18.548590] mtrr: no MTRR for f0000000,100000 found [ 19.134074] bootsplash: status on console 0 changed to on [ 27.500044] eth0: no IPv6 routers present [468044.486305] PPP generic driver version 2.4.2 [468044.501532] NET: Registered protocol family 24 [785560.993888] BIOS EDD facility v0.16 2004-Jun-25, 1 devices found [1267812575.161101] BUG: soft lockup - CPU#2 stuck for 1179833091s! [swapper:0] [1267812575.161105] Modules linked in: edd pppoe pppox ppp_generic slhc ipv6 ppdev parport_pc lp parport pcspkr i2c_piix4 brd virtio_net virtio_blk virtio_balloon ata_generic pata_acpi ata_piix libata virtio_pci [1267812575.161125] [1267812575.161139] Pid: 0, comm: swapper Not tainted (2.6.30.9-128 #1) [1267812575.161141] EIP: 0060:[<c0135829>] EFLAGS: 00000206 CPU: 2 [1267812575.161151] EIP is at __do_softirq+0x3e/0x133 [1267812575.161153] EAX: 00000002 EBX: 00000046 ECX: 11982cd1 EDX: 00000102 [1267812575.161155] ESI: 00000000 EDI: 00037811 EBP: f7093f20 ESP: f7093f04 [1267812575.161157] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 [1267812575.161161] CR0: 8005003b CR2: b7fe4be8 CR3: 3657f000 CR4: 000006d0 [1267812575.161171] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000 [1267812575.161177] DR6: ffff0ff0 DR7: 00000400 [1267812575.161179] Call Trace: [1267812575.161185] [<c013594f>] do_softirq+0x31/0x3c [1267812575.161188] [<c0135a3a>] irq_exit+0x29/0x57 [1267812575.161193] [<c01167a9>] smp_apic_timer_interrupt+0x6f/0x7d [1267812575.161198] [<c0103b26>] apic_timer_interrupt+0x2a/0x30 [1267812575.161209] [<c01400d8>] ? uevent_filter+0xb/0x11 [1267812575.161215] [<c014c711>] ? tick_nohz_restart_sched_tick+0xa7/0xb1 [1267812575.161218] [<c01024c3>] cpu_idle+0x5b/0x64 [1267812575.161224] [<c03fd033>] start_secondary+0xc6/0xc8 [1267812575.162037] BUG: soft lockup - CPU#0 stuck for 1179837187s! [ata/0:148] [1267812575.162037] Modules linked in: edd pppoe pppox ppp_generic slhc ipv6 ppdev parport_pc lp parport pcspkr i2c_piix4 brd virtio_net virtio_blk virtio_balloon ata_generic pata_acpi ata_piix libata virtio_pci [1267812575.162037] Pid: 148, comm: ata/0 Not tainted (2.6.30.9-128 #1) [1267812575.162037] EIP: 0060:[<c0290fff>] EFLAGS: 00000206 CPU: 0 [1267812575.162037] EIP is at ioread8+0x19/0x2f [1267812575.162037] EAX: 0001c001 EBX: c05a8020 ECX: 019c6000 EDX: 0000c033 [1267812575.162037] ESI: f6afc000 EDI: 00000000 EBP: f690fd1c ESP: f690fd1c [1267812575.162037] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 [1267812575.162037] CR0: 8005003b CR2: 0cd5d000 CR3: 36578000 CR4: 000006d0 [1267812575.162037] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000 [1267812575.162037] DR6: ffff0ff0 DR7: 00000400 [1267812575.162037] Call Trace: [1267812575.162037] [<f801f3d9>] vp_interrupt+0x1e/0x99 [virtio_pci] [1267812575.162037] [<c018b400>] ? __alloc_pages_internal+0xa1/0x387 [1267812575.162037] [<c0174c0b>] handle_IRQ_event+0x52/0x108 [1267812575.162037] [<c017600a>] handle_fasteoi_irq+0x69/0x9b [1267812575.162037] [<c0104cdb>] handle_irq+0x3b/0x48 [1267812575.162037] [<c0104648>] do_IRQ+0x40/0x83 [1267812575.162037] [<c0103889>] common_interrupt+0x29/0x30 [1267812575.162037] [<c01a84cc>] ? __kmalloc_track_caller+0xbc/0x105 [1267812575.162037] [<c038ee74>] ? __netdev_alloc_skb+0x17/0x34 [1267812575.162037] [<c038e489>] __alloc_skb+0x49/0x114 [1267812575.162037] [<c038ee74>] __netdev_alloc_skb+0x17/0x34 [1267812575.162037] [<f848cd69>] try_fill_recv+0x39/0x11b [virtio_net] [1267812575.162037] [<f848d801>] virtnet_poll+0x7d/0xcc [virtio_net] [1267812575.162037] [<c0394192>] net_rx_action+0x92/0x16b [1267812575.162037] [<c0135878>] __do_softirq+0x8d/0x133 [1267812575.162037] [<c013594f>] do_softirq+0x31/0x3c [1267812575.162037] [<c0135a3a>] irq_exit+0x29/0x57 [1267812575.162037] [<c0104675>] do_IRQ+0x6d/0x83 [1267812575.162037] [<c0103889>] common_interrupt+0x29/0x30 [1267812575.162037] [<c0400af2>] ? _spin_unlock_irqrestore+0x9/0xf [1267812575.162037] [<f843aabd>] ata_sff_hsm_move+0x237/0x49d [libata] [1267812575.162037] [<f843aff3>] ata_pio_task+0x8e/0x9e [libata] [1267812575.162037] [<c013e8ad>] run_workqueue+0x8a/0x107 [1267812575.162037] [<f843af65>] ? ata_pio_task+0x0/0x9e [libata] [1267812575.162037] [<c013e9e0>] worker_thread+0xb6/0xc2 [1267812575.162037] [<c0141a53>] ? autoremove_wake_function+0x0/0x33 [1267812575.162037] [<c013e92a>] ? worker_thread+0x0/0xc2 [1267812575.162037] [<c014175a>] kthread+0x45/0x6b [1267812575.162037] [<c0141715>] ? kthread+0x0/0x6b [1267812575.162037] [<c0103c77>] kernel_thread_helper+0x7/0x10 [1267987394.820065] Clocksource tsc unstable (delta = 237368126 ns)
| |