early console in setup code
Probing EDD (edd=off to disable)... ok
early console in extract_kernel
input_data: 0x02316069
input_len: 0x0039ebd0
output: 0x01000000
output_len: 0x00a0a5f4
kernel_total_size: 0x016cb000

Decompressing Linux... Parsing ELF... done.
Booting the kernel.
[    0.000000] Linux version 4.9.0-rc8-00175-gbb161fe (kbuild@lkp-g5) (gcc version 4.9.4 (Debian 4.9.4-2) ) #2 SMP PREEMPT Sun Dec 18 10:28:54 CST 2016
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    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-0x0000000013fddfff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fde000-0x0000000013ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[    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 = 0x13fde max_arch_pfn = 0x1000000
[    0.000000] MTRR: Disabled
[    0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
[    0.000000] initial memory mapped: [mem 0x00000000-0x029fffff]
[    0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[    0.000000] BRK [0x024b7000, 0x024b7fff] PGTABLE
[    0.000000] BRK [0x024b8000, 0x024b8fff] PGTABLE
[    0.000000] BRK [0x024b9000, 0x024b9fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x13920000-0x13fcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6960 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE1936 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE180A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 0017CA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: APIC 0x0000000013FE187E 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE18FE 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    0.000000] 319MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 13fde000
[    0.000000]   low ram: 0 - 13fde000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:13fdd001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 7094057355 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   Normal   [mem 0x0000000001000000-0x0000000013fddfff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x0000000013fddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fddfff]
[    0.000000] On node 0 totalpages: 81788
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   Normal zone: 608 pages used for memmap
[    0.000000]   Normal zone: 77790 pages, LIFO batch:15
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    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] 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 ffffb000 (fec00000)
[    0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 20 pages/cpu @d34f8000 s52416 r0 d29504 u81920
[    0.000000] pcpu-alloc: s52416 r0 d29504 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 134fac00
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 81148
[    0.000000] Kernel command line: ip=::::vm-lkp-wsx03-yocto-i386-12::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml ARCH=i386 kconfig=i386-randconfig-c0-12180139 branch=linux-devel/devel-spot-201612180129 commit=bb161feebcbd8560751784c5acdbdca9609624e5 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/vmlinuz-4.9.0-rc8-00175-gbb161fe max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 systemd.log_level=err ignore_loglevel earlyprintk=ttyS0,115200 console=ttyS0,1152
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Memory: 294332K/327152K available (5930K kernel code, 852K rwdata, 2672K rodata, 784K init, 10848K bss, 32820K reserved, 0K cma-reserved)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffd36000 - 0xfffff000   (2852 kB)
[    0.000000]     vmalloc : 0xd47de000 - 0xffd34000   ( 693 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd3fde000   ( 319 MB)
[    0.000000]       .init : 0xc1940000 - 0xc1a04000   ( 784 kB)
[    0.000000]       .data : 0xc15cabec - 0xc193e3c0   (3533 kB)
[    0.000000]       .text : 0xc1000000 - 0xc15cabec   (5930 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=2
[    0.000000] 
[    0.000000] **********************************************************
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **                                                      **
[    0.000000] ** trace_printk() being used. Allocating extra memory.  **
[    0.000000] **                                                      **
[    0.000000] ** This means that this is a DEBUG kernel and it is     **
[    0.000000] ** unsafe for production use.                           **
[    0.000000] **                                                      **
[    0.000000] ** If you see this message and you are not debugging    **
[    0.000000] ** the kernel, report this immediately to your vendor!  **
[    0.000000] **                                                      **
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **********************************************************
[    0.000000] NR_IRQS:2304 nr_irqs:440 16
[    0.000000] CPU 0 irqstacks, hard=d3234000 soft=d3236000
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] bootconsole [earlyser0] disabled
[    0.000000] Linux version 4.9.0-rc8-00175-gbb161fe (kbuild@lkp-g5) (gcc version 4.9.4 (Debian 4.9.4-2) ) #2 SMP PREEMPT Sun Dec 18 10:28:54 CST 2016
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    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-0x0000000013fddfff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fde000-0x0000000013ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[    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 = 0x13fde max_arch_pfn = 0x1000000
[    0.000000] MTRR: Disabled
[    0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
[    0.000000] initial memory mapped: [mem 0x00000000-0x029fffff]
[    0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[    0.000000] BRK [0x024b7000, 0x024b7fff] PGTABLE
[    0.000000] BRK [0x024b8000, 0x024b8fff] PGTABLE
[    0.000000] BRK [0x024b9000, 0x024b9fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x13920000-0x13fcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6960 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE1936 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE180A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 0017CA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: APIC 0x0000000013FE187E 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE18FE 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    0.000000] 319MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 13fde000
[    0.000000]   low ram: 0 - 13fde000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:13fdd001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 7094057355 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   Normal   [mem 0x0000000001000000-0x0000000013fddfff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x0000000013fddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fddfff]
[    0.000000] On node 0 totalpages: 81788
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   Normal zone: 608 pages used for memmap
[    0.000000]   Normal zone: 77790 pages, LIFO batch:15
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    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] 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 ffffb000 (fec00000)
[    0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 20 pages/cpu @d34f8000 s52416 r0 d29504 u81920
[    0.000000] pcpu-alloc: s52416 r0 d29504 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 134fac00
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 81148
[    0.000000] Kernel command line: ip=::::vm-lkp-wsx03-yocto-i386-12::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml ARCH=i386 kconfig=i386-randconfig-c0-12180139 branch=linux-devel/devel-spot-201612180129 commit=bb161feebcbd8560751784c5acdbdca9609624e5 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/vmlinuz-4.9.0-rc8-00175-gbb161fe max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 systemd.log_level=err ignore_loglevel earlyprintk=ttyS0,115200 console=ttyS0,1152
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Memory: 294332K/327152K available (5930K kernel code, 852K rwdata, 2672K rodata, 784K init, 10848K bss, 32820K reserved, 0K cma-reserved)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffd36000 - 0xfffff000   (2852 kB)
[    0.000000]     vmalloc : 0xd47de000 - 0xffd34000   ( 693 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd3fde000   ( 319 MB)
[    0.000000]       .init : 0xc1940000 - 0xc1a04000   ( 784 kB)
[    0.000000]       .data : 0xc15cabec - 0xc193e3c0   (3533 kB)
[    0.000000]       .text : 0xc1000000 - 0xc15cabec   (5930 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=2
[    0.000000] 
[    0.000000] **********************************************************
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **                                                      **
[    0.000000] ** trace_printk() being used. Allocating extra memory.  **
[    0.000000] **                                                      **
[    0.000000] ** This means that this is a DEBUG kernel and it is     **
[    0.000000] ** unsafe for production use.                           **
[    0.000000] **                                                      **
[    0.000000] ** If you see this message and you are not debugging    **
[    0.000000] ** the kernel, report this immediately to your vendor!  **
[    0.000000] **                                                      **
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **********************************************************
[    0.000000] NR_IRQS:2304 nr_irqs:440 16
[    0.000000] CPU 0 irqstacks, hard=d3234000 soft=d3236000
[    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:     32768
[    0.000000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.000000] ... CHAINHASH_SIZE:          32768
[    0.000000]  memory used by lock dependency info: 4895 kB
[    0.000000]  per task-struct memory footprint: 1344 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] ODEBUG: selftest passed
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Detected 2260.998 MHz processor
[    5.054903] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998)
[    5.074935] pid_max: default: 32768 minimum: 301
[    5.084037] ACPI: Core revision 20160831
[    5.333465] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    5.353523] Security Framework initialized
[    5.365615] Yama: becoming mindful.
[    5.377307] random: fast init done
[    5.388779] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    5.405891] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    5.428773] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    5.440404] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    5.464874] Freeing SMP alternatives memory: 20K (c1a04000 - c1a09000)
[    5.501846] smpboot: APIC(0) Converting physical 0 to logical package 0
[    5.518630] smpboot: APIC(1) Converting physical 1 to logical package 1
[    5.536224] smpboot: Max logical packages: 2
[    5.548827] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    5.564452] enabled ExtINT on CPU#0
[    5.584905] ENABLING IO-APIC IRQs
[    5.592878] init IO_APIC IRQs
[    5.601912]  apic 0 pin 0 not connected
[    5.612602] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    5.636495] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    5.659601] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    5.677643] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    5.699645] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    5.722172] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    5.744307] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    5.766314] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    5.788346] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    5.811059] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    5.834324] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    5.856882] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    5.877429] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    6.096108] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    6.117070] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    6.141863]  apic 0 pin 16 not connected
[    6.154531]  apic 0 pin 17 not connected
[    6.167429]  apic 0 pin 18 not connected
[    6.180161]  apic 0 pin 19 not connected
[    6.192900]  apic 0 pin 20 not connected
[    6.205544]  apic 0 pin 21 not connected
[    6.218443]  apic 0 pin 22 not connected
[    6.231795]  apic 0 pin 23 not connected
[    6.245827] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    6.268504] Using local APIC timer interrupts.
[    6.268504] calibrating APIC timer ...
[    6.401797] ... lapic delta = 6243317
[    6.417797] ... PM-Timer delta = 357564
[    6.433708] ... PM-Timer result ok
[    6.448429] ..... delta 6243317
[    6.462647] ..... mult: 268148423
[    6.477203] ..... calibration result: 998930
[    6.494866] ..... CPU clock speed is 2257.0390 MHz.
[    6.513202] ..... host bus clock speed is 998.0930 MHz.
[    6.532354] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    6.564342] Performance Events: PMU not available due to virtualization, using software events only.
[    6.641625] CPU 1 irqstacks, hard=d28c0000 soft=d28c2000
[    6.657179] x86: Booting SMP configuration:
[    6.669560] .... node  #0, CPUs:      #1[1266874882.615494] Initializing CPU#1
[    6.703324] kvm-clock: cpu 1, msr 0:13fdd041, secondary cpu clock
[    6.720890] masked ExtINT on CPU#1
[    6.753640] KVM setup async PF for cpu 1
[    6.771695] kvm-stealtime: cpu 1, msr 1350ec00
[    6.785185] x86: Booted up 1 node, 2 CPUs
[    6.795365] ----------------
[    6.803235] | NMI testsuite:
[    6.811343] --------------------
[    6.819342]   remote IPI:  ok  |
[    6.847175]    local IPI:  ok  |
[    6.868998] --------------------
[    6.878404] Good, all   2 testcases passed! |
[    6.891587] ---------------------------------
[    6.899297] smpboot: Total of 2 processors activated (9043.99 BogoMIPS)
[    6.917778] devtmpfs: initialized
[    6.978891] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    7.003750] prandom: seed boundary self test passed
[    7.023745] prandom: 100 self tests passed
[    7.039620] NET: Registered protocol family 16
[    7.071517] EISA bus registered
[    7.081552] cpuidle: using governor menu
[    7.095295] ACPI: bus type PCI registered
[    7.111237] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0
[    7.123198] PCI: Using configuration type 1 for base access
[    7.349558] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    7.413342] ACPI: Added _OSI(Module Device)
[    7.426280] ACPI: Added _OSI(Processor Device)
[    7.437665] ACPI: Added _OSI(3.0 _SCP Extensions)
[    7.449112] ACPI: Added _OSI(Processor Aggregator Device)
[    7.565547] ACPI: Interpreter enabled
[    7.574553] ACPI: (supports S0 S3 S5)
[    7.582745] ACPI: Using IOAPIC for interrupt routing
[    7.593902] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    7.962676] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    7.975221] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    7.986625] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    7.999558] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    8.026026] PCI host bridge to bus 0000:00
[    8.035045] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    8.048329] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    8.061221] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    8.077068] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window]
[    8.093050] pci_bus 0000:00: root bus resource [bus 00-ff]
[    8.104746] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    8.131897] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    8.160028] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    8.227884] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    8.263899] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    8.278516] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    8.291252] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    8.304575] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    8.328265] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    8.345181] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    8.360833] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    8.386696] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    8.429380] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    8.493703] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    8.599441] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    8.626498] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    8.661206] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    8.694785] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    8.805057] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    8.831569] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    8.870004] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    8.910958] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    9.003712] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref]
[    9.063113] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    9.080118] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    9.149920] pci_bus 0000:00: on NUMA node 0
[    9.198950] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    9.224231] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    9.249083] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    9.274069] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    9.294424] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    9.322391] ACPI: Enabled 3 GPEs in block 00 to 0F
[    9.340831] vgaarb: setting as boot device: PCI:0000:00:02.0
[    9.354472] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    9.374723] vgaarb: loaded
[    9.383117] vgaarb: bridge control possible 0000:00:02.0
[    9.405383] SCSI subsystem initialized
[    9.416717] libata version 3.00 loaded.
[    9.429461] media: Linux media interface: v0.10
[    9.442619] pps_core: LinuxPPS API ver. 1 registered
[    9.456093] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    9.478033] PTP clock support registered
[    9.505971] PCI: Using ACPI for IRQ routing
[    9.523962] PCI: pci_cache_line_size set to 32 bytes
[    9.542971] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    9.555064] e820: reserve RAM buffer [mem 0x13fde000-0x13ffffff]
[    9.580732] workqueue: round-robin CPU selection forced, expect performance impact
[    9.600661] nfc: nfc_init: NFC Core ver 0.1
[    9.610873] NET: Registered protocol family 39
[    9.625962] clocksource: Switched to clocksource kvm-clock
[   10.834317] VFS: Disk quotas dquot_6.6.0
[   10.850592] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[   10.868440] pnp: PnP ACPI init
[   10.879909] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[   10.896081] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[   10.912091] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[   10.925508] pnp 00:03: [dma 2]
[   10.935171] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[   10.952483] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[   10.970180] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[   10.987460] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[   11.022641] pnp: PnP ACPI: found 7 devices
[   11.255436] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[   11.274556] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[   11.285480] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[   11.296231] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[   11.309459] pci_bus 0000:00: resource 7 [mem 0x14000000-0xfebfffff window]
[   11.326533] NET: Registered protocol family 1
[   11.336261] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[   11.347994] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[   11.358363] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[   11.371290] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[   11.389076] PCI: CLS 0 bytes, default 32
[   11.400294] Unpacking initramfs...
[   21.185846] Freeing initrd memory: 6848K (d3920000 - d3fd0000)
[   21.239725] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[   21.257094] apm: disabled - APM is not SMP safe.
[   21.285519] rcu-torture:--- Start of test: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0
[   21.358013] rcu-torture: Creating rcu_torture_writer task
[   21.384656] rcu-torture: Creating rcu_torture_fakewriter task
[   21.385377] rcu-torture: rcu_torture_writer task started
[   21.387771] rcu-torture: Creating rcu_torture_fakewriter task
[   21.389712] rcu-torture: rcu_torture_fakewriter task started
[   21.391431] rcu-torture: Creating rcu_torture_fakewriter task
[   21.392791] rcu-torture: rcu_torture_fakewriter task started
[   21.393696] rcu-torture: Creating rcu_torture_fakewriter task
[   21.397428] rcu-torture: rcu_torture_fakewriter task started
[   21.399658] rcu-torture: Creating rcu_torture_reader task
[   21.402768] rcu-torture: rcu_torture_fakewriter task started
[   21.403685] rcu-torture: Creating rcu_torture_stats task
[   21.405655] rcu-torture: rcu_torture_reader task started
[   21.405815] rcu-torture: Creating torture_shuffle task
[   21.408766] rcu-torture: rcu_torture_stats task started
[   21.411517] rcu-torture: Creating torture_stutter task
[   21.412814] rcu-torture: torture_shuffle task started
[   21.415666] rcu-torture: Creating rcu_torture_cbflood task
[   21.416420] rcu-torture: torture_stutter task started
[   21.428208] rcu-torture: rcu_torture_cbflood task started
[   21.444509] futex hash table entries: 512 (order: 3, 32768 bytes)
[   21.445423] audit: initializing netlink subsys (disabled)
[   21.446612] audit: type=2000 audit(1482029807.006:1): initialized
[   21.486770] workingset: timestamp_bits=30 max_order=17 bucket_order=0
[   21.797218] zbud: loaded
[   21.902675] JFS: nTxBlock = 2353, nTxLock = 18825
[   21.923684] SGI XFS with ACLs, security attributes, debug enabled
[   22.037558] NILFS version 2 loaded
[   22.064688] OCFS2 User DLM kernel interface loaded
[   22.249652] NET: Registered protocol family 38
[   22.260223] Key type asymmetric registered
[   22.273512] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[   22.293375] io scheduler noop registered
[   22.302190] io scheduler deadline registered (default)
[   22.314990] start plist test
[   22.350995] end plist test
[   22.378251] test_hexdump: all 1184 tests passed
[   22.390234] test_firmware: interface ready
[   22.399375] test_uuid: all 18 tests passed
[   22.412277] glob: 64 self-tests passed, 0 failed
[   22.501761] gpio_it87: no device
[   22.540809] hgafb: HGA card not detected.
[   22.555263] hgafb: probe of hgafb.0 failed with error -22
[   22.574452] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   22.590296] ACPI: Power Button [PWRF]
[   22.614724] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[   22.630282] ACPI: Power Button [PWRF]
[   22.640106] Warning: Processor Platform Limit event detected, but not handled.
[   22.656073] Consider compiling CPUfreq support into your kernel.
[   23.922680] r3964: Philips r3964 Driver $Revision: 1.10 $
[   23.933992] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   24.053074] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   24.088394] console [ttyS0] disabled
[    0.000000] Linux version 4.9.0-rc8-00175-gbb161fe (kbuild@lkp-g5) (gcc version 4.9.4 (Debian 4.9.4-2) ) #2 SMP PREEMPT Sun Dec 18 10:28:54 CST 2016
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    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-0x0000000013fddfff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fde000-0x0000000013ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[    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 = 0x13fde max_arch_pfn = 0x1000000
[    0.000000] MTRR: Disabled
[    0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
[    0.000000] initial memory mapped: [mem 0x00000000-0x029fffff]
[    0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[    0.000000] BRK [0x024b7000, 0x024b7fff] PGTABLE
[    0.000000] BRK [0x024b8000, 0x024b8fff] PGTABLE
[    0.000000] BRK [0x024b9000, 0x024b9fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x13920000-0x13fcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6960 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE1936 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE180A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 0017CA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: APIC 0x0000000013FE187E 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE18FE 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    0.000000] 319MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 13fde000
[    0.000000]   low ram: 0 - 13fde000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:13fdd001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 7094057355 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   Normal   [mem 0x0000000001000000-0x0000000013fddfff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x0000000013fddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fddfff]
[    0.000000] On node 0 totalpages: 81788
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   Normal zone: 608 pages used for memmap
[    0.000000]   Normal zone: 77790 pages, LIFO batch:15
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    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] 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 ffffb000 (fec00000)
[    0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 20 pages/cpu @d34f8000 s52416 r0 d29504 u81920
[    0.000000] pcpu-alloc: s52416 r0 d29504 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 134fac00
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 81148
[    0.000000] Kernel command line: ip=::::vm-lkp-wsx03-yocto-i386-12::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml ARCH=i386 kconfig=i386-randconfig-c0-12180139 branch=linux-devel/devel-spot-201612180129 commit=bb161feebcbd8560751784c5acdbdca9609624e5 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/vmlinuz-4.9.0-rc8-00175-gbb161fe max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 systemd.log_level=err ignore_loglevel earlyprintk=ttyS0,115200 console=ttyS0,1152
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Memory: 294332K/327152K available (5930K kernel code, 852K rwdata, 2672K rodata, 784K init, 10848K bss, 32820K reserved, 0K cma-reserved)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffd36000 - 0xfffff000   (2852 kB)
[    0.000000]     vmalloc : 0xd47de000 - 0xffd34000   ( 693 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd3fde000   ( 319 MB)
[    0.000000]       .init : 0xc1940000 - 0xc1a04000   ( 784 kB)
[    0.000000]       .data : 0xc15cabec - 0xc193e3c0   (3533 kB)
[    0.000000]       .text : 0xc1000000 - 0xc15cabec   (5930 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=2
[    0.000000] 
[    0.000000] **********************************************************
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **                                                      **
[    0.000000] ** trace_printk() being used. Allocating extra memory.  **
[    0.000000] **                                                      **
[    0.000000] ** This means that this is a DEBUG kernel and it is     **
[    0.000000] ** unsafe for production use.                           **
[    0.000000] **                                                      **
[    0.000000] ** If you see this message and you are not debugging    **
[    0.000000] ** the kernel, report this immediately to your vendor!  **
[    0.000000] **                                                      **
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **********************************************************
[    0.000000] NR_IRQS:2304 nr_irqs:440 16
[    0.000000] CPU 0 irqstacks, hard=d3234000 soft=d3236000
[    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:     32768
[    0.000000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.000000] ... CHAINHASH_SIZE:          32768
[    0.000000]  memory used by lock dependency info: 4895 kB
[    0.000000]  per task-struct memory footprint: 1344 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] ODEBUG: selftest passed
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Detected 2260.998 MHz processor
[    5.054903] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998)
[    5.074935] pid_max: default: 32768 minimum: 301
[    5.084037] ACPI: Core revision 20160831
[    5.333465] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    5.353523] Security Framework initialized
[    5.365615] Yama: becoming mindful.
[    5.377307] random: fast init done
[    5.388779] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    5.405891] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    5.428773] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    5.440404] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    5.464874] Freeing SMP alternatives memory: 20K (c1a04000 - c1a09000)
[    5.501846] smpboot: APIC(0) Converting physical 0 to logical package 0
[    5.518630] smpboot: APIC(1) Converting physical 1 to logical package 1
[    5.536224] smpboot: Max logical packages: 2
[    5.548827] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    5.564452] enabled ExtINT on CPU#0
[    5.584905] ENABLING IO-APIC IRQs
[    5.592878] init IO_APIC IRQs
[    5.601912]  apic 0 pin 0 not connected
[    5.612602] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    5.636495] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    5.659601] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    5.677643] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    5.699645] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    5.722172] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    5.744307] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    5.766314] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    5.788346] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    5.811059] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    5.834324] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    5.856882] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    5.877429] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    6.096108] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    6.117070] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    6.141863]  apic 0 pin 16 not connected
[    6.154531]  apic 0 pin 17 not connected
[    6.167429]  apic 0 pin 18 not connected
[    6.180161]  apic 0 pin 19 not connected
[    6.192900]  apic 0 pin 20 not connected
[    6.205544]  apic 0 pin 21 not connected
[    6.218443]  apic 0 pin 22 not connected
[    6.231795]  apic 0 pin 23 not connected
[    6.245827] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    6.268504] Using local APIC timer interrupts.
[    6.268504] calibrating APIC timer ...
[    6.401797] ... lapic delta = 6243317
[    6.417797] ... PM-Timer delta = 357564
[    6.433708] ... PM-Timer result ok
[    6.448429] ..... delta 6243317
[    6.462647] ..... mult: 268148423
[    6.477203] ..... calibration result: 998930
[    6.494866] ..... CPU clock speed is 2257.0390 MHz.
[    6.513202] ..... host bus clock speed is 998.0930 MHz.
[    6.532354] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    6.564342] Performance Events: PMU not available due to virtualization, using software events only.
[    6.641625] CPU 1 irqstacks, hard=d28c0000 soft=d28c2000
[    6.657179] x86: Booting SMP configuration:
[    6.669560] .... node  #0, CPUs:      #1
[1266874882.615494] Initializing CPU#1
[    6.703324] kvm-clock: cpu 1, msr 0:13fdd041, secondary cpu clock
[    6.720890] masked ExtINT on CPU#1
[    6.753640] KVM setup async PF for cpu 1
[    6.771695] kvm-stealtime: cpu 1, msr 1350ec00
[    6.785185] x86: Booted up 1 node, 2 CPUs
[    6.795365] ----------------
[    6.803235] | NMI testsuite:
[    6.811343] --------------------
[    6.819342]   remote IPI:  ok  |
[    6.847175]    local IPI:  ok  |
[    6.868998] --------------------
[    6.878404] Good, all   2 testcases passed! |
[    6.891587] ---------------------------------
[    6.899297] smpboot: Total of 2 processors activated (9043.99 BogoMIPS)
[    6.917778] devtmpfs: initialized
[    6.978891] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    7.003750] prandom: seed boundary self test passed
[    7.023745] prandom: 100 self tests passed
[    7.039620] NET: Registered protocol family 16
[    7.071517] EISA bus registered
[    7.081552] cpuidle: using governor menu
[    7.095295] ACPI: bus type PCI registered
[    7.111237] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0
[    7.123198] PCI: Using configuration type 1 for base access
[    7.349558] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    7.413342] ACPI: Added _OSI(Module Device)
[    7.426280] ACPI: Added _OSI(Processor Device)
[    7.437665] ACPI: Added _OSI(3.0 _SCP Extensions)
[    7.449112] ACPI: Added _OSI(Processor Aggregator Device)
[    7.565547] ACPI: Interpreter enabled
[    7.574553] ACPI: (supports S0 S3 S5)
[    7.582745] ACPI: Using IOAPIC for interrupt routing
[    7.593902] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    7.962676] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    7.975221] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    7.986625] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    7.999558] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    8.026026] PCI host bridge to bus 0000:00
[    8.035045] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    8.048329] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    8.061221] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    8.077068] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window]
[    8.093050] pci_bus 0000:00: root bus resource [bus 00-ff]
[    8.104746] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    8.131897] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    8.160028] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    8.227884] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    8.263899] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    8.278516] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    8.291252] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    8.304575] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    8.328265] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    8.345181] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    8.360833] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    8.386696] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    8.429380] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    8.493703] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    8.599441] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    8.626498] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    8.661206] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    8.694785] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    8.805057] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    8.831569] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    8.870004] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    8.910958] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    9.003712] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref]
[    9.063113] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    9.080118] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    9.149920] pci_bus 0000:00: on NUMA node 0
[    9.198950] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    9.224231] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    9.249083] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    9.274069] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    9.294424] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    9.322391] ACPI: Enabled 3 GPEs in block 00 to 0F
[    9.340831] vgaarb: setting as boot device: PCI:0000:00:02.0
[    9.354472] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    9.374723] vgaarb: loaded
[    9.383117] vgaarb: bridge control possible 0000:00:02.0
[    9.405383] SCSI subsystem initialized
[    9.416717] libata version 3.00 loaded.
[    9.429461] media: Linux media interface: v0.10
[    9.442619] pps_core: LinuxPPS API ver. 1 registered
[    9.456093] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    9.478033] PTP clock support registered
[    9.505971] PCI: Using ACPI for IRQ routing
[    9.523962] PCI: pci_cache_line_size set to 32 bytes
[    9.542971] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    9.555064] e820: reserve RAM buffer [mem 0x13fde000-0x13ffffff]
[    9.580732] workqueue: round-robin CPU selection forced, expect performance impact
[    9.600661] nfc: nfc_init: NFC Core ver 0.1
[    9.610873] NET: Registered protocol family 39
[    9.625962] clocksource: Switched to clocksource kvm-clock
[   10.834317] VFS: Disk quotas dquot_6.6.0
[   10.850592] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[   10.868440] pnp: PnP ACPI init
[   10.879909] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[   10.896081] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[   10.912091] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[   10.925508] pnp 00:03: [dma 2]
[   10.935171] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[   10.952483] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[   10.970180] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[   10.987460] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[   11.022641] pnp: PnP ACPI: found 7 devices
[   11.255436] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[   11.274556] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[   11.285480] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[   11.296231] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[   11.309459] pci_bus 0000:00: resource 7 [mem 0x14000000-0xfebfffff window]
[   11.326533] NET: Registered protocol family 1
[   11.336261] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[   11.347994] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[   11.358363] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[   11.371290] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[   11.389076] PCI: CLS 0 bytes, default 32
[   11.400294] Unpacking initramfs...
[   21.185846] Freeing initrd memory: 6848K (d3920000 - d3fd0000)
[   21.239725] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[   21.257094] apm: disabled - APM is not SMP safe.
[   21.285519] rcu-torture:--- Start of test: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0
[   21.358013] rcu-torture: Creating rcu_torture_writer task
[   21.384656] rcu-torture: Creating rcu_torture_fakewriter task
[   21.385377] rcu-torture: rcu_torture_writer task started
[   21.387771] rcu-torture: Creating rcu_torture_fakewriter task
[   21.389712] rcu-torture: rcu_torture_fakewriter task started
[   21.391431] rcu-torture: Creating rcu_torture_fakewriter task
[   21.392791] rcu-torture: rcu_torture_fakewriter task started
[   21.393696] rcu-torture: Creating rcu_torture_fakewriter task
[   21.397428] rcu-torture: rcu_torture_fakewriter task started
[   21.399658] rcu-torture: Creating rcu_torture_reader task
[   21.402768] rcu-torture: rcu_torture_fakewriter task started
[   21.403685] rcu-torture: Creating rcu_torture_stats task
[   21.405655] rcu-torture: rcu_torture_reader task started
[   21.405815] rcu-torture: Creating torture_shuffle task
[   21.408766] rcu-torture: rcu_torture_stats task started
[   21.411517] rcu-torture: Creating torture_stutter task
[   21.412814] rcu-torture: torture_shuffle task started
[   21.415666] rcu-torture: Creating rcu_torture_cbflood task
[   21.416420] rcu-torture: torture_stutter task started
[   21.428208] rcu-torture: rcu_torture_cbflood task started
[   21.444509] futex hash table entries: 512 (order: 3, 32768 bytes)
[   21.445423] audit: initializing netlink subsys (disabled)
[   21.446612] audit: type=2000 audit(1482029807.006:1): initialized
[   21.486770] workingset: timestamp_bits=30 max_order=17 bucket_order=0
[   21.797218] zbud: loaded
[   21.902675] JFS: nTxBlock = 2353, nTxLock = 18825
[   21.923684] SGI XFS with ACLs, security attributes, debug enabled
[   22.037558] NILFS version 2 loaded
[   22.064688] OCFS2 User DLM kernel interface loaded
[   22.249652] NET: Registered protocol family 38
[   22.260223] Key type asymmetric registered
[   22.273512] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[   22.293375] io scheduler noop registered
[   22.302190] io scheduler deadline registered (default)
[   22.314990] start plist test
[   22.350995] end plist test
[   22.378251] test_hexdump: all 1184 tests passed
[   22.390234] test_firmware: interface ready
[   22.399375] test_uuid: all 18 tests passed
[   22.412277] glob: 64 self-tests passed, 0 failed
[   22.501761] gpio_it87: no device
[   22.540809] hgafb: HGA card not detected.
[   22.555263] hgafb: probe of hgafb.0 failed with error -22
[   22.574452] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   22.590296] ACPI: Power Button [PWRF]
[   22.614724] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[   22.630282] ACPI: Power Button [PWRF]
[   22.640106] Warning: Processor Platform Limit event detected, but not handled.
[   22.656073] Consider compiling CPUfreq support into your kernel.
[   23.922680] r3964: Philips r3964 Driver $Revision: 1.10 $
[   23.933992] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   24.053074] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   24.088394] console [ttyS0] disabled
[   24.189404] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   28.313211] console [ttyS0] enabled
[   28.418798] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   28.548479] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   28.624728] DoubleTalk PC - not found
[   28.642257] Failed to find cpu0 device node
[   28.653279] Unable to detect cache hierarchy from DT for CPU 0
[   28.666299] dummy-irq: no IRQ given.  Use irq=N
[   28.682706] lkdtm: No crash points registered, enable through debugfs
[   28.706421] ipddp.c:v0.01 8/28/97 Bradford W. Johnson <johns393@maroon.tc.umn.edu>
[   28.729623] ipddp0: Appletalk-IP Decap. mode by Jay Schulist <jschlst@samba.org>
[   28.744956] CAN device driver interface
[   28.752637] sja1000 CAN netdevice driver
[   28.772303] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   28.798685] serio: i8042 KBD port at 0x60,0x64 irq 1
[   28.809472] serio: i8042 AUX port at 0x60,0x64 irq 12
[   28.826774] evbug: Connected device: input1 (Power Button at LNXPWRBN/button/input0)
[   28.855325] logibm.c: Didn't find Logitech busmouse at 0x23c
[   28.868126] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[   28.888808] evbug: Connected device: input2 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[   28.916348] rtc rtc0: alarm rollover: day
[   28.926195] evbug: Disconnected device: input2
[   28.937707] rtc rtc0: test: dev (252:0)
[   28.958283] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[   28.978369] rtc-test rtc-test.0: rtc core: registered test as rtc0
[   28.992435] evbug: Connected device: input3 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[   29.026855] rtc rtc0: alarm rollover: day
[   29.042860] rtc rtc0: test: dev (252:0)
[   29.053657] rtc-test rtc-test.0: rtc core: registered test as rtc0
[   29.071412] rtc rtc1: alarm rollover: day
[   29.086123] rtc rtc1: test: dev (252:1)
[   29.095489] rtc-test rtc-test.1: rtc core: registered test as rtc1
[   29.113275] rtc rtc1: alarm rollover: day
[   29.126897] rtc rtc1: test: dev (252:1)
[   29.134734] rtc-test rtc-test.1: rtc core: registered test as rtc1
[   29.149831] Driver for 1-wire Dallas network protocol.
[   29.163420] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko
[   29.183568] platform eisa.0: Probing EISA bus 0
[   29.196143] platform eisa.0: EISA: Cannot allocate resource for mainboard
[   29.217472] ledtrig-cpu: registered to indicate activity on CPUs
[   29.268305] fw_cfg: probe of QEMU0002:00 failed with error -16
[   29.301487] GACT probability on
[   29.309462] Simple TC action Loaded
[   29.321573] NET: Registered protocol family 15
[   29.334376] NET: Registered protocol family 4
[   29.344527] NET: Registered protocol family 5
[   29.354540] can: controller area network core (rev 20120528 abi 9)
[   29.371261] NET: Registered protocol family 29
[   29.382335] can: raw protocol (rev 20120528)
[   29.392122] can: broadcast manager protocol (rev 20161123 t)
[   29.404439] NET: Registered protocol family 35
[   29.421327] 8021q: 802.1Q VLAN Support v1.8
[   29.431495] NET: Registered protocol family 37
[   29.465099] batman_adv: B.A.T.M.A.N. advanced 2016.4 (compatibility version 15) loaded
[   29.495460] ... APIC ID:      00000000 (0)
[   29.504347] ... APIC VERSION: 01050014
[   29.512481] 0000000000000000000000000000000000000000000000000000000000000000
[   29.532633] 0000000000000000000000000000000000000000000000000000000000000000
[   29.553015] 0000000000000000000000000000000000000000000000000000000000008000
[   29.575275] number of MP IRQ sources: 15.
[   29.584319] number of IO-APIC #0 registers: 24.
[   29.593951] testing the IO APIC.......................
[   29.606319] IO APIC #0......
[   29.613682] .... register #00: 00000000
[   29.622264] .......    : physical APIC id: 00
[   29.631664] .......    : Delivery Type: 0
[   29.642354] .......    : LTS          : 0
[   29.652694] .... register #01: 00170011
[   29.661253] .......     : max redirection entries: 17
[   29.671717] .......     : PRQ implemented: 0
[   29.680993] .......     : IO APIC version: 11
[   29.690387] .... register #02: 00000000
[   29.699227] .......     : arbitration: 00
[   29.708213] .... IRQ redirection table:
[   29.717123] IOAPIC 0:
[   29.723763]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   29.740194]  pin01, enabled , edge , high, V(31), IRR(0), S(0), logical , D(03), M(1)
[   29.756791]  pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(03), M(1)
[   29.773244]  pin03, disabled, edge , high, V(33), IRR(0), S(0), logical , D(03), M(1)
[   29.789681]  pin04, disabled, edge , high, V(34), IRR(0), S(0), logical , D(03), M(1)
[   29.806434]  pin05, disabled, level, high, V(35), IRR(0), S(0), logical , D(03), M(1)
[   29.822994]  pin06, enabled , edge , high, V(36), IRR(0), S(0), logical , D(03), M(1)
[   29.839521]  pin07, enabled , edge , high, V(37), IRR(0), S(0), logical , D(03), M(1)
[   29.856398]  pin08, enabled , edge , high, V(38), IRR(0), S(0), logical , D(03), M(1)
[   29.872991]  pin09, enabled , level, high, V(39), IRR(0), S(0), logical , D(03), M(1)
[   29.889426]  pin0a, disabled, level, high, V(3A), IRR(0), S(0), logical , D(03), M(1)
[   29.906180]  pin0b, disabled, level, high, V(3B), IRR(0), S(0), logical , D(03), M(1)
[   29.921673]  pin0c, enabled , edge , high, V(3C), IRR(0), S(0), logical , D(03), M(1)
[   29.939136]  pin0d, enabled , edge , high, V(3D), IRR(0), S(0), logical , D(03), M(1)
[   29.955536]  pin0e, enabled , edge , high, V(3E), IRR(0), S(0), logical , D(03), M(1)
[   29.968048]  pin0f, enabled , edge , high, V(3F), IRR(0), S(0), logical , D(03), M(1)
[   29.983119]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   29.999703]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.016529]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.030885]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.045563]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.060370]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.078124]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.094101]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   30.111121] IRQ to pin mappings:
[   30.118958] IRQ0 -> 0:2
[   30.273828] IRQ1 -> 0:1
[   30.281441] IRQ3 -> 0:3
[   30.290716] IRQ4 -> 0:4
[   30.299693] IRQ5 -> 0:5
[   30.308442] IRQ6 -> 0:6
[   30.318517] IRQ7 -> 0:7
[   30.327977] IRQ8 -> 0:8
[   30.338031] IRQ9 -> 0:9
[   30.345507] IRQ10 -> 0:10
[   30.353357] IRQ11 -> 0:11
[   30.362975] IRQ12 -> 0:12
[   30.371377] IRQ13 -> 0:13
[   30.378991] IRQ14 -> 0:14
[   30.386597] IRQ15 -> 0:15
[   30.395844] .................................... done.
[   30.405946] Using IPI No-Shortcut mode
[   30.424256] page_owner is disabled
[   30.443016] BIOS EDD facility v0.16 2004-Jun-25, 1 devices found
[   30.461528] OF: Duplicate name in testcase-data, renamed to "duplicate-name#1"
[   30.560782] ### dt-test ### start of unittest - you will see error messages
[   30.579285] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[   30.605378] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1
[   30.631592] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle
[   30.649378] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle
[   30.667237] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property
[   30.689376] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property
[   30.714068] irq: no irq domain found for /testcase-data/interrupts/intc0 !
[   30.801792] OF: overlay: overlay_is_topmost: #5 clashes #6 @/testcase-data/overlay-node/test-bus/test-unittest8
[   30.824465] OF: overlay: overlay #5 is not topmost
[   30.873078] OF: overlay: destroy: Could not find overlay #6
[   30.885691] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #6
[   30.927749] ### dt-test ### end of unittest - 142 passed, 0 failed
[   30.952479] Freeing unused kernel memory: 784K (c1940000 - c1a04000)
[   30.969742] Write protecting the kernel text: 5932k
[   30.982285] Write protecting the kernel read-only data: 2680k
ifconfig: socket: Address family not supported by protocol
mkdir: can't create directory '/var/lock': No such file or directory
mkdir: can't create directory '/var/lock/lkp-bootstrap.lock': No such file or directory
sleep: invalid number '333.3'
PATH=/sbin:/usr/sbin:/bin:/usr/bin:/lkp/lkp/src/bin
/ # LKP: HOSTNAME vm-lkp-wsx03-yocto-i386-12, MAC , kernel 4.9.0-rc8-00175-gbb161fe 2, serial console /dev/ttyS0
[   32.394637] Kernel tests: Boot OK!
[   32.394637] LKP: waiting for network...
[   49.461256] ls /sys/class/net
[   49.461256] ipddp0
[   49.461256] lo
[   49.461256] /lkp/lkp/src/bin/run-lkp
[   49.461256] RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/0
[   49.461256] job=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml
[   49.461256] run-job /lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml
[   49.461256] skip http request: cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml&job_state=running -O /dev/null
[   76.572493] trinity-main uses obsolete (PF_INET,SOCK_PACKET)
[   79.588251] VFS: Warning: trinity-c7 using old stat() call. Recompile your binary.
[   80.122468] vm86_32: Denied a call to vm86(old) from trinity-c7[446] (uid: 1).  Set the vm.mmap_min_addr sysctl to 0 and/or adjust LSM mmap_min_addr policy to enable vm86 if you are using a vm86-based DOS emulator.
[   80.541838] warning: process `trinity-c4' used the deprecated sysctl system call with 
[   80.575405] 
[   81.187627] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary.
[   81.450859] VFS: Warning: trinity-c6 using old stat() call. Recompile your binary.
[   81.798663] VFS: Warning: trinity-c4 using old stat() call. Recompile your binary.
[   82.642291] rcu-torture: rtc: c1fa3540 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 
[   82.707686] VFS: Warning: trinity-c7 using old stat() call. Recompile your binary.
[   82.767282] rtbf: 0 rtb: 0 nt: 1 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 cbflood: 1
[   82.933608] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[   83.141252] rcu-torture: Reader Batch:  1 1 0 0 0 0 0 0 0 0 0
[   83.279040] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[  144.095432] rcu-torture: rtc: c1fa3540 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 cbflood: 1
[  144.146858] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[  144.204091] rcu-torture: Reader Batch:  1 1 0 0 0 0 0 0 0 0 0
[  144.238314] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[  144.295286] ??? Writer stall state RTWS_STUTTER(8) g24831 c24830 f0x0 ->state 0x1
[  144.320140] rcu_preempt: wait state: 3 ->state: 0x1
[  144.364407] rcu_sched: wait state: 1 ->state: 0x1
[  144.388452] rcu_bh: wait state: 1 ->state: 0x1
[  144.410770] Dumping ftrace buffer:
[  144.423541] ---------------------------------
[  144.436660] rb_produ-46      0.... 21737102us : ring_buffer_producer_thread: Starting ring buffer hammer
[  144.466383] rb_produ-46      1.... 31739486us : ring_buffer_producer_thread: End ring buffer hammer
[  144.495159] rb_produ-46      1.... 32305561us : ring_buffer_producer_thread: Running Consumer at nice: 19
[  144.524350] rb_produ-46      1.... 32305587us : ring_buffer_producer_thread: Running Producer at nice: 19
[  144.549138] rb_produ-46      1.... 32305588us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[  144.577827] rb_produ-46      1.... 32305589us : ring_buffer_producer_thread: Time:     10002378 (usecs)
[  144.595711] rb_produ-46      1.... 32305590us : ring_buffer_producer_thread: Overruns: 912130
[  144.612271] rb_produ-46      1.... 32305592us : ring_buffer_producer_thread: Read:     570220  (by events)
[  144.630352] rb_produ-46      1.... 32305593us : ring_buffer_producer_thread: Entries:  0
[  144.650445] rb_produ-46      1.... 32305594us : ring_buffer_producer_thread: Total:    1482350
[  144.667263] rb_produ-46      1.... 32305616us : ring_buffer_producer_thread: Missed:   0
[  144.683334] rb_produ-46      1.... 32305617us : ring_buffer_producer_thread: Hit:      1482350
[  144.700085] rb_produ-46      1.... 32305619us : ring_buffer_producer_thread: Entries per millisec: 148
[  144.717660] rb_produ-46      1.... 32305620us : ring_buffer_producer_thread: 6756 ns per entry
[  144.734673] rb_produ-46      1.... 32305620us : ring_buffer_producer_thread: Sleeping for 10 secs
[  144.753653] rb_produ-46      1.... 42722798us : ring_buffer_producer_thread: Starting ring buffer hammer
[  144.771513] rb_produ-46      0.... 52722807us : ring_buffer_producer_thread: End ring buffer hammer
[  144.789370] rb_produ-46      0.... 52723103us : ring_buffer_producer_thread: Running Consumer at nice: 19
[  144.807512] rb_produ-46      0.... 52723126us : ring_buffer_producer_thread: Running Producer at nice: 19
[  144.825501] rb_produ-46      0.... 52723127us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[  144.846370] rb_produ-46      0.... 52723128us : ring_buffer_producer_thread: Time:     10000003 (usecs)
[  144.864323] rb_produ-46      0.... 52723129us : ring_buffer_producer_thread: Overruns: 0
[  144.880274] rb_produ-46      0.... 52723131us : ring_buffer_producer_thread: Read:     5846891  (by pages)
[  144.898644] rb_produ-46      0.... 52723132us : ring_buffer_producer_thread: Entries:  2109
[  144.915263] rb_produ-46      0.... 52723133us : ring_buffer_producer_thread: Total:    5849000
[  144.931897] rb_produ-46      0.... 52723134us : ring_buffer_producer_thread: Missed:   0
[  144.948195] rb_produ-46      0.... 52723135us : ring_buffer_producer_thread: Hit:      5849000
[  144.967311] rb_produ-46      0.... 52723157us : ring_buffer_producer_thread: Entries per millisec: 584
[  144.987080] rb_produ-46      0.... 52723158us : ring_buffer_producer_thread: 1712 ns per entry
[  145.005877] rb_produ-46      0.... 52723158us : ring_buffer_producer_thread: Sleeping for 10 secs
[  145.025319] rb_produ-46      1.... 63249859us : ring_buffer_producer_thread: Starting ring buffer hammer
[  145.045702] rb_produ-46      0.... 73252049us : ring_buffer_producer_thread: End ring buffer hammer
[  145.065214] rb_produ-46      0.... 73976464us : ring_buffer_producer_thread: Running Consumer at nice: 19
[  145.085596] rb_produ-46      0.... 73976468us : ring_buffer_producer_thread: Running Producer at nice: 19
[  145.106167] rb_produ-46      0.... 73976469us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[  145.129930] rb_produ-46      0.... 73976491us : ring_buffer_producer_thread: Time:     10002184 (usecs)
[  145.150146] rb_produ-46      0.... 73976492us : ring_buffer_producer_thread: Overruns: 2261850
[  145.169040] rb_produ-46      0.... 73976494us : ring_buffer_producer_thread: Read:     772800  (by events)
[  145.189584] rb_produ-46      0.... 73976495us : ring_buffer_producer_thread: Entries:  0
[  145.207571] rb_produ-46      0.... 73976496us : ring_buffer_producer_thread: Total:    3034650
[  145.226592] rb_produ-46      0.... 73976497us : ring_buffer_producer_thread: Missed:   0
[  145.404277] rb_produ-46      0.... 73976497us : ring_buffer_producer_thread: Hit:      3034650
[  145.423519] rb_produ-46      0.... 73976498us : ring_buffer_producer_thread: Entries per millisec: 303
[  145.443776] rb_produ-46      0.... 73976499us : ring_buffer_producer_thread: 3300 ns per entry
[  145.462643] rb_produ-46      0.... 73976500us : ring_buffer_producer_thread: Sleeping for 10 secs
[  145.482053] rb_produ-46      1.... 84304035us : ring_buffer_producer_thread: Starting ring buffer hammer
[  145.502205] rb_produ-46      1.... 94316235us : ring_buffer_producer_thread: End ring buffer hammer
[  145.521813] rb_produ-46      1.... 94329721us : ring_buffer_producer_thread: Running Consumer at nice: 19
[  145.542060] rb_produ-46      1.... 94329745us : ring_buffer_producer_thread: Running Producer at nice: 19
[  145.563324] rb_produ-46      1.... 94329746us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[  145.586787] rb_produ-46      1.... 94329747us : ring_buffer_producer_thread: Time:     10012194 (usecs)
[  145.606814] rb_produ-46      1.... 94329748us : ring_buffer_producer_thread: Overruns: 0
[  145.624770] rb_produ-46      1.... 94329750us : ring_buffer_producer_thread: Read:     1586079  (by pages)
[  145.645111] rb_produ-46      1.... 94329752us : ring_buffer_producer_thread: Entries:  321
[  145.663267] rb_produ-46      1.... 94329753us : ring_buffer_producer_thread: Total:    1586400
[  145.681927] rb_produ-46      1.... 94329753us : ring_buffer_producer_thread: Missed:   0
[  145.699851] rb_produ-46      1.... 94329754us : ring_buffer_producer_thread: Hit:      1586400
[  145.718575] rb_produ-46      1.... 94329776us : ring_buffer_producer_thread: Entries per millisec: 158
[  145.738313] rb_produ-46      1.... 94329777us : ring_buffer_producer_thread: 6329 ns per entry
[  145.756954] rb_produ-46      1.... 94329778us : ring_buffer_producer_thread: Sleeping for 10 secs
[  145.776037] rb_produ-46      1.... 104672339us : ring_buffer_producer_thread: Starting ring buffer hammer
[  145.796550] rb_produ-46      1.... 114710092us : ring_buffer_producer_thread: End ring buffer hammer
[  145.815738] rb_produ-46      1.... 116272192us : ring_buffer_producer_thread: Running Consumer at nice: 19
[  145.836046] rb_produ-46      1.... 116272217us : ring_buffer_producer_thread: Running Producer at nice: 19
[  145.856279] rb_produ-46      1.... 116272218us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[  145.879925] rb_produ-46      1.... 116272219us : ring_buffer_producer_thread: Time:     10037747 (usecs)
[  145.899766] rb_produ-46      1.... 116272220us : ring_buffer_producer_thread: Overruns: 1383879
[  145.918593] rb_produ-46      1.... 116272223us : ring_buffer_producer_thread: Read:     701921  (by events)
[  145.938880] rb_produ-46      1.... 116272224us : ring_buffer_producer_thread: Entries:  0
[  145.956762] rb_produ-46      1.... 116272225us : ring_buffer_producer_thread: Total:    2085800
[  145.975557] rb_produ-46      1.... 116272226us : ring_buffer_producer_thread: Missed:   0
[  145.993419] rb_produ-46      1.... 116272227us : ring_buffer_producer_thread: Hit:      2085800
[  146.012152] rb_produ-46      1.... 116272228us : ring_buffer_producer_thread: Entries per millisec: 207
[  146.032015] rb_produ-46      1.... 116272250us : ring_buffer_producer_thread: 4830 ns per entry
[  146.050835] rb_produ-46      1.... 116272251us : ring_buffer_producer_thread: Sleeping for 10 secs
[  146.069925] rb_produ-46      1.... 126704305us : ring_buffer_producer_thread: Starting ring buffer hammer
[  146.090084] rb_produ-46      0.... 136704331us : ring_buffer_producer_thread: End ring buffer hammer
[  146.109497] rb_produ-46      0.... 136705534us : ring_buffer_producer_thread: Running Consumer at nice: 19
[  146.129818] rb_produ-46      0.... 136705536us : ring_buffer_producer_thread: Running Producer at nice: 19
[  146.149975] rb_produ-46      0.... 136705537us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[  146.173395] rb_produ-46      0.... 136705538us : ring_buffer_producer_thread: Time:     10000000 (usecs)
[  146.193293] rb_produ-46      0.... 136705539us : ring_buffer_producer_thread: Overruns: 0
[  146.211124] rb_produ-46      0.... 136705541us : ring_buffer_producer_thread: Read:     2713433  (by pages)
[  146.231508] rb_produ-46      0.... 136705563us : ring_buffer_producer_thread: Entries:  417
[  146.249684] rb_produ-46      0.... 136705564us : ring_buffer_producer_thread: Total:    2713850
[  146.268415] rb_produ-46      0.... 136705565us : ring_buffer_producer_thread: Missed:   0
[  146.286209] rb_produ-46      0.... 136705566us : ring_buffer_producer_thread: Hit:      2713850
[  146.304938] rb_produ-46      0.... 136705567us : ring_buffer_producer_thread: Entries per millisec: 271
[  146.324730] rb_produ-46      0.... 136705568us : ring_buffer_producer_thread: 3690 ns per entry
[  146.343500] rb_produ-46      0.... 136705569us : ring_buffer_producer_thread: Sleeping for 10 secs
[  146.362484] ---------------------------------
[  146.372237] BUG: spinlock missed unlock deadline on CPU#1, rcu_torture_sta/41
[  146.388805]  lock: jiffies_lock+0x14/0x40, .magic: dead4ead, .owner: rcu_torture_sta/41, .owner_cpu: 1
[  146.409995] CPU: 1 PID: 41 Comm: rcu_torture_sta Not tainted 4.9.0-rc8-00175-gbb161fe #2
[  146.429314] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[  146.452805]  cfd9fda0 c137d94f d1f1b35c c1871ed4 cfd9fdc8 c1074f3a c17b120c c1871ed4
[  146.479859]  dead4ead d1f1b35c 00000029 00000001 c1871ed4 000f4240 cfd9fdd8 c1074f61
[  146.505565]  c17b12be c1871ed4 cfd9fde4 c107515c c1871ed4 cfd9fdf0 c15c87bb 00000000
[  146.532230] Call Trace:
[  146.538713]  [<c137d94f>] dump_stack+0x72/0xa3
[  146.548623]  [<c1074f3a>] spin_dump+0x75/0x7f
[  146.557793]  [<c1074f61>] spin_bug+0x1d/0x21
[  146.566973]  [<c107515c>] do_raw_spin_unlock+0x74/0x88
[  146.577409]  [<c15c87bb>] _raw_spin_unlock+0x1d/0x30
[  146.587680]  [<c10969f5>] tick_do_update_jiffies64+0xfd/0x11b
[  146.599311]  [<c1096a71>] tick_nohz_handler+0x5e/0xce
[  146.609514]  [<c1027f80>] local_apic_timer_interrupt+0x3c/0x41
[  146.621385]  [<c1028614>] smp_apic_timer_interrupt+0x20/0x2f
[  146.632786]  [<c15c99f2>] apic_timer_interrupt+0x32/0x40
[  146.643638]  [<c10b5ce7>] ? arch_local_irq_restore+0x5/0xb
[  146.654590]  [<c10bc412>] ftrace_dump+0x1bd/0x1c5
[  146.663894]  [<c1083c28>] rcu_torture_stats_print+0x33e/0x350
[  146.675269]  [<c1083c3a>] ? rcu_torture_stats_print+0x350/0x350
[  146.686421]  [<c1083c72>] rcu_torture_stats+0x38/0x59
[  146.696629]  [<c1057f92>] kthread+0x8d/0x92
[  146.705274]  [<c1057f05>] ? kthread_create_on_node+0x1a/0x1a
[  146.716753]  [<c15c8e3b>] ret_from_fork+0x1b/0x30
[  147.447220] serial8250: too much work for irq3
[  150.482619] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns

Elapsed time: 150

initrds=(
	/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
	/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.cgz
	/lkp/lkp/lkp-i386.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz
	/osimage/pkg/static/trinity-i386.cgz
	/pkg/linux/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/modules.cgz
)

cat "${initrds[@]}" > /fs/sdc1/initrd-vm-lkp-wsx03-yocto-i386-12

kvm=(
	qemu-system-i386
	-enable-kvm
	-kernel /pkg/linux/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/vmlinuz-4.9.0-rc8-00175-gbb161fe
	-initrd /fs/sdc1/initrd-vm-lkp-wsx03-yocto-i386-12
	-m 320
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=/fs/sdc1/disk0-vm-lkp-wsx03-yocto-i386-12,media=disk,if=virtio
	-pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-yocto-i386-12
	-serial file:/dev/shm/kboot/vm-lkp-wsx03-yocto-i386-12/serial
	-serial file:/dev/shm/kboot/vm-lkp-wsx03-yocto-i386-12/kmsg
	-daemonize
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-wsx03-yocto-i386-12::dhcp
	root=/dev/ram0
	user=lkp
	job=/lkp/scheduled/vm-lkp-wsx03-yocto-i386-12/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-bb161feebcbd8560751784c5acdbdca9609624e5-20161218-97201-1hozxji-0.yaml
	ARCH=i386
	kconfig=i386-randconfig-c0-12180139
	branch=linux-devel/devel-spot-201612180129
	commit=bb161feebcbd8560751784c5acdbdca9609624e5
	BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/vmlinuz-4.9.0-rc8-00175-gbb161fe
	max_uptime=1500
	RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-12180139/gcc-4.9/bb161feebcbd8560751784c5acdbdca9609624e5/0
	LKP_SERVER=inn
	debug
	apic=debug
	sysrq_always_enabled
	rcupdate.rcu_cpu_stall_timeout=100
	net.ifnames=0
	printk.devkmsg=on
	panic=-1
	softlockup_panic=1
	nmi_watchdog=panic
	oops=panic
	load_ramdisk=2
	prompt_ramdisk=0
	systemd.log_level=err
	ignore_loglevel
	earlyprintk=ttyS0,115200
	console=ttyS0,115200
	console=tty0
	vga=normal
	rw
	drbd.minor_count=8
)

"${kvm[@]}" -append "${append[*]}"
