early console in setup code [ 0.000000] Linux version 3.14.0-rc5-03857-gaafcfca (kbuild@bay) (gcc version 4.8.1 (Debian 4.8.1-8) ) #2 Thu Mar 6 14:09:51 CST 2014 [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000000fffdfff] usable [ 0.000000] BIOS-e820: [mem 0x000000000fffe000-0x000000000fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0xfffe max_arch_pfn = 0x1000000 [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] initial memory mapped: [mem 0x00000000-0x025fffff] [ 0.000000] Base memory trampoline at [8009b000] 9b000 size 16384 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] [mem 0x00000000-0x000fffff] page 4k [ 0.000000] init_memory_mapping: [mem 0x0e400000-0x0e5fffff] [ 0.000000] [mem 0x0e400000-0x0e5fffff] page 4k [ 0.000000] BRK [0x01d0f000, 0x01d0ffff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x0c000000-0x0e3fffff] [ 0.000000] [mem 0x0c000000-0x0e3fffff] page 4k [ 0.000000] BRK [0x01d10000, 0x01d10fff] PGTABLE [ 0.000000] BRK [0x01d11000, 0x01d11fff] PGTABLE [ 0.000000] BRK [0x01d12000, 0x01d12fff] PGTABLE [ 0.000000] BRK [0x01d13000, 0x01d13fff] PGTABLE [ 0.000000] BRK [0x01d14000, 0x01d14fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x00100000-0x0bffffff] [ 0.000000] [mem 0x00100000-0x0bffffff] page 4k [ 0.000000] init_memory_mapping: [mem 0x0e600000-0x0fffdfff] [ 0.000000] [mem 0x0e600000-0x0fffdfff] page 4k [ 0.000000] RAMDISK: [mem 0x0e7ab000-0x0ffeffff] [ 0.000000] ACPI: RSDP 0x000F1960 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0FFFFB3C 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0FFFF177 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0FFFE040 001137 (v01 BXPC BXDSDT 00000001 INTL 20130823) [ 0.000000] ACPI: FACS 0x0FFFE000 000040 [ 0.000000] ACPI: SSDT 0x0FFFF1EB 000899 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x0FFFFA84 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0FFFFB04 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] 255MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 0fffe000 [ 0.000000] low ram: 0 - 0fffe000 [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:fffd001, boot clock [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] Normal [mem 0x01000000-0x0fffdfff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x00001000-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0x0fffdfff] [ 0.000000] On node 0 totalpages: 65436 [ 0.000000] free_area_init_node: node 0, pgdat 817cd5a8, node_mem_map 8e359024 [ 0.000000] DMA zone: 36 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] Normal zone: 540 pages used for memmap [ 0.000000] Normal zone: 61438 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1654d00 [ 0.000000] e820: [mem 0x10000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64860 [ 0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled panic=10 softlockup_panic=1 nmi_watchdog=panic prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kernel-tests/run-queue/kvm/i386-randconfig-ba1-03061322/linux-devel:devel-hourly-2014030612/.vmlinuz-aafcfca36a7a26409af2ec2265927ed8ad6eb4b2-20140306141026-3-lkp-nex05 branch=linux-devel/devel-hourly-2014030612 BOOT_IMAGE=/kernel/i386-randconfig-ba1-03061322/aafcfca36a7a26409af2ec2265927ed8ad6eb4b2/vmlinuz-3.14.0-rc5-03857-gaafcfca [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) [ 0.000000] Initializing CPU#0 [ 0.000000] Memory: 220452K/261744K available (4038K kernel code, 1894K rwdata, 2356K rodata, 320K init, 4676K bss, 41292K reserved) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfffe0000 - 0xfffff000 ( 124 kB) [ 0.000000] vmalloc : 0x907fe000 - 0xfffde000 (1783 MB) [ 0.000000] lowmem : 0x80000000 - 0x8fffe000 ( 255 MB) [ 0.000000] .init : 0x8181b000 - 0x8186b000 ( 320 kB) [ 0.000000] .data : 0x813f1e3a - 0x818199f0 (4254 kB) [ 0.000000] .text : 0x81000000 - 0x813f1e3a (4039 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] CPU 0 irqstacks, hard=8dc02000 soft=8dc04000 [ 0.000000] ACPI: Core revision 20140214 [ 0.000000] ACPI: All ACPI Tables successfully acquired [ 0.000000] ACPI: setting ELCR to 0200 (from 0c00) [ 0.000000] console [ttyS0] 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: 3807 kB [ 0.000000] per task-struct memory footprint: 1920 bytes [ 0.000000] ------------------------ [ 0.000000] | Locking API testsuite: [ 0.000000] ---------------------------------------------------------------------------- [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] A-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] double unlock: ok | ok | ok | ok | ok | ok | [ 0.000000] initialize held: ok | ok | ok | ok | ok | ok | [ 0.000000] bad unlock order: ok | ok | ok | ok | ok | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] recursive read-lock: | ok | |failed| [ 0.000000] recursive read-lock #2: | ok | |failed| [ 0.000000] mixed read-write-lock: |failed| |failed| [ 0.000000] mixed write-read-lock: |failed| |failed| [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] hard-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.000000] soft-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.000000] hard-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.000000] soft-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.000000] sirq-safe-A => hirqs-on/12:failed|failed| ok | [ 0.000000] sirq-safe-A => hirqs-on/21:failed|failed| ok | [ 0.000000] hard-safe-A + irqs-on/12:failed|failed| ok | [ 0.000000] soft-safe-A + irqs-on/12:failed|failed| ok | [ 0.000000] hard-safe-A + irqs-on/21:failed|failed| ok | [ 0.000000] soft-safe-A + irqs-on/21:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/123:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/123:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/132:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/132:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/213:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/213:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/231:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/231:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/312:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/312:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/321:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/321:failed|failed| ok | [ 0.000000] hard-irq read-recursion/123: ok | [ 0.000000] soft-irq read-recursion/123: ok | [ 0.000000] hard-irq read-recursion/132: ok | [ 0.000000] soft-irq read-recursion/132: ok | [ 0.000000] hard-irq read-recursion/213: ok | [ 0.000000] soft-irq read-recursion/213: ok | [ 0.000000] hard-irq read-recursion/231: ok | [ 0.000000] soft-irq read-recursion/231: ok | [ 0.000000] hard-irq read-recursion/312: ok | [ 0.000000] soft-irq read-recursion/312: ok | [ 0.000000] hard-irq read-recursion/321: ok | [ 0.000000] soft-irq read-recursion/321: ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] | Wound/wait tests | [ 0.000000] --------------------- [ 0.000000] ww api failures: ok | ok | ok | [ 0.000000] ww contexts mixing:failed| ok | [ 0.000000] finishing ww context: ok | ok | ok | ok | [ 0.000000] locking mismatches: ok | ok | ok | [ 0.000000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.000000] spinlock nest unlocked: ok | [ 0.000000] ----------------------------------------------------- [ 0.000000] |block | try |context| [ 0.000000] ----------------------------------------------------- [ 0.000000] context:failed| ok | ok | [ 0.000000] try:failed| ok |failed| [ 0.000000] block:failed| ok |failed| [ 0.000000] spinlock:failed| ok |failed| [ 0.000000] -------------------------------------------------------- [ 0.000000] 141 out of 253 testcases failed, as expected. | [ 0.000000] ---------------------------------------------------- [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 1995.134 MHz processor [ 0.003000] Calibrating delay loop (skipped) preset value.. 3990.26 BogoMIPS (lpj=1995134) [ 0.003409] pid_max: default: 32768 minimum: 301 [ 0.004123] Mount-cache hash table entries: 512 [ 0.006049] mce: CPU supports 10 MCE banks [ 0.006707] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.006707] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.007008] CPU: Intel Common KVM processor (fam: 0f, model: 06, stepping: 01) [ 0.012530] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.014385] ftrace: Allocated trace_printk buffers [ 0.018355] NMI watchdog: disabled (cpu0): hardware events not enabled [ 0.019576] devtmpfs: initialized [ 0.022778] regulator-dummy: no parameters [ 0.023266] NET: Registered protocol family 16 [ 0.025035] cpuidle: using governor ladder [ 0.025744] cpuidle: using governor menu [ 0.026329] ACPI: bus type PCI registered [ 0.027251] PCI : PCI BIOS area is rw and x. Use pci=nobios if you want it NX. [ 0.028016] PCI: PCI BIOS revision 2.10 entry at 0xfd467, last bus=0 [ 0.029009] PCI: Using configuration type 1 for base access [ 0.038468] ACPI: Added _OSI(Module Device) [ 0.039011] ACPI: Added _OSI(Processor Device) [ 0.039654] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.040011] ACPI: Added _OSI(Processor Aggregator Device) [ 0.063063] ACPI: Interpreter enabled [ 0.063614] ACPI: (supports S0) [ 0.064010] ACPI: Using PIC for interrupt routing [ 0.064795] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.095132] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.096019] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.096812] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.098456] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.102175] PCI host bridge to bus 0000:00 [ 0.103023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.104017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] [ 0.105016] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff] [ 0.107015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] [ 0.108018] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff] [ 0.109117] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.112367] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.114925] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.119920] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 0.123431] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.125183] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.126031] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 0.128806] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 0.132000] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref] [ 0.134000] pci 0000:00:02.0: reg 0x14: [mem 0xfebf0000-0xfebf0fff] [ 0.146000] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.147483] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.149904] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.151901] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.161909] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.163514] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 0.165488] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 0.174029] pci_bus 0000:00: on NUMA node 0 [ 0.177025] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.178665] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.180549] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.182310] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.183579] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.185570] acpi LNXCPU:00: Invalid PBLK length [0] [ 0.187746] ACPI: Enabled 16 GPEs in block 00 to 0F [ 0.189639] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.190015] vgaarb: loaded [ 0.190496] vgaarb: bridge control possible 0000:00:02.0 [ 0.192830] PCI: Using ACPI for IRQ routing [ 0.193013] PCI: pci_cache_line_size set to 64 bytes [ 0.194229] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.195018] e820: reserve RAM buffer [mem 0x0fffe000-0x0fffffff] [ 0.197748] Bluetooth: Core ver 2.19 [ 0.198081] NET: Registered protocol family 31 [ 0.199011] Bluetooth: HCI device and connection manager initialized [ 0.200026] Bluetooth: HCI socket layer initialized [ 0.201013] Bluetooth: L2CAP socket layer initialized [ 0.202064] Bluetooth: SCO socket layer initialized [ 0.204144] Switched to clocksource kvm-clock [ 0.205916] Warning: could not register annotated branches stats [ 0.235751] pnp: PnP ACPI init [ 0.236480] ACPI: bus type PNP registered [ 0.237558] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.239243] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.240713] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.242224] pnp 00:03: [dma 2] [ 0.242987] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.244518] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.246187] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.247743] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.250347] pnp 00:07: Plug and Play ACPI device, IDs PNP0103 (active) [ 0.252313] pnp: PnP ACPI: found 8 devices [ 0.253019] ACPI: bus type PNP unregistered [ 0.290776] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7] [ 0.291799] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff] [ 0.292893] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff] [ 0.294140] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff] [ 0.295372] NET: Registered protocol family 2 [ 0.296586] TCP established hash table entries: 2048 (order: 1, 8192 bytes) [ 0.297923] TCP bind hash table entries: 2048 (order: 4, 90112 bytes) [ 0.299283] TCP: Hash tables configured (established 2048 bind 2048) [ 0.300693] TCP: reno registered [ 0.301389] UDP hash table entries: 256 (order: 2, 24576 bytes) [ 0.302546] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes) [ 0.303949] NET: Registered protocol family 1 [ 0.304791] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.305916] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.307133] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.308333] pci 0000:00:02.0: Boot video device [ 0.309318] PCI: CLS 0 bytes, default 64 [ 0.310548] Unpacking initramfs... [ 2.392025] debug: unmapping init [mem 0x8e7ab000-0x8ffeffff] [ 2.396025] Scanning for low memory corruption every 60 seconds [ 2.398998] Initializing RT-Tester: OK [ 2.399679] spin_lock-torture:--- Start of test: nwriters_stress=2 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 2.401800] spin_lock-torture: Creating torture_shuffle task [ 2.402795] spin_lock-torture: Creating torture_stutter task [ 2.403778] spin_lock-torture: torture_shuffle task started [ 2.404638] spin_lock-torture: Creating lock_torture_writer task [ 2.405618] spin_lock-torture: torture_stutter task started [ 2.406563] spin_lock-torture: Creating lock_torture_writer task [ 2.407543] spin_lock-torture: lock_torture_writer task started [ 2.408471] spin_lock-torture: Creating lock_torture_stats task [ 2.409407] spin_lock-torture: lock_torture_writer task started [ 2.411220] futex hash table entries: 256 (order: 1, 12288 bytes) [ 2.412172] Initialise system trusted keyring [ 2.414788] spin_lock-torture: lock_torture_stats task started [ 2.417325] jffs2: version 2.2. (NAND) [ 2.425785] Key type asymmetric registered [ 2.426543] Asymmetric key parser 'x509' registered [ 2.436063] list_sort_test: start testing list_sort() [ 2.438225] test_string_helpers: Running tests... [ 2.447671] crc32: CRC_LE_BITS = 1, CRC_BE BITS = 1 [ 2.448455] crc32: self tests passed, processed 225944 bytes in 4171223 nsec [ 2.455242] crc32c: CRC_LE_BITS = 1 [ 2.455835] crc32c: self tests passed, processed 225944 bytes in 2645747 nsec [ 3.131650] crc32_combine: 8373 self tests passed [ 3.396268] tsc: Refined TSC clocksource calibration: 1995.000 MHz [ 8.296162] random: nonblocking pool is initialized [ 19.853890] CE: hpet increased min_delta_ns to 5000 nsec [ 19.853890] CE: hpet increased min_delta_ns to 7500 nsec [ 19.853890] CE: hpet increased min_delta_ns to 11250 nsec [ 19.853890] CE: hpet increased min_delta_ns to 16875 nsec [ 19.853890] CE: hpet increased min_delta_ns to 25312 nsec [ 19.853890] hrtimer: interrupt took 3315122 ns [ 88.019164] BUG: soft lockup - CPU#0 stuck for 22s! [lock_torture_wr:26] [ 88.019164] Modules linked in: [ 88.019164] CPU: 0 PID: 26 Comm: lock_torture_wr Not tainted 3.14.0-rc5-03857-gaafcfca #2 [ 88.019164] task: 8dfd8000 ti: 8dc02000 task.ti: 88a26000 [ 88.019164] EIP: 0060:[<8109afde>] EFLAGS: 00000202 CPU: 0 [ 88.019164] EIP is at stutter_wait+0x1d/0x5a [ 88.019164] EAX: 00000002 EBX: 815a9cf2 ECX: 00000000 EDX: 00000000 [ 88.019164] ESI: 8b0e5438 EDI: 00000000 EBP: 88a27efc ESP: 88a27ef8 [ 88.019164] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068 [ 88.019164] CR0: 8005003b CR2: 00000000 CR3: 0186f000 CR4: 000006f0 [ 88.019164] Stack: [ 88.019164] 8b96f300 88a27f20 81060f8e 815a9a86 815abef3 815a9a65 00000000 8b96f330 [ 88.019164] 8b0e5438 81060e9e 88a27fac 8104c0f8 00000000 0000000c 8b0e5438 00000000 [ 88.019164] 00000001 dead4ead ffffffff ffffffff 8187c858 00000000 00000000 815a6175 [ 88.019164] Call Trace: [ 88.019164] [<81060f8e>] lock_torture_writer+0xf0/0x13c [ 88.019164] [<81060e9e>] ? torture_lock_busted_write_unlock+0x5/0x5 [ 88.019164] [<8104c0f8>] kthread+0xb7/0xbc [ 88.019164] [<813f10bb>] ret_from_kernel_thread+0x1b/0x30 [ 88.019164] [<8104c041>] ? kthread_stop+0xaa/0xaa [ 88.019164] Code: 00 a1 3c c7 79 81 48 74 de 5b 58 5b 5d c3 55 89 e5 53 89 c3 eb 36 83 3d 0c c6 cd 81 00 74 17 a1 0c c6 cd 81 83 f8 01 75 02 eb 15 0c c6 cd 81 85 c0 74 11 eb f5 b8 e8 03 00 00 e8 74 e8 f9 ff [ 88.019164] Kernel panic - not syncing: softlockup: hung tasks [ 88.019164] CPU: 0 PID: 26 Comm: lock_torture_wr Not tainted 3.14.0-rc5-03857-gaafcfca #2 [ 88.019164] 00000000 00000000 8dc03ecc 813e81a1 8dc03eec 813e55a4 815a626f 8187a464 [ 88.019164] 8109aff2 00000001 88a27ebc 00000016 8dc03f24 8107aab9 815ad036 00000000 [ 88.019164] 00000016 8dfd8220 0000001a ee6b2800 00000000 7e59f15c 00000014 00000000 [ 88.019164] Call Trace: [ 88.019164] [<813e81a1>] dump_stack+0x16/0x18 [ 88.019164] [<813e55a4>] panic+0xa6/0x1ba [ 88.019164] [<8109aff2>] ? stutter_wait+0x31/0x5a [ 88.019164] [<8107aab9>] watchdog_timer_fn+0x13d/0x15a [ 88.019164] [<8107a97c>] ? __touch_watchdog+0x16/0x16 [ 88.019164] [<8104e6fe>] __run_hrtimer+0xd8/0x21c [ 88.019164] [<8104f146>] hrtimer_interrupt+0xfe/0x228 [ 88.019164] [<8100482a>] timer_interrupt+0xd/0x14 [ 88.019164] [<81064e51>] handle_irq_event_percpu+0x4b/0x262 [ 88.019164] [<81060b1d>] ? do_raw_spin_unlock+0x98/0xc0 [ 88.019164] [<81065094>] handle_irq_event+0x2c/0x43 [ 88.019164] [<81067233>] handle_level_irq+0xad/0xc0 [ 88.019164] [<81067186>] ? cond_unmask_irq+0x26/0x26 [ 88.019164] [<81004200>] ? do_IRQ+0x34/0x99 [ 88.019164] [<813eb9f3>] ? schedule_timeout+0x12b/0x15e [ 88.019164] [<8105c288>] ? lock_release_holdtime+0xc1/0xcd [ 88.019164] [<813f15d0>] ? common_interrupt+0x30/0x35 [ 88.019164] [<8109afde>] ? stutter_wait+0x1d/0x5a [ 88.019164] [<81060f8e>] ? lock_torture_writer+0xf0/0x13c [ 88.019164] [<81060e9e>] ? torture_lock_busted_write_unlock+0x5/0x5 [ 88.019164] [<8104c0f8>] ? kthread+0xb7/0xbc [ 88.019164] [<813f10bb>] ? ret_from_kernel_thread+0x1b/0x30 [ 88.019164] [<8104c041>] ? kthread_stop+0xaa/0xaa [ 88.019164] Kernel Offset: 0x0 from 0x81000000 (relocation range: 0x80000000-0x907fdfff) Elapsed time: 100 qemu-system-x86_64 -cpu kvm64 -enable-kvm -kernel /kernel/i386-randconfig-ba1-03061322/aafcfca36a7a26409af2ec2265927ed8ad6eb4b2/vmlinuz-3.14.0-rc5-03857-gaafcfca -append 'hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled panic=10 softlockup_panic=1 nmi_watchdog=panic prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kernel-tests/run-queue/kvm/i386-randconfig-ba1-03061322/linux-devel:devel-hourly-2014030612/.vmlinuz-aafcfca36a7a26409af2ec2265927ed8ad6eb4b2-20140306141026-3-lkp-nex05 branch=linux-devel/devel-hourly-2014030612 BOOT_IMAGE=/kernel/i386-randconfig-ba1-03061322/aafcfca36a7a26409af2ec2265927ed8ad6eb4b2/vmlinuz-3.14.0-rc5-03857-gaafcfca' -initrd /kernel-tests/initrd/quantal-core-i386.cgz -m 256 -smp 2 -net nic,vlan=1,model=e1000 -net user,vlan=1,hostfwd=tcp::26465-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-quantal-lkp-nex05-61 -serial file:/dev/shm/kboot/serial-quantal-lkp-nex05-61 -daemonize -display none -monitor null