early console in setup code Probing EDD (edd=off to disable)... ok early console in decompress_kernel KASLR using RDTSC... Decompressing Linux... Parsing ELF... Performing relocations... done. Booting the kernel. [ 0.000000] Linux version 3.14.0-rc5-next-20140307-07129-g1b0a7e3 (kbuild@roam) (gcc version 4.8.1 (Debian 4.8.1-8) ) #1 SMP Sun Mar 9 13:41:01 CST 2014 [ 0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 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/x86_64-randconfig-r5-03091328/next:master/.vmlinuz-1b0a7e3263168a06d3858798e48c5a21d1c78d3c-20140309134226-8-ivytown2 branch=next/master BOOT_IMAGE=/kernel/x86_64-randconfig-r5-03091328/1b0a7e3263168a06d3858798e48c5a21d1c78d3c/vmlinuz-3.14.0-rc5-next-20140307-07129-g1b0a7e3 [ 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-0x0000000013ffdfff] usable [ 0.000000] BIOS-e820: [mem 0x0000000013ffe000-0x0000000013ffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.4 present. [ 0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2011 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] No AGP bridge found [ 0.000000] e820: last_pfn = 0x13ffe max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 0080000000 mask FF80000000 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] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] Scan for SMP in [mem 0x00000000-0x000003ff] [ 0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff] [ 0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff] [ 0.000000] found SMP MP-table at [mem 0x000fdae0-0x000fdaef] mapped at [ffff8800000fdae0] [ 0.000000] mpc: fdaf0-fdbe4 [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] [mem 0x00000000-0x000fffff] page 4k [ 0.000000] BRK [0x06691000, 0x06691fff] PGTABLE [ 0.000000] BRK [0x06692000, 0x06692fff] PGTABLE [ 0.000000] BRK [0x06693000, 0x06693fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x13a00000-0x13bfffff] [ 0.000000] [mem 0x13a00000-0x13bfffff] page 4k [ 0.000000] BRK [0x06694000, 0x06694fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x10000000-0x139fffff] [ 0.000000] [mem 0x10000000-0x139fffff] page 4k [ 0.000000] BRK [0x06695000, 0x06695fff] PGTABLE [ 0.000000] BRK [0x06696000, 0x06696fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x00100000-0x0fffffff] [ 0.000000] [mem 0x00100000-0x0fffffff] page 4k [ 0.000000] init_memory_mapping: [mem 0x13c00000-0x13ffdfff] [ 0.000000] [mem 0x13c00000-0x13ffdfff] page 4k [ 0.000000] RAMDISK: [mem 0x13ce6000-0x13feffff] [ 0.000000] ACPI: RSDP 0x00000000000FD950 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000013FFE450 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000013FFFF80 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000013FFE490 0011A9 (v01 BXPC BXDSDT 00000001 INTL 20100528) [ 0.000000] ACPI: FACS 0x0000000013FFFF40 000040 [ 0.000000] ACPI: SSDT 0x0000000013FFF7A0 000796 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x0000000013FFF680 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000013FFF640 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff57a000 ( fee00000) [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:13c66001, primary cpu clock [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-0xffffffff] [ 0.000000] Normal empty [ 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-0x13ffdfff] [ 0.000000] On node 0 totalpages: 81820 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1216 pages used for memmap [ 0.000000] DMA32 zone: 77822 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff57a000 ( fee00000) [ 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_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b [ 0.000000] ACPI: IRQ0 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01 [ 0.000000] ACPI: IRQ2 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04 [ 0.000000] ACPI: IRQ5 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08 [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] ACPI: IRQ10 used by override. [ 0.000000] ACPI: IRQ11 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff579000 (fec00000) [ 0.000000] nr_irqs_gsi: 40 [ 0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff880013800000 s79232 r8192 d23168 u1048576 [ 0.000000] pcpu-alloc: s79232 r8192 d23168 u1048576 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1380dc40 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 80519 [ 0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 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/x86_64-randconfig-r5-03091328/next:master/.vmlinuz-1b0a7e3263168a06d3858798e48c5a21d1c78d3c-20140309134226-8-ivytown2 branch=next/master BOOT_IMAGE=/kernel/x86_64-randconfig-r5-03091328/1b0a7e3263168a06d3858798e48c5a21d1c78d3c/vmlinuz-3.14.0-rc5-next-20140307-07129-g1b0a7e3 [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 292616K/327280K available (5911K kernel code, 1272K rwdata, 3396K rodata, 1040K init, 10520K bss, 34664K reserved) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS:524544 nr_irqs:56 16 [ 0.000000] ACPI: Core revision 20140214 [ 0.000000] ACPI: All ACPI Tables successfully acquired [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] bootconsole [earlyser0] disabled [ 0.000000] Linux version 3.14.0-rc5-next-20140307-07129-g1b0a7e3 (kbuild@roam) (gcc version 4.8.1 (Debian 4.8.1-8) ) #1 SMP Sun Mar 9 13:41:01 CST 2014 [ 0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 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/x86_64-randconfig-r5-03091328/next:master/.vmlinuz-1b0a7e3263168a06d3858798e48c5a21d1c78d3c-20140309134226-8-ivytown2 branch=next/master BOOT_IMAGE=/kernel/x86_64-randconfig-r5-03091328/1b0a7e3263168a06d3858798e48c5a21d1c78d3c/vmlinuz-3.14.0-rc5-next-20140307-07129-g1b0a7e3 [ 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-0x0000000013ffdfff] usable [ 0.000000] BIOS-e820: [mem 0x0000000013ffe000-0x0000000013ffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.4 present. [ 0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2011 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] No AGP bridge found [ 0.000000] e820: last_pfn = 0x13ffe max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 0080000000 mask FF80000000 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] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] Scan for SMP in [mem 0x00000000-0x000003ff] [ 0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff] [ 0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff] [ 0.000000] found SMP MP-table at [mem 0x000fdae0-0x000fdaef] mapped at [ffff8800000fdae0] [ 0.000000] mpc: fdaf0-fdbe4 [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] [mem 0x00000000-0x000fffff] page 4k [ 0.000000] BRK [0x06691000, 0x06691fff] PGTABLE [ 0.000000] BRK [0x06692000, 0x06692fff] PGTABLE [ 0.000000] BRK [0x06693000, 0x06693fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x13a00000-0x13bfffff] [ 0.000000] [mem 0x13a00000-0x13bfffff] page 4k [ 0.000000] BRK [0x06694000, 0x06694fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x10000000-0x139fffff] [ 0.000000] [mem 0x10000000-0x139fffff] page 4k [ 0.000000] BRK [0x06695000, 0x06695fff] PGTABLE [ 0.000000] BRK [0x06696000, 0x06696fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x00100000-0x0fffffff] [ 0.000000] [mem 0x00100000-0x0fffffff] page 4k [ 0.000000] init_memory_mapping: [mem 0x13c00000-0x13ffdfff] [ 0.000000] [mem 0x13c00000-0x13ffdfff] page 4k [ 0.000000] RAMDISK: [mem 0x13ce6000-0x13feffff] [ 0.000000] ACPI: RSDP 0x00000000000FD950 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000013FFE450 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000013FFFF80 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000013FFE490 0011A9 (v01 BXPC BXDSDT 00000001 INTL 20100528) [ 0.000000] ACPI: FACS 0x0000000013FFFF40 000040 [ 0.000000] ACPI: SSDT 0x0000000013FFF7A0 000796 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x0000000013FFF680 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000013FFF640 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff57a000 ( fee00000) [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:13c66001, primary cpu clock [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-0xffffffff] [ 0.000000] Normal empty [ 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-0x13ffdfff] [ 0.000000] On node 0 totalpages: 81820 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1216 pages used for memmap [ 0.000000] DMA32 zone: 77822 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff57a000 ( fee00000) [ 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_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b [ 0.000000] ACPI: IRQ0 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01 [ 0.000000] ACPI: IRQ2 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04 [ 0.000000] ACPI: IRQ5 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08 [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] ACPI: IRQ10 used by override. [ 0.000000] ACPI: IRQ11 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff579000 (fec00000) [ 0.000000] nr_irqs_gsi: 40 [ 0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff880013800000 s79232 r8192 d23168 u1048576 [ 0.000000] pcpu-alloc: s79232 r8192 d23168 u1048576 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1380dc40 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 80519 [ 0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 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/x86_64-randconfig-r5-03091328/next:master/.vmlinuz-1b0a7e3263168a06d3858798e48c5a21d1c78d3c-20140309134226-8-ivytown2 branch=next/master BOOT_IMAGE=/kernel/x86_64-randconfig-r5-03091328/1b0a7e3263168a06d3858798e48c5a21d1c78d3c/vmlinuz-3.14.0-rc5-next-20140307-07129-g1b0a7e3 [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 292616K/327280K available (5911K kernel code, 1272K rwdata, 3396K rodata, 1040K init, 10520K bss, 34664K reserved) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS:524544 nr_irqs:56 16 [ 0.000000] ACPI: Core revision 20140214 [ 0.000000] ACPI: All ACPI Tables successfully acquired [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] bootconsole [earlyser0] disabled [ 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: 5855 kB [ 0.000000] per task-struct memory footprint: 1920 bytes [ 0.000000] ODEBUG: 15 of 15 active objects replaced [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2494.168 MHz processor [ 0.003000] Calibrating delay loop (skipped) preset value.. 4988.33 BogoMIPS (lpj=2494168) [ 0.004008] pid_max: default: 32768 minimum: 301 [ 0.004864] Security Framework initialized [ 0.005018] AppArmor: AppArmor initialized [ 0.006046] Mount-cache hash table entries: 256 [ 0.008047] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.008047] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.008047] tlb_flushall_shift: 6 [ 0.009084] debug: unmapping init [mem 0xffffffff85c44000-0xffffffff85c49fff] [ 0.013868] Getting VERSION: 50014 [ 0.014035] Getting VERSION: 50014 [ 0.015011] Getting ID: 0 [ 0.016017] Getting ID: ff000000 [ 0.017017] Getting LVT0: 8700 [ 0.018011] Getting LVT1: 8400 [ 0.019030] enabled ExtINT on CPU#0 [ 0.022050] ENABLING IO-APIC IRQs [ 0.022974] init IO_APIC IRQs [ 0.023011] apic 0 pin 0 not connected [ 0.024060] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.025042] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.026037] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.027036] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.028036] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.029036] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.030036] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.031036] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.032040] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.033035] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.034046] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.035040] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.036044] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.037036] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.038039] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.039030] apic 0 pin 16 not connected [ 0.040008] apic 0 pin 17 not connected [ 0.042019] apic 0 pin 18 not connected [ 0.043008] apic 0 pin 19 not connected [ 0.044008] apic 0 pin 20 not connected [ 0.045009] apic 0 pin 21 not connected [ 0.046008] apic 0 pin 22 not connected [ 0.047008] apic 0 pin 23 not connected [ 0.048167] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.049008] smpboot: CPU0: Intel Common KVM processor (fam: 0f, model: 06, stepping: 01) [ 0.053008] Using local APIC timer interrupts. [ 0.053008] calibrating APIC timer ... [ 0.055000] ... lapic delta = 6249793 [ 0.055000] ... PM-Timer delta = 357939 [ 0.055000] ... PM-Timer result ok [ 0.055000] ..... delta 6249793 [ 0.055000] ..... mult: 268426565 [ 0.055000] ..... calibration result: 999966 [ 0.055000] ..... CPU clock speed is 2492.0586 MHz. [ 0.055000] ..... host bus clock speed is 999.0966 MHz. [ 0.055077] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.060981] x86: Booting SMP configuration: [ 0.061009] .... node #0, CPUs: #1 [ 0.060251] kvm-clock: cpu 1, msr 0:13c66041, secondary cpu clock [ 0.060251] masked ExtINT on CPU#1 [ 0.075187] KVM setup async PF for cpu 1 [ 0.075276] x86: Booted up 1 node, 2 CPUs [ 0.075284] ---------------- [ 0.075285] | NMI testsuite: [ 0.075285] -------------------- [ 0.075187] remote IPI: [ 0.075187] kvm-stealtime: cpu 1, msr 1390dc40 [ 0.083035] ok | [ 0.083824] local IPI: ok | [ 0.090014] -------------------- [ 0.090870] Good, all 2 testcases passed! | [ 0.091006] --------------------------------- [ 0.092008] smpboot: Total of 2 processors activated (9976.67 BogoMIPS) [ 0.095216] devtmpfs: initialized [ 0.097077] EVM: security.capability [ 0.098753] atomic64 test passed for x86-64 platform with CX8 and with SSE [ 0.099658] regulator-dummy: no parameters [ 0.101258] NET: Registered protocol family 16 [ 0.103159] cpuidle: using governor ladder [ 0.104009] cpuidle: using governor menu [ 0.106827] ACPI: bus type PCI registered [ 0.108034] PCI: Using configuration type 1 for base access [ 0.117521] ACPI: Added _OSI(Module Device) [ 0.118008] ACPI: Added _OSI(Processor Device) [ 0.119007] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.120007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.129723] ACPI: Interpreter enabled [ 0.130021] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20140214/hwxface-580) [ 0.132673] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20140214/hwxface-580) [ 0.135043] ACPI: (supports S0 S3) [ 0.136006] ACPI: Using IOAPIC for interrupt routing [ 0.137056] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.151695] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.152017] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments] [ 0.153067] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.155679] PCI host bridge to bus 0000:00 [ 0.156011] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.157009] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] [ 0.158009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff] [ 0.159009] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] [ 0.160009] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff] [ 0.161092] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.163629] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.165876] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.169009] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 0.171792] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.172490] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.173021] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 0.175405] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 0.178052] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref] [ 0.180057] pci 0000:00:02.0: reg 0x14: [mem 0xfebf0000-0xfebf0fff] [ 0.189048] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.191877] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.193008] pci 0000:00:03.0: reg 0x10: [mem 0xfeba0000-0xfebbffff] [ 0.195537] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.202009] pci 0000:00:03.0: reg 0x30: [mem 0xfebc0000-0xfebdffff pref] [ 0.204232] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 0.206009] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 0.211618] pci_bus 0000:00: on NUMA node 0 [ 0.213927] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.216060] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.218108] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.220174] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.222103] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.224732] ACPI: Enabled 16 GPEs in block 00 to 0F [ 0.226396] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.227009] vgaarb: loaded [ 0.228006] vgaarb: bridge control possible 0000:00:02.0 [ 0.230115] SCSI subsystem initialized [ 0.231783] PCI: Using ACPI for IRQ routing [ 0.232011] PCI: pci_cache_line_size set to 64 bytes [ 0.234136] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.235020] e820: reserve RAM buffer [mem 0x13ffe000-0x13ffffff] [ 0.237628] irda_init() [ 0.238113] NET: Registered protocol family 23 [ 0.239074] Bluetooth: Core ver 2.19 [ 0.240042] NET: Registered protocol family 31 [ 0.241006] Bluetooth: HCI device and connection manager initialized [ 0.242035] Bluetooth: HCI socket layer initialized [ 0.243014] Bluetooth: L2CAP socket layer initialized [ 0.244071] Bluetooth: SCO socket layer initialized [ 0.245027] NET: Registered protocol family 8 [ 0.246007] NET: Registered protocol family 20 [ 0.247102] nfc: nfc_init: NFC Core ver 0.1 [ 0.248092] NET: Registered protocol family 39 [ 0.250298] Switched to clocksource kvm-clock [ 0.252003] AppArmor: AppArmor Filesystem Enabled [ 0.253185] pnp: PnP ACPI init [ 0.254083] ACPI: bus type PNP registered [ 0.255157] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3) [ 0.257139] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.258596] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3) [ 0.270258] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.271705] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3) [ 0.273655] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.275121] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3) [ 0.276958] pnp 00:03: [dma 2] [ 0.277888] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.279351] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3) [ 0.281278] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.282816] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3) [ 0.284761] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.286671] pnp 00:06: Plug and Play ACPI device, IDs PNP0103 (active) [ 0.288363] pnp: PnP ACPI: found 7 devices [ 0.289349] ACPI: bus type PNP unregistered [ 0.307078] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7] [ 0.308314] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff] [ 0.309545] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff] [ 0.310865] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff] [ 0.312269] NET: Registered protocol family 1 [ 0.313338] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.314633] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.315926] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.317273] pci 0000:00:02.0: Boot video device [ 0.318371] PCI: CLS 0 bytes, default 64 [ 0.319790] Unpacking initramfs... [ 0.427950] debug: unmapping init [mem 0xffff880013ce6000-0xffff880013feffff] [ 0.431246] Scanning for low memory corruption every 60 seconds [ 0.432875] camellia-x86_64: performance on this CPU would be suboptimal: disabling camellia-x86_64. [ 0.435152] twofish-x86_64-3way: performance on this CPU would be suboptimal: disabling twofish-x86_64-3way. [ 0.435267] cryptomgr_test (26) used greatest stack depth: 6552 bytes left [ 0.438664] AVX or AES-NI instructions are not detected. [ 0.439842] AVX instructions are not detected. [ 0.440873] AVX instructions are not detected. [ 0.441904] AVX instructions are not detected. [ 0.442939] AVX2 or AES-NI instructions are not detected. [ 0.444117] AVX2 instructions are not detected. [ 0.446879] Initializing RT-Tester: OK [ 0.447841] spin_lock-torture:--- Start of test: nwriters_stress=4 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 0.451052] spin_lock-torture: Creating torture_shuffle task [ 0.452457] spin_lock-torture: torture_shuffle task started [ 0.452469] spin_lock-torture: Creating torture_stutter task [ 0.452557] spin_lock-torture: Creating lock_torture_writer task [ 0.452659] spin_lock-torture: torture_stutter task started [ 0.457648] spin_lock-torture: Creating lock_torture_writer task [ 0.457720] spin_lock-torture: lock_torture_writer task started [ 0.457741] spin_lock-torture: Creating lock_torture_writer task [ 0.457810] spin_lock-torture: lock_torture_writer task started [ 0.457830] spin_lock-torture: Creating lock_torture_writer task [ 0.457914] spin_lock-torture: lock_torture_writer task started [ 0.457934] spin_lock-torture: Creating lock_torture_stats task [ 0.457995] spin_lock-torture: lock_torture_writer task started [ 0.458799] futex hash table entries: 512 (order: 4, 65536 bytes) [ 0.458918] Initialise system trusted keyring [ 0.459035] audit: initializing netlink subsys (disabled) [ 0.459178] audit: type=2000 audit(1394343786.846:1): initialized [ 0.476898] spin_lock-torture: lock_torture_stats task started [ 0.489404] Kprobe smoke test started [ 0.514663] [ 0.515287] ====================================================== [ 0.515561] [ INFO: possible circular locking dependency detected ] [ 0.515561] 3.14.0-rc5-next-20140307-07129-g1b0a7e3 #1 Not tainted [ 0.515561] ------------------------------------------------------- [ 0.515561] kworker/0:1/25 is trying to acquire lock: [ 0.515561] (text_mutex){+.+.+.}, at: [] kprobe_optimizer+0x121/0x23c [ 0.515561] [ 0.515561] but task is already holding lock: [ 0.515561] (module_mutex){+.+...}, at: [] kprobe_optimizer+0x25/0x23c [ 0.515561] [ 0.515561] which lock already depends on the new lock. [ 0.515561] [ 0.515561] [ 0.515561] the existing dependency chain (in reverse order) is: [ 0.515561] -> #2 (module_mutex){+.+...}: [ 0.515561] [] __lock_acquire+0x910/0xa03 [ 0.515561] [] lock_acquire+0xa4/0xc4 [ 0.515561] [] mutex_lock_nested+0x65/0x34a [ 0.515561] [] module_alloc+0x42/0xb5 [ 0.515561] [] alloc_insn_page+0xe/0x10 [ 0.515561] [] __get_insn_slot+0xe0/0x165 [ 0.515561] [] arch_prepare_kprobe+0x36/0xb7 [ 0.515561] [] register_kprobe+0x330/0x490 [ 0.515561] [] init_test_probes+0x4e/0x489 [ 0.515561] [] init_kprobes+0x17b/0x189 [ 0.515561] [] do_one_initcall+0x9f/0x135 [ 0.515561] [] kernel_init_freeable+0x104/0x196 [ 0.515561] [] kernel_init+0x9/0xd5 [ 0.515561] [] ret_from_fork+0x7c/0xb0 [ 0.515561] -> #1 (kprobe_insn_slots.mutex){+.+.+.}: [ 0.515561] [] __lock_acquire+0x910/0xa03 [ 0.515561] [] lock_acquire+0xa4/0xc4 [ 0.515561] [] mutex_lock_nested+0x65/0x34a [ 0.515561] [] __get_insn_slot+0x1c/0x165 [ 0.515561] [] arch_prepare_kprobe+0x36/0xb7 [ 0.515561] [] register_kprobe+0x330/0x490 [ 0.515561] [] init_test_probes+0x4e/0x489 [ 0.515561] [] init_kprobes+0x17b/0x189 [ 0.515561] [] do_one_initcall+0x9f/0x135 [ 0.515561] [] kernel_init_freeable+0x104/0x196 [ 0.515561] [] kernel_init+0x9/0xd5 [ 0.515561] [] ret_from_fork+0x7c/0xb0 [ 0.515561] -> #0 (text_mutex){+.+.+.}: [ 0.515561] [] validate_chain.isra.20+0x8cf/0xf2e [ 0.515561] [] __lock_acquire+0x910/0xa03 [ 0.515561] [] lock_acquire+0xa4/0xc4 [ 0.515561] [] mutex_lock_nested+0x65/0x34a [ 0.515561] [] kprobe_optimizer+0x121/0x23c [ 0.515561] [] process_one_work+0x237/0x3db [ 0.515561] [] worker_thread+0x1cb/0x2c4 [ 0.515561] [] kthread+0xf1/0xf9 [ 0.515561] [] ret_from_fork+0x7c/0xb0 [ 0.515561] [ 0.515561] other info that might help us debug this: [ 0.515561] [ 0.515561] Chain exists of: text_mutex --> kprobe_insn_slots.mutex --> module_mutex [ 0.515561] Possible unsafe locking scenario: [ 0.515561] [ 0.515561] CPU0 CPU1 [ 0.515561] ---- ---- [ 0.515561] lock(module_mutex); [ 0.515561] lock(kprobe_insn_slots.mutex); [ 0.515561] lock(module_mutex); [ 0.515561] lock(text_mutex); [ 0.515561] [ 0.515561] *** DEADLOCK *** [ 0.515561] [ 0.515561] 4 locks held by kworker/0:1/25: [ 0.515561] #0: ("events"){.+.+..}, at: [] process_one_work+0x1d1/0x3db [ 0.515561] #1: ((optimizing_work).work){+.+...}, at: [] process_one_work+0x1d1/0x3db [ 0.515561] #2: (kprobe_mutex){+.+.+.}, at: [] kprobe_optimizer+0x17/0x23c [ 0.515561] #3: (module_mutex){+.+...}, at: [] kprobe_optimizer+0x25/0x23c [ 0.515561] [ 0.515561] stack backtrace: [ 0.515561] CPU: 0 PID: 25 Comm: kworker/0:1 Not tainted 3.14.0-rc5-next-20140307-07129-g1b0a7e3 #1 [ 0.515561] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 0.515561] Workqueue: events kprobe_optimizer [ 0.515561] 0000000000000000 ffff8800137e3b60 ffffffff855b5b1d ffffffff86069110 [ 0.515561] ffff8800137e3ba0 ffffffff855b2850 ffff8800137e4650 ffff8800137e5318 [ 0.515561] ffff8800137e4650 ffff8800137e5318 0000000000000004 ffff8800137e52f0 [ 0.515561] Call Trace: [ 0.515561] [] dump_stack+0x4d/0x66 [ 0.515561] [] print_circular_bug+0x2b4/0x2c2 [ 0.515561] [] validate_chain.isra.20+0x8cf/0xf2e [ 0.515561] [] __lock_acquire+0x910/0xa03 [ 0.515561] [] lock_acquire+0xa4/0xc4 [ 0.515561] [] ? kprobe_optimizer+0x121/0x23c [ 0.515561] [] mutex_lock_nested+0x65/0x34a [ 0.515561] [] ? kprobe_optimizer+0x121/0x23c [ 0.515561] [] ? trace_hardirqs_on+0xd/0xf [ 0.515561] [] kprobe_optimizer+0x121/0x23c [ 0.515561] [] process_one_work+0x237/0x3db [ 0.515561] [] ? process_one_work+0x1d1/0x3db [ 0.515561] [] worker_thread+0x1cb/0x2c4 [ 0.515561] [] ? rescuer_thread+0x29b/0x29b [ 0.515561] [] kthread+0xf1/0xf9 [ 0.515561] [] ? kthread_stop+0x5a/0x5a [ 0.515561] [] ret_from_fork+0x7c/0xb0 [ 0.515561] [] ? kthread_stop+0x5a/0x5a [ 0.679517] Kprobe smoke test passed successfully [ 0.681393] bounce pool size: 64 pages [ 0.682499] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.684990] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.687421] EFS: 1.0a - http://aeschi.ch.eu.org/efs/ [ 0.688594] NILFS version 2 loaded [ 0.689344] BeFS version: 0.9.3 [ 0.691160] GFS2 installed [ 0.692017] msgmni has been set to 571 [ 0.697561] Key type asymmetric registered [ 0.698730] Asymmetric key parser 'x509' registered [ 0.699914] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 0.701506] io scheduler noop registered (default) [ 0.702366] list_sort_test: start testing list_sort() [ 0.703791] test_string_helpers: Running tests... [ 0.705189] xz_dec_test: module loaded [ 0.706098] xz_dec_test: Create a device node with 'mknod xz_dec_test c 251 0' and write .xz files to it. [ 0.708539] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.709870] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.711398] cpcihp_zt5550: ZT5550 CompactPCI Hot Plug Driver version: 0.2 [ 0.713111] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 0.714930] ACPI: Power Button [PWRF] [ 0.749820] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.773535] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 0.776727] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $ [ 0.778323] ac.o: No PCI boards found. [ 0.779326] ac.o: For an ISA board you must supply memory and irq parameters. [ 0.780936] smapi::smapi_init, ERROR invalid usSmapiID [ 0.782122] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine [ 0.784072] mwave: mwavedd::mwave_init: Error: Failed to initialize board data [ 0.785708] mwave: mwavedd::mwave_init: Error: Failed to initialize [ 0.786991] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 0.788899] Hangcheck: Using getrawmonotonic(). [ 0.790084] lkdtm: No crash points registered, enable through debugfs [ 0.791419] Guest personality initialized and is inactive [ 0.792957] VMCI host device registered (name=vmci, major=10, minor=61) [ 0.794531] Initialized host personality [ 0.797149] Uniform Multi-Platform E-IDE driver [ 0.798654] piix 0000:00:01.1: IDE controller (0x8086:0x7010 rev 0x00) [ 0.800440] piix 0000:00:01.1: not 100% native mode: will probe irqs later [ 0.802889] ide0: BM-DMA at 0xc040-0xc047 [ 0.804904] ide1: BM-DMA at 0xc048-0xc04f [ 0.806406] Probing IDE interface ide0... [ 1.337183] Probing IDE interface ide1... [ 1.614891] tsc: Refined TSC clocksource calibration: 2492.686 MHz [ 2.009671] hdc: QEMU DVD-ROM, ATAPI CD/DVD-ROM drive [ 2.622360] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO0 [ 2.623533] hdc: MWDMA2 mode selected [ 2.624474] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [ 2.625399] ide1 at 0x170-0x177,0x376 on irq 15 [ 2.626610] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports [ 2.628583] Loading iSCSI transport class v2.0-870. [ 2.629846] L440GX flash mapping: failed to find PIIX4 ISA bridge, cannot continue [ 2.631172] slram: not enough parameters. [ 2.632036] HSI/SSI char device loaded [ 2.632943] CAN device driver interface [ 2.633760] pcnet32: pcnet32.c:v1.35 21.Apr.2008 tsbogend@alpha.franken.de [ 2.634882] cnic: Broadcom NetXtreme II CNIC Driver cnic v2.5.19 (December 19, 2013) [ 2.636332] enic: Cisco VIC Ethernet NIC Driver, ver 2.1.1.50 [ 2.637286] jme: JMicron JMC2XX ethernet driver version 1.0.8 [ 2.638297] ns83820.c: National Semiconductor DP83820 10/100/1000 driver. [ 2.639666] irda_register_dongle : registering dongle "JetEye PC ESI-9680 PC" (1). [ 2.640940] irda_register_dongle : registering dongle "Parallax LiteLink" (5). [ 2.642188] irda_register_dongle : registering dongle "Microchip MCP2120" (9). [ 2.643756] ieee802154hardmac ieee802154hardmac: Added ieee802154 HardMAC hardware [ 2.645393] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.647575] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.648406] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.649459] mousedev: PS/2 mouse device common for all mice [ 2.650840] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.652811] apanel: Fujitsu BIOS signature 'FJKEYINF' not found... [ 2.654322] input: PC Speaker as /devices/platform/pcspkr/input/input4 [ 2.655612] I2O subsystem v1.325 [ 2.656247] i2o: max drivers = 8 [ 2.657429] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0 [ 2.658972] Driver for 1-wire Dallas network protocol. [ 2.724203] applesmc: supported laptop not found! [ 2.724998] applesmc: driver init failed (ret=-19)! [ 3.455114] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 4.462215] ib700wdt: WDT driver for IB700 single board computer initialising [ 4.463728] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05 [ 4.465290] i6300esb: cannot register miscdev on minor=130 (err=-16) [ 4.466689] i6300ESB timer: probe of 0000:00:04.0 failed with error -16 [ 4.468292] smsc37b787_wdt: SMsC 37B787 watchdog component driver 1.1 initialising... [ 4.471195] smsc37b787_wdt: Unable to register miscdev on minor 130 [ 4.472579] w83877f_wdt: I/O address 0x0443 already in use [ 4.473789] sbc_epx_c3: cannot register miscdev on minor=130 (err=-16) [ 4.475260] Bluetooth: HCI UART driver ver 2.2 [ 4.476329] Bluetooth: HCI H4 protocol initialized [ 4.477455] Bluetooth: HCILL protocol initialized [ 4.478535] Bluetooth: HCIATH3K protocol initialized [ 4.479662] Bluetooth: HCI Three-wire UART (H5) protocol initialized [ 4.485454] ISDN subsystem Rev: 1.1.2.3/1.1.2.2/none/none/1.1.2.2 [ 4.487443] t1pci: revision 1.1.2.2 [ 4.488340] b1: revision 1.1.2.2 [ 4.489181] b1dma: revision 1.1.2.3 [ 7.175475] (null): enodev DEV ADDR = 0xFF [ 7.176612] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 7.178571] hdaps: supported laptop not found! [ 7.179400] hdaps: driver init failed (ret=-19)! [ 7.180547] NET: Registered protocol family 17 [ 7.181596] can: controller area network core (rev 20120528 abi 9) [ 7.182631] NET: Registered protocol family 29 [ 7.183436] can: raw protocol (rev 20120528) [ 7.184394] can: netlink gateway (rev 20130117) max_hops=1 [ 7.185542] NET: Registered protocol family 36 [ 7.186491] batman_adv: B.A.T.M.A.N. advanced 2014.1.0 (compatibility version 15) loaded [ 7.188387] [ 7.188387] printing PIC contents [ 7.189475] ... PIC IMR: ffff [ 7.190233] ... PIC IRR: 1013 [ 7.190929] ... PIC ISR: 0000 [ 7.191750] ... PIC ELCR: 0c00 [ 7.192488] printing local APIC contents on CPU#0/0: [ 7.193274] ... APIC ID: 00000000 (0) [ 7.193274] ... APIC VERSION: 00050014 [ 7.193274] ... APIC TASKPRI: 00000000 (00) [ 7.193274] ... APIC PROCPRI: 00000000 [ 7.193274] ... APIC LDR: 01000000 [ 7.193274] ... APIC DFR: ffffffff [ 7.193274] ... APIC SPIV: 000001ff [ 7.193274] ... APIC ISR field: [ 7.193274] 0000000000000000000000000000000000000000000000000000000000000000 [ 7.193274] ... APIC TMR field: [ 7.193274] 0000000002000000000000000000000000000000000000000000000000000000 [ 7.193274] ... APIC IRR field: [ 7.193274] 0000000000000000000000000000000000000000000000000000000000008000 [ 7.193274] ... APIC ESR: 00000000 [ 7.193274] ... APIC ICR: 000008fd [ 7.193274] ... APIC ICR2: 02000000 [ 7.193274] ... APIC LVTT: 000200ef [ 7.193274] ... APIC LVTPC: 00010000 [ 7.193274] ... APIC LVT0: 00010700 [ 7.193274] ... APIC LVT1: 00000400 [ 7.193274] ... APIC LVTERR: 000000fe [ 7.193274] ... APIC TMICT: 0000f421 [ 7.193274] ... APIC TMCCT: 000079e0 [ 7.193274] ... APIC TDCR: 00000003 [ 7.193274] [ 7.230484] number of MP IRQ sources: 15. [ 7.231310] number of IO-APIC #0 registers: 24. [ 7.232188] testing the IO APIC....................... [ 7.233194] IO APIC #0...... [ 7.233937] .... register #00: 00000000 [ 7.234796] ....... : physical APIC id: 00 [ 7.235638] ....... : Delivery Type: 0 [ 7.236493] ....... : LTS : 0 [ 7.237394] .... register #01: 00170011 [ 7.238260] ....... : max redirection entries: 17 [ 7.239290] ....... : PRQ implemented: 0 [ 7.240157] ....... : IO APIC version: 11 [ 7.241181] .... register #02: 00000000 [ 7.241983] ....... : arbitration: 00 [ 7.242886] .... IRQ redirection table: [ 7.243685] 1 0 0 0 0 0 0 00 [ 7.244543] 0 0 0 0 0 1 1 31 [ 7.245499] 0 0 0 0 0 1 1 30 [ 7.246430] 0 0 0 0 0 1 1 33 [ 7.247387] 1 0 0 0 0 1 1 34 [ 7.256029] 1 1 0 0 0 1 1 35 [ 7.256877] 0 0 0 0 0 1 1 36 [ 7.257880] 0 0 0 0 0 1 1 37 [ 7.258725] 0 0 0 0 0 1 1 38 [ 7.259588] 0 1 0 0 0 1 1 39 [ 7.260486] 1 1 0 0 0 1 1 3A [ 7.261401] 1 1 0 0 0 1 1 3B [ 7.262394] 0 0 0 0 0 1 1 3C [ 7.263334] 0 0 0 0 0 1 1 3D [ 7.264481] 0 0 0 0 0 1 1 3E [ 7.265426] 0 0 0 0 0 1 1 3F [ 7.266343] 1 0 0 0 0 0 0 00 [ 7.267253] 1 0 0 0 0 0 0 00 [ 7.268135] 1 0 0 0 0 0 0 00 [ 7.269090] 1 0 0 0 0 0 0 00 [ 7.270011] 1 0 0 0 0 0 0 00 [ 7.270996] 1 0 0 0 0 0 0 00 [ 7.271920] 1 0 0 0 0 0 0 00 [ 7.272852] 1 0 0 0 0 0 0 00 [ 7.273663] IRQ to pin mappings: [ 7.274382] IRQ0 -> 0:2 [ 7.275255] IRQ1 -> 0:1 [ 7.275909] IRQ3 -> 0:3 [ 7.276788] IRQ4 -> 0:4 [ 7.277432] IRQ5 -> 0:5 [ 7.278374] IRQ6 -> 0:6 [ 7.279156] IRQ7 -> 0:7 [ 7.279892] IRQ8 -> 0:8 [ 7.280762] IRQ9 -> 0:9 [ 7.281526] IRQ10 -> 0:10 [ 7.282403] IRQ11 -> 0:11 [ 7.283086] IRQ12 -> 0:12 [ 7.283830] IRQ13 -> 0:13 [ 7.284578] IRQ14 -> 0:14 [ 7.285498] IRQ15 -> 0:15 [ 7.286157] .................................... done. [ 7.287289] Loading compiled-in X.509 certificates [ 7.288956] Loaded X.509 cert 'Magrathea: Glacier signing key: 95edff3f79cb97869f663866ce5f93d0c8f223ae' [ 7.290820] registered taskstats version 1 [ 7.292076] kworker/u4:1 (91) used greatest stack depth: 6480 bytes left [ 7.292257] Key type encrypted registered [ 7.294440] cryptomgr_probe (93) used greatest stack depth: 6000 bytes left [ 7.296334] AppArmor: AppArmor sha1 policy hashing enabled [ 7.297920] console [netcon0] enabled [ 7.298712] netconsole: network logging started [ 7.299596] drivers/rtc/hctosys.c: unable to open rtc device (rtc0) [ 7.301237] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 7.302135] EDD information not available. [ 7.303136] debug: unmapping init [mem 0xffffffff85b40000-0xffffffff85c43fff] [ 7.304484] Write protecting the kernel read-only data: 10240k [ 7.305790] debug: unmapping init [mem 0xffff8800055c7000-0xffff8800055fffff] [ 7.307192] debug: unmapping init [mem 0xffff880005951000-0xffff8800059fffff] [ 7.314266] mount (104) used greatest stack depth: 5848 bytes left [ 7.320541] S00fbsetup (106) used greatest stack depth: 5624 bytes left [ 7.348038] udev[120]: starting version 164 [ 7.753170] GFS2: path_lookup on rootfs returned error -2 Kernel tests: Boot OK! [ 10.683698] udevd (123) used greatest stack depth: 5600 bytes left [ 10.685228] udevd (124) used greatest stack depth: 5576 bytes left [ 10.686703] udevd (125) used greatest stack depth: 5328 bytes left [ 60.848987] spin_lock-torture: Writes: Total: 4 Max/Min: 0/0 Fail: 0 [ 76.104305] Unregister pv shared memory for cpu 0 [ 76.105437] Unregister pv shared memory for cpu 1 [ 76.106719] spin_lock-torture: Unscheduled system shutdown detected [ 76.108219] no ifx modem active; [ 76.109167] torture thread torture_stutter parking due to system shutdown [ 76.111202] torture thread torture_shuffle parking due to system shutdown [ 76.113369] reboot: Restarting system [ 76.114264] reboot: machine restart Elapsed time: 80 qemu-system-x86_64 -cpu kvm64 -enable-kvm -kernel /kernel/x86_64-randconfig-r5-03091328/1b0a7e3263168a06d3858798e48c5a21d1c78d3c/vmlinuz-3.14.0-rc5-next-20140307-07129-g1b0a7e3 -append 'hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 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/x86_64-randconfig-r5-03091328/next:master/.vmlinuz-1b0a7e3263168a06d3858798e48c5a21d1c78d3c-20140309134226-8-ivytown2 branch=next/master BOOT_IMAGE=/kernel/x86_64-randconfig-r5-03091328/1b0a7e3263168a06d3858798e48c5a21d1c78d3c/vmlinuz-3.14.0-rc5-next-20140307-07129-g1b0a7e3' -initrd /kernel-tests/initrd/yocto-minimal-x86_64.cgz -m 320 -smp 2 -net nic,vlan=1,model=e1000 -net user,vlan=1,hostfwd=tcp::24449-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-yocto-ivytown2-24 -serial file:/dev/shm/kboot/serial-yocto-ivytown2-24 -daemonize -display none -monitor null