early console in setup code
Probing EDD (edd=off to disable)... ok
[    0.000000] Linux version 4.14.0-rc6-00050-g2dcd5ad (kbuild@lkp-hsx03) (gcc version 5.4.1 20160904 (Debian 5.4.1-2)) #1 SMP PREEMPT Sat Oct 28 11:11:05 CST 2017
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    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-0x000000000ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000000ffe0000-0x000000000fffffff] 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] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] random: fast init done
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0xffe0 max_arch_pfn = 0x100000
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] initial memory mapped: [mem 0x00000000-0x043fffff]
[    0.000000] Base memory trampoline at [b009b000] 9b000 size 16384
[    0.000000] BRK [0x0410d000, 0x0410dfff] PGTABLE
[    0.000000] BRK [0x0410e000, 0x0410efff] PGTABLE
[    0.000000] BRK [0x0410f000, 0x0410ffff] PGTABLE
[    0.000000] BRK [0x04110000, 0x04110fff] PGTABLE
[    0.000000] BRK [0x04111000, 0x04111fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x0f3cd000-0x0ffdffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68D0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000000FFE15CE 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000000FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000000FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000000FFE0000 000040
[    0.000000] ACPI: APIC 0x000000000FFE151E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000000FFE1596 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    0.000000] 255MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 0ffe0000
[    0.000000]   low ram: 0 - 0ffe0000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:f3cc001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 1374810119 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-0x000000000ffdffff]
[    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-0x000000000ffdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000000ffdffff]
[    0.000000] On node 0 totalpages: 65406
[    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: 480 pages used for memmap
[    0.000000]   Normal zone: 61408 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 1 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffb000 (fec00000)
[    0.000000] e820: [mem 0x10000000-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: 19112604462750000 ns
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] percpu: Embedded 341 pages/cpu @bf074000 s1366048 r0 d30688 u1396736
[    0.000000] pcpu-alloc: s1366048 r0 d30688 u1396736 alloc=341*4096
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr f076b00
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64894
[    0.000000] Kernel command line: ip=::::vm-lkp-nhm-dp1-yocto-i386-9::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-nhm-dp1-yocto-i386-9/boot-1-yocto-tiny-i386-2016-04-22.cgz-2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b-20171028-21522-vhp700-0.yaml ARCH=i386 kconfig=i386-randconfig-h1-10271316 branch=linux-devel/devel-spot-201710271052 commit=2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b BOOT_IMAGE=/pkg/linux/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/vmlinuz-4.14.0-rc6-00050-g2dcd5ad max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-nhm-dp1-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/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 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Memory: 195144K/261624K available (12015K kernel code, 2742K rwdata, 5052K rodata, 2368K init, 27860K bss, 66480K reserved, 0K cma-reserved)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff0f000 - 0xfffff000   ( 960 kB)
[    0.000000]     vmalloc : 0xc07e0000 - 0xfff0d000   (1015 MB)
[    0.000000]     lowmem  : 0xb0000000 - 0xbffe0000   ( 255 MB)
[    0.000000]       .init : 0xb2371000 - 0xb25c1000   (2368 kB)
[    0.000000]       .data : 0xb1bbbfd6 - 0xb235b9c0   (7806 kB)
[    0.000000]       .text : 0xb1000000 - 0xb1bbbfd6   (12015 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.010000] Running RCU self tests
[    0.010000] Preemptible hierarchical RCU implementation.
[    0.010000] 	RCU event tracing is enabled.
[    0.010000] 	RCU lockdep checking is enabled.
[    0.010000] 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[    0.010000] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    0.010000] 	Tasks RCU enabled.
[    0.010000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.010000] NR_IRQS: 2304, nr_irqs: 256, preallocated irqs: 16
[    0.010000] CPU 0 irqstacks, hard=be818000 soft=be81a000
[    0.010000] 	Offload RCU callbacks from CPUs: .
[    0.010000] console [ttyS0] enabled
[    0.010000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.010000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.010000] ... MAX_LOCK_DEPTH:          48
[    0.010000] ... MAX_LOCKDEP_KEYS:        8191
[    0.010000] ... CLASSHASH_SIZE:          4096
[    0.010000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.010000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.010000] ... CHAINHASH_SIZE:          32768
[    0.010000]  memory used by lock dependency info: 4655 kB
[    0.010000]  per task-struct memory footprint: 2304 bytes
[    0.010000] ------------------------
[    0.010000] | Locking API testsuite:
[    0.010000] ----------------------------------------------------------------------------
[    0.010000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]               recursive read-lock:             |  ok  |             |  ok  |
[    0.010000]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.010000]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.010000]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.010000]   mixed read-lock/lock-write ABBA:             |FAILED|             |  ok  |
[    0.010000]    mixed read-lock/lock-read ABBA:             |  ok  |             |  ok  |
[    0.010000]  mixed write-lock/lock-write ABBA:             |  ok  |             |  ok  |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.010000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.010000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.010000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.010000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq read-recursion/123:  ok  |
[    0.010000]       soft-irq read-recursion/123:  ok  |
[    0.010000]       hard-irq read-recursion/132:  ok  |
[    0.010000]       soft-irq read-recursion/132:  ok  |
[    0.010000]       hard-irq read-recursion/213:  ok  |
[    0.010000]       soft-irq read-recursion/213:  ok  |
[    0.010000]       hard-irq read-recursion/231:  ok  |
[    0.010000]       soft-irq read-recursion/231:  ok  |
[    0.010000]       hard-irq read-recursion/312:  ok  |
[    0.010000]       soft-irq read-recursion/312:  ok  |
[    0.010000]       hard-irq read-recursion/321:  ok  |
[    0.010000]       soft-irq read-recursion/321:  ok  |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]   | Wound/wait tests |
[    0.010000]   ---------------------
[    0.010000]                   ww api failures:  ok  |  ok  |  ok  |
[    0.010000]                ww contexts mixing:  ok  |  ok  |
[    0.010000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    0.010000]                locking mismatches:  ok  |  ok  |  ok  |
[    0.010000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]            spinlock nest unlocked:  ok  |
[    0.010000]   -----------------------------------------------------
[    0.010000]                                  |block | try  |context|
[    0.010000]   -----------------------------------------------------
[    0.010000]                           context:  ok  |  ok  |  ok  |
[    0.010000]                               try:  ok  |  ok  |  ok  |
[    0.010000]                             block:  ok  |  ok  |  ok  |
[    0.010000]                          spinlock:  ok  |  ok  |  ok  |
[    0.010000] -------------------------------------------------------
[    0.010000] Good, all 261 testcases passed! |
[    0.010000] ---------------------------------
[    0.010000] allocated 262144 bytes of page_ext
[    0.010000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.010000] hpet clockevent registered
[    0.010077] tsc: Detected 2925.998 MHz processor
[    0.010809] Calibrating delay loop (skipped) preset value.. 5851.99 BogoMIPS (lpj=29259980)
[    0.011334] pid_max: default: 32768 minimum: 301
[    0.012164] ACPI: Core revision 20170728
[    0.038554] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.039870] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.040037] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.043455] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.044265] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.050662] debug: unmapping init [mem 0xb25c1000-0xb25c5fff]
[    0.052777] smpboot: Max logical packages: 1
[    0.053238] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.053791] enabled ExtINT on CPU#0
[    0.055285] ENABLING IO-APIC IRQs
[    0.055667] init IO_APIC IRQs
[    0.055959]  apic 0 pin 0 not connected
[    0.056521] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.057420] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.058235] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.059006] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.059783] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.060000]  apic 0 pin 16 not connected
[    0.060000]  apic 0 pin 17 not connected
[    0.060000]  apic 0 pin 18 not connected
[    0.060000]  apic 0 pin 19 not connected
[    0.060000]  apic 0 pin 20 not connected
[    0.060000]  apic 0 pin 21 not connected
[    0.060000]  apic 0 pin 22 not connected
[    0.060000]  apic 0 pin 23 not connected
[    0.060000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.060000] Using local APIC timer interrupts.
[    0.060000] calibrating APIC timer ...
[    0.060000] ... lapic delta = 6263145
[    0.060000] ... PM-Timer delta = 359034
[    0.060000] ... PM-Timer result ok
[    0.060000] ..... delta 6263145
[    0.060000] ..... mult: 269000029
[    0.060000] ..... calibration result: 10021032
[    0.060000] ..... CPU clock speed is 2936.9724 MHz.
[    0.060000] ..... host bus clock speed is 1002.1032 MHz.
[    0.060107] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.100152] Performance Events: PMU not available due to virtualization, using software events only.
[    0.112174] Hierarchical SRCU implementation.
[    0.170073] smp: Bringing up secondary CPUs ...
[    0.170720] smp: Brought up 1 node, 1 CPU
[    0.171136] smpboot: Total of 1 processors activated (5851.99 BogoMIPS)
[    0.173698] devtmpfs: initialized
[    0.175764] gcov: version magic: 0x3530342a
[    0.179459] workqueue: round-robin CPU selection forced, expect performance impact
[    0.190331] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.191766] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.192696] xor: measuring software checksum speed
[    0.290039]    pIII_sse  : 11509.200 MB/sec
[    0.390015]    prefetch64-sse: 14545.200 MB/sec
[    0.390458] xor: using function: prefetch64-sse (14545.200 MB/sec)
[    0.391043] pinctrl core: initialized pinctrl subsystem
[    0.392597] regulator-dummy: no parameters
[    0.393542] regulator-dummy: no parameters
[    0.394069] regulator-dummy: Failed to create debugfs directory
[    0.395419] NET: Registered protocol family 16
[    0.397899] cpuidle: using governor ladder
[    0.398771] ACPI: bus type PCI registered
[    0.399649] PCI: Using configuration type 1 for base access
[    0.620165] raid6: mmxx1    gen()   563 MB/s
[    0.790113] raid6: mmxx2    gen()   777 MB/s
[    0.960049] raid6: sse1x1   gen()   652 MB/s
[    1.130215] raid6: sse1x2   gen()   661 MB/s
[    1.300053] raid6: sse2x1   gen()  1508 MB/s
[    1.470041] raid6: sse2x1   xor()   939 MB/s
[    1.640034] raid6: sse2x2   gen()  1583 MB/s
[    1.810032] raid6: sse2x2   xor()  1027 MB/s
[    1.810541] raid6: using algorithm sse2x2 gen() 1583 MB/s
[    1.811035] raid6: .... xor() 1027 MB/s, rmw enabled
[    1.811481] raid6: using intx1 recovery algorithm
[    1.812571] ACPI: Added _OSI(Module Device)
[    1.813091] ACPI: Added _OSI(Processor Device)
[    1.813577] ACPI: Added _OSI(3.0 _SCP Extensions)
[    1.814154] ACPI: Added _OSI(Processor Aggregator Device)
[    1.828007] ACPI: Interpreter enabled
[    1.828398] ACPI: (supports S0 S5)
[    1.828766] ACPI: Using IOAPIC for interrupt routing
[    1.829666] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    1.873320] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    1.873949] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments]
[    1.874687] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    1.876200] PCI host bridge to bus 0000:00
[    1.876594] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    1.877215] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    1.877833] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    1.878504] pci_bus 0000:00: root bus resource [mem 0x10000000-0xfebfffff window]
[    1.879182] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.879805] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    1.882477] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    1.884888] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    1.888837] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    1.891311] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    1.891964] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    1.892546] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    1.893197] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    1.895039] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    1.896017] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    1.897261] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    1.900945] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    1.904726] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    1.909902] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    1.918785] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    1.922357] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    1.924758] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    1.927099] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    1.935152] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    1.937378] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[    1.939075] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[    1.944844] pci_bus 0000:00: on NUMA node 0
[    1.950081] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    1.951469] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    1.952792] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    1.954103] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    1.954989] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    1.970212] PCI: Using ACPI for IRQ routing
[    1.970714] PCI: pci_cache_line_size set to 32 bytes
[    1.971293] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.971934] e820: reserve RAM buffer [mem 0x0ffe0000-0x0fffffff]
[    1.974869] clocksource: Switched to clocksource kvm-clock
[    2.099602] VFS: Disk quotas dquot_6.6.0
[    2.100053] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.101097] FS-Cache: Loaded
[    2.101587] pnp: PnP ACPI init
[    2.102367] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    2.103268] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    2.104150] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    2.104830] pnp 00:03: [dma 2]
[    2.105464] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    2.106492] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    2.107608] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    2.108564] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    2.111358] pnp: PnP ACPI: found 7 devices
[    2.148958] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    2.150231] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    2.150799] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    2.151361] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    2.151979] pci_bus 0000:00: resource 7 [mem 0x10000000-0xfebfffff window]
[    2.153004] NET: Registered protocol family 1
[    2.153430] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    2.153984] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    2.154526] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    2.155140] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    2.155955] PCI: CLS 0 bytes, default 32
[    2.156977] Unpacking initramfs...
[    3.886205] debug: unmapping init [mem 0xbf3cd000-0xbffdffff]
[    3.890173] Scanning for low memory corruption every 60 seconds
[    4.440631] 
[    4.442100] ======================================================
[    4.442653] WARNING: possible circular locking dependency detected
[    4.443206] 4.14.0-rc6-00050-g2dcd5ad #1 Not tainted
[    4.443648] ------------------------------------------------------
[    4.444200] kworker/0:1/15 is trying to acquire lock:
[    4.444661]  (ww_class_mutex){+.+.}, at: [<b110af92>] test_abba_work+0x3b/0x167
[    4.445354] 
[    4.445354] but now in release context of a crosslock acquired at the following:
[    4.446160]  ((completion)&abba.b_ready){+.+.}, at: [<b1baf438>] wait_for_common+0x1b/0x2b
[    4.446899] 
[    4.446899] which lock already depends on the new lock.
[    4.446899] 
[    4.447622] 
[    4.447622] the existing dependency chain (in reverse order) is:
[    4.448297] 
[    4.448297] -> #1 ((completion)&abba.b_ready){+.+.}:
[    4.448912]        save_stack_trace+0x1e/0x2e
[    4.449336]        __lock_acquire+0x1554/0x1ea3
[    4.449797]        save_trace+0x0/0xfc
[    4.450156]        lock_acquire+0x25f/0x368
[    4.450414]        wait_for_common+0x1b/0x2b
[    4.450414]        __wait_for_common+0x72/0x44b
[    4.450414]        wait_for_common+0x1b/0x2b
[    4.450414]        mark_held_locks+0x98/0xcb
[    4.450414]        schedule_timeout+0x0/0x129
[    4.450414]        trace_hardirqs_on_caller+0x353/0x3ac
[    4.450414]        wait_for_common+0x1b/0x2b
[    4.450414]        wait_for_completion+0x1d/0x2c
[    4.450414]        test_abba+0x180/0x351
[    4.450414]        test_abba_work+0x0/0x167
[    4.450414]        test_abba+0x14c/0x351
[    4.450414]        complete+0x1d/0x95
[    4.450414]        ww_acquire_fini+0x20/0xca
[    4.450414]        test_aa+0x161/0x17a
[    4.450414]        test_aa+0x4d/0x17a
[    4.450414]        test_ww_mutex_init+0x17f/0x694
[    4.450414]        _raw_spin_unlock_irqrestore+0xbb/0x13e
[    4.450414]        add_device_randomness+0x29d/0x368
[    4.450414]        test_ww_mutex_init+0x0/0x694
[    4.450414]        do_one_initcall+0x108/0x279
[    4.450414]        do_early_param+0xee/0x1a1
[    4.450414]        kernel_init_freeable+0x2b6/0x494
[    4.450414]        kernel_init_freeable+0x2b6/0x494
[    4.450414]        kernel_init_freeable+0x2ed/0x494
[    4.450414]        kernel_init+0x0/0x231
[    4.450414]        kernel_init+0x13/0x231
[    4.450414]        ret_from_fork+0x19/0x30
[    4.450414] 
[    4.450414] -> #0 (ww_class_mutex){+.+.}:
[    4.450414]        test_abba_work+0x3b/0x167
[    4.450414] 
[    4.450414] other info that might help us debug this:
[    4.450414] 
[    4.450414]  Possible unsafe locking scenario by crosslock:
[    4.450414] 
[    4.450414]        CPU0                    CPU1
[    4.450414]        ----                    ----
[    4.450414]   lock(ww_class_mutex);
[    4.450414]   lock((completion)&abba.b_ready);
[    4.450414]                                lock(ww_class_mutex);
[    4.450414]                                unlock((completion)&abba.b_ready);
[    4.450414] 
[    4.450414]  *** DEADLOCK ***
[    4.450414] 
[    4.450414] 5 locks held by kworker/0:1/15:
[    4.450414]  #0:  ("events"){+.+.}, at: [<b10b18cc>] process_one_work+0x210/0x9bd
[    4.450414]  #1:  ((&abba.work)){+.+.}, at: [<b10b18cc>] process_one_work+0x210/0x9bd
[    4.450414]  #2:  (ww_class_acquire){+.+.}, at: [<b110af7b>] test_abba_work+0x24/0x167
[    4.450414]  #3:  (ww_class_mutex){+.+.}, at: [<b110af92>] test_abba_work+0x3b/0x167
[    4.450414]  #4:  (&x->wait#6){....}, at: [<b10edca2>] complete+0x1d/0x95
[    4.450414] 
[    4.450414] stack backtrace:
[    4.450414] CPU: 0 PID: 15 Comm: kworker/0:1 Not tainted 4.14.0-rc6-00050-g2dcd5ad #1
[    4.450414] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    4.450414] Workqueue: events test_abba_work
[    4.450414] Call Trace:
[    4.450414]  ? dump_stack+0x1a0/0x240
[    4.450414]  ? print_circular_bug+0x5b7/0x5d0
[    4.450414]  ? check_prev_add+0xaf/0x448
[    4.450414]  ? lock_commit_crosslock+0x660/0x6ac
[    4.450414]  ? debug_locks_off_graph_unlock+0x35/0x35
[    4.450414]  ? complete+0x35/0x95
[    4.450414]  ? test_abba_work+0x57/0x167
[    4.450414]  ? process_one_work+0x47f/0x9bd
[    4.450414]  ? worker_thread+0x371/0x594
[    4.450414]  ? kthread+0x1f3/0x206
[    4.450414]  ? mod_delayed_work_on+0xf1/0xf1
[    4.450414]  ? list_del_init+0x63/0x63
[    4.450414]  ? ret_from_fork+0x19/0x30
[    4.981673] tsc: Refined TSC clocksource calibration: 2925.995 MHz
[    4.982283] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2a2d2f8b9e6, max_idle_ns: 440795279943 ns
[   10.597299] rcu-perf:--- Start of test: nreaders=0 nwriters=1 verbose=1 shutdown=1
[   10.598377] rcu-torture: Creating rcu_perf_shutdown task
[   10.613441] rcu-torture: Creating rcu_perf_writer task
[   10.623613] rcu-perf: rcu_perf_writer task started
[   11.164456] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[   11.165375] zbud: loaded
[   11.165887] ntfs: driver 2.1.32 [Flags: R/O DEBUG].
[   11.225456] gfs2: GFS2 installed
[   11.234806] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[   11.235535] io scheduler noop registered (default)
[   11.235973] io scheduler mq-deadline registered
[   11.236405] io scheduler bfq registered
[   11.236895] test_string_helpers: Running tests...
[   11.239779] test_hexdump: all 1184 tests passed
[   11.241140] Running rhashtable test nelem=8, max_size=0, shrinking=0
[   11.241711] Test 00:
[   11.243264]   Adding 50000 keys
[   11.334260]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.371625]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.372425]   Deleting 50000 keys
[   11.406616]   Duration of test: 163013033 ns
[   11.407092] Test 01:
[   11.408537]   Adding 50000 keys
[   11.494364]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.531287]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.532056]   Deleting 50000 keys
[   11.565426]   Duration of test: 156545791 ns
[   11.565846] Test 02:
[   11.567297]   Adding 50000 keys
[   11.665042]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.709338]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.710154]   Deleting 50000 keys
[   11.745793]   Duration of test: 178161100 ns
[   11.746264] Test 03:
[   11.747803]   Adding 50000 keys
[   11.844865]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.885471]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.886281]   Deleting 50000 keys
[   11.922990]   Duration of test: 174441753 ns
[   11.923715] Average test time: 168040419
[   11.924277] Testing concurrent rhashtable access from 10 threads
[   15.856693] Started 10 threads, 0 failed
[   15.990885] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 1521
[   16.000632] test_bitmap: all 460506 tests passed
[   16.002044] xz_dec_test: module loaded
[   16.002589] xz_dec_test: Create a device node with 'mknod xz_dec_test c 252 0' and write .xz files to it.
[   16.005554] switchtec: loaded.
[   16.006801] intel_idle: does not run on family 6 model 6
[   16.400877] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   16.425983] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   16.428963] console [ttyS0] disabled
[    0.000000] Linux version 4.14.0-rc6-00050-g2dcd5ad (kbuild@lkp-hsx03) (gcc version 5.4.1 20160904 (Debian 5.4.1-2)) #1 SMP PREEMPT Sat Oct 28 11:11:05 CST 2017
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    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-0x000000000ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000000ffe0000-0x000000000fffffff] 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] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] random: fast init done
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0xffe0 max_arch_pfn = 0x100000
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] initial memory mapped: [mem 0x00000000-0x043fffff]
[    0.000000] Base memory trampoline at [b009b000] 9b000 size 16384
[    0.000000] BRK [0x0410d000, 0x0410dfff] PGTABLE
[    0.000000] BRK [0x0410e000, 0x0410efff] PGTABLE
[    0.000000] BRK [0x0410f000, 0x0410ffff] PGTABLE
[    0.000000] BRK [0x04110000, 0x04110fff] PGTABLE
[    0.000000] BRK [0x04111000, 0x04111fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x0f3cd000-0x0ffdffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68D0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000000FFE15CE 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000000FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000000FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000000FFE0000 000040
[    0.000000] ACPI: APIC 0x000000000FFE151E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000000FFE1596 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    0.000000] 255MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 0ffe0000
[    0.000000]   low ram: 0 - 0ffe0000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:f3cc001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 1374810119 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-0x000000000ffdffff]
[    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-0x000000000ffdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000000ffdffff]
[    0.000000] On node 0 totalpages: 65406
[    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: 480 pages used for memmap
[    0.000000]   Normal zone: 61408 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 1 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffb000 (fec00000)
[    0.000000] e820: [mem 0x10000000-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: 19112604462750000 ns
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] percpu: Embedded 341 pages/cpu @bf074000 s1366048 r0 d30688 u1396736
[    0.000000] pcpu-alloc: s1366048 r0 d30688 u1396736 alloc=341*4096
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr f076b00
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64894
[    0.000000] Kernel command line: ip=::::vm-lkp-nhm-dp1-yocto-i386-9::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-nhm-dp1-yocto-i386-9/boot-1-yocto-tiny-i386-2016-04-22.cgz-2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b-20171028-21522-vhp700-0.yaml ARCH=i386 kconfig=i386-randconfig-h1-10271316 branch=linux-devel/devel-spot-201710271052 commit=2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b BOOT_IMAGE=/pkg/linux/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/vmlinuz-4.14.0-rc6-00050-g2dcd5ad max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-nhm-dp1-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/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 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Memory: 195144K/261624K available (12015K kernel code, 2742K rwdata, 5052K rodata, 2368K init, 27860K bss, 66480K reserved, 0K cma-reserved)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff0f000 - 0xfffff000   ( 960 kB)
[    0.000000]     vmalloc : 0xc07e0000 - 0xfff0d000   (1015 MB)
[    0.000000]     lowmem  : 0xb0000000 - 0xbffe0000   ( 255 MB)
[    0.000000]       .init : 0xb2371000 - 0xb25c1000   (2368 kB)
[    0.000000]       .data : 0xb1bbbfd6 - 0xb235b9c0   (7806 kB)
[    0.000000]       .text : 0xb1000000 - 0xb1bbbfd6   (12015 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.010000] Running RCU self tests
[    0.010000] Preemptible hierarchical RCU implementation.
[    0.010000] 	RCU event tracing is enabled.
[    0.010000] 	RCU lockdep checking is enabled.
[    0.010000] 	RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=1.
[    0.010000] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    0.010000] 	Tasks RCU enabled.
[    0.010000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.010000] NR_IRQS: 2304, nr_irqs: 256, preallocated irqs: 16
[    0.010000] CPU 0 irqstacks, hard=be818000 soft=be81a000
[    0.010000] 	Offload RCU callbacks from CPUs: .
[    0.010000] console [ttyS0] enabled
[    0.010000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.010000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.010000] ... MAX_LOCK_DEPTH:          48
[    0.010000] ... MAX_LOCKDEP_KEYS:        8191
[    0.010000] ... CLASSHASH_SIZE:          4096
[    0.010000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.010000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.010000] ... CHAINHASH_SIZE:          32768
[    0.010000]  memory used by lock dependency info: 4655 kB
[    0.010000]  per task-struct memory footprint: 2304 bytes
[    0.010000] ------------------------
[    0.010000] | Locking API testsuite:
[    0.010000] ----------------------------------------------------------------------------
[    0.010000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]               recursive read-lock:             |  ok  |             |  ok  |
[    0.010000]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.010000]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.010000]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.010000]   mixed read-lock/lock-write ABBA:             |FAILED|             |  ok  |
[    0.010000]    mixed read-lock/lock-read ABBA:             |  ok  |             |  ok  |
[    0.010000]  mixed write-lock/lock-write ABBA:             |  ok  |             |  ok  |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.010000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.010000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.010000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.010000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq read-recursion/123:  ok  |
[    0.010000]       soft-irq read-recursion/123:  ok  |
[    0.010000]       hard-irq read-recursion/132:  ok  |
[    0.010000]       soft-irq read-recursion/132:  ok  |
[    0.010000]       hard-irq read-recursion/213:  ok  |
[    0.010000]       soft-irq read-recursion/213:  ok  |
[    0.010000]       hard-irq read-recursion/231:  ok  |
[    0.010000]       soft-irq read-recursion/231:  ok  |
[    0.010000]       hard-irq read-recursion/312:  ok  |
[    0.010000]       soft-irq read-recursion/312:  ok  |
[    0.010000]       hard-irq read-recursion/321:  ok  |
[    0.010000]       soft-irq read-recursion/321:  ok  |
[    0.010000]   --------------------------------------------------------------------------
[    0.010000]   | Wound/wait tests |
[    0.010000]   ---------------------
[    0.010000]                   ww api failures:  ok  |  ok  |  ok  |
[    0.010000]                ww contexts mixing:  ok  |  ok  |
[    0.010000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    0.010000]                locking mismatches:  ok  |  ok  |  ok  |
[    0.010000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]            spinlock nest unlocked:  ok  |
[    0.010000]   -----------------------------------------------------
[    0.010000]                                  |block | try  |context|
[    0.010000]   -----------------------------------------------------
[    0.010000]                           context:  ok  |  ok  |  ok  |
[    0.010000]                               try:  ok  |  ok  |  ok  |
[    0.010000]                             block:  ok  |  ok  |  ok  |
[    0.010000]                          spinlock:  ok  |  ok  |  ok  |
[    0.010000] -------------------------------------------------------
[    0.010000] Good, all 261 testcases passed! |
[    0.010000] ---------------------------------
[    0.010000] allocated 262144 bytes of page_ext
[    0.010000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.010000] hpet clockevent registered
[    0.010077] tsc: Detected 2925.998 MHz processor
[    0.010809] Calibrating delay loop (skipped) preset value.. 5851.99 BogoMIPS (lpj=29259980)
[    0.011334] pid_max: default: 32768 minimum: 301
[    0.012164] ACPI: Core revision 20170728
[    0.038554] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.039870] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.040037] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.043455] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.044265] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.050662] debug: unmapping init [mem 0xb25c1000-0xb25c5fff]
[    0.052777] smpboot: Max logical packages: 1
[    0.053238] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.053791] enabled ExtINT on CPU#0
[    0.055285] ENABLING IO-APIC IRQs
[    0.055667] init IO_APIC IRQs
[    0.055959]  apic 0 pin 0 not connected
[    0.056521] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.057420] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.058235] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.059006] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.059783] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.060000]  apic 0 pin 16 not connected
[    0.060000]  apic 0 pin 17 not connected
[    0.060000]  apic 0 pin 18 not connected
[    0.060000]  apic 0 pin 19 not connected
[    0.060000]  apic 0 pin 20 not connected
[    0.060000]  apic 0 pin 21 not connected
[    0.060000]  apic 0 pin 22 not connected
[    0.060000]  apic 0 pin 23 not connected
[    0.060000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.060000] Using local APIC timer interrupts.
[    0.060000] calibrating APIC timer ...
[    0.060000] ... lapic delta = 6263145
[    0.060000] ... PM-Timer delta = 359034
[    0.060000] ... PM-Timer result ok
[    0.060000] ..... delta 6263145
[    0.060000] ..... mult: 269000029
[    0.060000] ..... calibration result: 10021032
[    0.060000] ..... CPU clock speed is 2936.9724 MHz.
[    0.060000] ..... host bus clock speed is 1002.1032 MHz.
[    0.060107] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.100152] Performance Events: PMU not available due to virtualization, using software events only.
[    0.112174] Hierarchical SRCU implementation.
[    0.170073] smp: Bringing up secondary CPUs ...
[    0.170720] smp: Brought up 1 node, 1 CPU
[    0.171136] smpboot: Total of 1 processors activated (5851.99 BogoMIPS)
[    0.173698] devtmpfs: initialized
[    0.175764] gcov: version magic: 0x3530342a
[    0.179459] workqueue: round-robin CPU selection forced, expect performance impact
[    0.190331] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.191766] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.192696] xor: measuring software checksum speed
[    0.290039]    pIII_sse  : 11509.200 MB/sec
[    0.390015]    prefetch64-sse: 14545.200 MB/sec
[    0.390458] xor: using function: prefetch64-sse (14545.200 MB/sec)
[    0.391043] pinctrl core: initialized pinctrl subsystem
[    0.392597] regulator-dummy: no parameters
[    0.393542] regulator-dummy: no parameters
[    0.394069] regulator-dummy: Failed to create debugfs directory
[    0.395419] NET: Registered protocol family 16
[    0.397899] cpuidle: using governor ladder
[    0.398771] ACPI: bus type PCI registered
[    0.399649] PCI: Using configuration type 1 for base access
[    0.620165] raid6: mmxx1    gen()   563 MB/s
[    0.790113] raid6: mmxx2    gen()   777 MB/s
[    0.960049] raid6: sse1x1   gen()   652 MB/s
[    1.130215] raid6: sse1x2   gen()   661 MB/s
[    1.300053] raid6: sse2x1   gen()  1508 MB/s
[    1.470041] raid6: sse2x1   xor()   939 MB/s
[    1.640034] raid6: sse2x2   gen()  1583 MB/s
[    1.810032] raid6: sse2x2   xor()  1027 MB/s
[    1.810541] raid6: using algorithm sse2x2 gen() 1583 MB/s
[    1.811035] raid6: .... xor() 1027 MB/s, rmw enabled
[    1.811481] raid6: using intx1 recovery algorithm
[    1.812571] ACPI: Added _OSI(Module Device)
[    1.813091] ACPI: Added _OSI(Processor Device)
[    1.813577] ACPI: Added _OSI(3.0 _SCP Extensions)
[    1.814154] ACPI: Added _OSI(Processor Aggregator Device)
[    1.828007] ACPI: Interpreter enabled
[    1.828398] ACPI: (supports S0 S5)
[    1.828766] ACPI: Using IOAPIC for interrupt routing
[    1.829666] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    1.873320] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    1.873949] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments]
[    1.874687] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    1.876200] PCI host bridge to bus 0000:00
[    1.876594] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    1.877215] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    1.877833] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    1.878504] pci_bus 0000:00: root bus resource [mem 0x10000000-0xfebfffff window]
[    1.879182] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.879805] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    1.882477] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    1.884888] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    1.888837] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    1.891311] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    1.891964] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    1.892546] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    1.893197] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    1.895039] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    1.896017] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    1.897261] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    1.900945] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    1.904726] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    1.909902] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    1.918785] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    1.922357] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    1.924758] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    1.927099] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    1.935152] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    1.937378] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[    1.939075] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[    1.944844] pci_bus 0000:00: on NUMA node 0
[    1.950081] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    1.951469] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    1.952792] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    1.954103] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    1.954989] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    1.970212] PCI: Using ACPI for IRQ routing
[    1.970714] PCI: pci_cache_line_size set to 32 bytes
[    1.971293] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.971934] e820: reserve RAM buffer [mem 0x0ffe0000-0x0fffffff]
[    1.974869] clocksource: Switched to clocksource kvm-clock
[    2.099602] VFS: Disk quotas dquot_6.6.0
[    2.100053] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.101097] FS-Cache: Loaded
[    2.101587] pnp: PnP ACPI init
[    2.102367] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    2.103268] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    2.104150] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    2.104830] pnp 00:03: [dma 2]
[    2.105464] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    2.106492] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    2.107608] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    2.108564] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    2.111358] pnp: PnP ACPI: found 7 devices
[    2.148958] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    2.150231] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    2.150799] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    2.151361] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    2.151979] pci_bus 0000:00: resource 7 [mem 0x10000000-0xfebfffff window]
[    2.153004] NET: Registered protocol family 1
[    2.153430] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    2.153984] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    2.154526] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    2.155140] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    2.155955] PCI: CLS 0 bytes, default 32
[    2.156977] Unpacking initramfs...
[    3.886205] debug: unmapping init [mem 0xbf3cd000-0xbffdffff]
[    3.890173] Scanning for low memory corruption every 60 seconds
[    4.440631] 
[    4.442100] ======================================================
[    4.442653] WARNING: possible circular locking dependency detected
[    4.443206] 4.14.0-rc6-00050-g2dcd5ad #1 Not tainted
[    4.443648] ------------------------------------------------------
[    4.444200] kworker/0:1/15 is trying to acquire lock:
[    4.444661]  (ww_class_mutex){+.+.}, at: [<b110af92>] test_abba_work+0x3b/0x167
[    4.445354] 
[    4.445354] but now in release context of a crosslock acquired at the following:
[    4.446160]  ((completion)&abba.b_ready){+.+.}, at: [<b1baf438>] wait_for_common+0x1b/0x2b
[    4.446899] 
[    4.446899] which lock already depends on the new lock.
[    4.446899] 
[    4.447622] 
[    4.447622] the existing dependency chain (in reverse order) is:
[    4.448297] 
[    4.448297] -> #1 ((completion)&abba.b_ready){+.+.}:
[    4.448912]        save_stack_trace+0x1e/0x2e
[    4.449336]        __lock_acquire+0x1554/0x1ea3
[    4.449797]        save_trace+0x0/0xfc
[    4.450156]        lock_acquire+0x25f/0x368
[    4.450414]        wait_for_common+0x1b/0x2b
[    4.450414]        __wait_for_common+0x72/0x44b
[    4.450414]        wait_for_common+0x1b/0x2b
[    4.450414]        mark_held_locks+0x98/0xcb
[    4.450414]        schedule_timeout+0x0/0x129
[    4.450414]        trace_hardirqs_on_caller+0x353/0x3ac
[    4.450414]        wait_for_common+0x1b/0x2b
[    4.450414]        wait_for_completion+0x1d/0x2c
[    4.450414]        test_abba+0x180/0x351
[    4.450414]        test_abba_work+0x0/0x167
[    4.450414]        test_abba+0x14c/0x351
[    4.450414]        complete+0x1d/0x95
[    4.450414]        ww_acquire_fini+0x20/0xca
[    4.450414]        test_aa+0x161/0x17a
[    4.450414]        test_aa+0x4d/0x17a
[    4.450414]        test_ww_mutex_init+0x17f/0x694
[    4.450414]        _raw_spin_unlock_irqrestore+0xbb/0x13e
[    4.450414]        add_device_randomness+0x29d/0x368
[    4.450414]        test_ww_mutex_init+0x0/0x694
[    4.450414]        do_one_initcall+0x108/0x279
[    4.450414]        do_early_param+0xee/0x1a1
[    4.450414]        kernel_init_freeable+0x2b6/0x494
[    4.450414]        kernel_init_freeable+0x2b6/0x494
[    4.450414]        kernel_init_freeable+0x2ed/0x494
[    4.450414]        kernel_init+0x0/0x231
[    4.450414]        kernel_init+0x13/0x231
[    4.450414]        ret_from_fork+0x19/0x30
[    4.450414] 
[    4.450414] -> #0 (ww_class_mutex){+.+.}:
[    4.450414]        test_abba_work+0x3b/0x167
[    4.450414] 
[    4.450414] other info that might help us debug this:
[    4.450414] 
[    4.450414]  Possible unsafe locking scenario by crosslock:
[    4.450414] 
[    4.450414]        CPU0                    CPU1
[    4.450414]        ----                    ----
[    4.450414]   lock(ww_class_mutex);
[    4.450414]   lock((completion)&abba.b_ready);
[    4.450414]                                lock(ww_class_mutex);
[    4.450414]                                unlock((completion)&abba.b_ready);
[    4.450414] 
[    4.450414]  *** DEADLOCK ***
[    4.450414] 
[    4.450414] 5 locks held by kworker/0:1/15:
[    4.450414]  #0:  ("events"){+.+.}, at: [<b10b18cc>] process_one_work+0x210/0x9bd
[    4.450414]  #1:  ((&abba.work)){+.+.}, at: [<b10b18cc>] process_one_work+0x210/0x9bd
[    4.450414]  #2:  (ww_class_acquire){+.+.}, at: [<b110af7b>] test_abba_work+0x24/0x167
[    4.450414]  #3:  (ww_class_mutex){+.+.}, at: [<b110af92>] test_abba_work+0x3b/0x167
[    4.450414]  #4:  (&x->wait#6){....}, at: [<b10edca2>] complete+0x1d/0x95
[    4.450414] 
[    4.450414] stack backtrace:
[    4.450414] CPU: 0 PID: 15 Comm: kworker/0:1 Not tainted 4.14.0-rc6-00050-g2dcd5ad #1
[    4.450414] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    4.450414] Workqueue: events test_abba_work
[    4.450414] Call Trace:
[    4.450414]  ? dump_stack+0x1a0/0x240
[    4.450414]  ? print_circular_bug+0x5b7/0x5d0
[    4.450414]  ? check_prev_add+0xaf/0x448
[    4.450414]  ? lock_commit_crosslock+0x660/0x6ac
[    4.450414]  ? debug_locks_off_graph_unlock+0x35/0x35
[    4.450414]  ? complete+0x35/0x95
[    4.450414]  ? test_abba_work+0x57/0x167
[    4.450414]  ? process_one_work+0x47f/0x9bd
[    4.450414]  ? worker_thread+0x371/0x594
[    4.450414]  ? kthread+0x1f3/0x206
[    4.450414]  ? mod_delayed_work_on+0xf1/0xf1
[    4.450414]  ? list_del_init+0x63/0x63
[    4.450414]  ? ret_from_fork+0x19/0x30
[    4.981673] tsc: Refined TSC clocksource calibration: 2925.995 MHz
[    4.982283] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2a2d2f8b9e6, max_idle_ns: 440795279943 ns
[   10.597299] rcu-perf:--- Start of test: nreaders=0 nwriters=1 verbose=1 shutdown=1
[   10.598377] rcu-torture: Creating rcu_perf_shutdown task
[   10.613441] rcu-torture: Creating rcu_perf_writer task
[   10.623613] rcu-perf: rcu_perf_writer task started
[   11.164456] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[   11.165375] zbud: loaded
[   11.165887] ntfs: driver 2.1.32 [Flags: R/O DEBUG].
[   11.225456] gfs2: GFS2 installed
[   11.234806] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[   11.235535] io scheduler noop registered (default)
[   11.235973] io scheduler mq-deadline registered
[   11.236405] io scheduler bfq registered
[   11.236895] test_string_helpers: Running tests...
[   11.239779] test_hexdump: all 1184 tests passed
[   11.241140] Running rhashtable test nelem=8, max_size=0, shrinking=0
[   11.241711] Test 00:
[   11.243264]   Adding 50000 keys
[   11.334260]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.371625]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.372425]   Deleting 50000 keys
[   11.406616]   Duration of test: 163013033 ns
[   11.407092] Test 01:
[   11.408537]   Adding 50000 keys
[   11.494364]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.531287]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.532056]   Deleting 50000 keys
[   11.565426]   Duration of test: 156545791 ns
[   11.565846] Test 02:
[   11.567297]   Adding 50000 keys
[   11.665042]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.709338]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.710154]   Deleting 50000 keys
[   11.745793]   Duration of test: 178161100 ns
[   11.746264] Test 03:
[   11.747803]   Adding 50000 keys
[   11.844865]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.885471]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.886281]   Deleting 50000 keys
[   11.922990]   Duration of test: 174441753 ns
[   11.923715] Average test time: 168040419
[   11.924277] Testing concurrent rhashtable access from 10 threads
[   15.856693] Started 10 threads, 0 failed
[   15.990885] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 1521
[   16.000632] test_bitmap: all 460506 tests passed
[   16.002044] xz_dec_test: module loaded
[   16.002589] xz_dec_test: Create a device node with 'mknod xz_dec_test c 252 0' and write .xz files to it.
[   16.005554] switchtec: loaded.
[   16.006801] intel_idle: does not run on family 6 model 6
[   16.400877] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   16.425983] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   16.428963] console [ttyS0] disabled
[   16.455442] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   16.802634] console [ttyS0] enabled
[   16.832059] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   16.861391] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   16.878337] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $
[   16.879361] ac.o: No PCI boards found.
[   16.879889] ac.o: For an ISA board you must supply memory and irq parameters.
[   16.882572] platform pc8736x_gpio.0: NatSemi pc8736x GPIO Driver Initializing
[   16.883586] platform pc8736x_gpio.0: no device found
[   16.884361] nsc_gpio initializing
[   16.884971] parport_pc 00:04: reported by Plug and Play ACPI
[   16.885979] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[   16.967052] parport_pc 00:04: reported by Plug and Play ACPI
[   16.968035] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE,EPP]
[   17.053947] lkdtm: No crash points registered, enable through debugfs
[   17.054604] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti
[   17.209027] parport0: AVR Butterfly
[   17.249384] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   17.252466] serio: i8042 KBD port at 0x60,0x64 irq 1
[   17.254165] serio: i8042 AUX port at 0x60,0x64 irq 12
[   17.255911] parport0: cannot grant exclusive access for device parkbd
[   17.257925] hv_vmbus: registering driver hyperv_keyboard
[   17.260157] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[   17.261974] Driver for 1-wire Dallas network protocol.
[   17.263844] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko
[   17.265599] intel_powerclamp: CPU does not support MWAIT
[   17.265874] ledtrig-cpu: registered to indicate activity on CPUs
[   17.270077] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[   17.271144] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[   17.281738] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   17.329274] FPGA DOWNLOAD --->
[   17.329689] FPGA image file name: xlinx_fpga_firmware.bit
[   17.330266] GPIO INIT FAIL!!
[   17.330607] mostcore: init()
[   17.332370] aim_cdev: init()
[   17.332686] mostcore: registered new application interfacing module cdev
[   17.333991] greybus: registered new driver bootrom
[   17.335741] greybus: registered new driver vibrator
[   17.336238] acerhdf: Acer Aspire One Fan driver, v.0.7.0
[   17.336728] acerhdf: unknown (unsupported) BIOS version QEMU/Standard PC (i440FX + PIIX, 1996)/1.10.2-1, please report, aborting!
[   17.337914] hv_vmbus: registering driver hv_balloon
[   17.339293] intel_rapl: driver does not support CPU family 6 model 6
[   17.339886] oprofile: using NMI interrupt.
[   17.340999] ... APIC ID:      00000000 (0)
[   17.341401] ... APIC VERSION: 01050014
[   17.341931] 0000000000000000000000000000000000000000000000000000000000000000
[   17.342907] 0000000000000000000000000000000000000000000000000000000000000000
[   17.343979] 0000000000000000000000000000000000000000000000000000000000000000
[   17.344892] number of MP IRQ sources: 15.
[   17.345255] number of IO-APIC #0 registers: 24.
[   17.345660] testing the IO APIC.......................
[   17.346135] IO APIC #0......
[   17.346400] .... register #00: 00000000
[   17.346747] .......    : physical APIC id: 00
[   17.347176] .......    : Delivery Type: 0
[   17.347589] .......    : LTS          : 0
[   17.348000] .... register #01: 00170011
[   17.348346] .......     : max redirection entries: 17
[   17.348795] .......     : PRQ implemented: 0
[   17.349182] .......     : IO APIC version: 11
[   17.349733] .... register #02: 00000000
[   17.350294] .......     : arbitration: 00
[   17.350783] .... IRQ redirection table:
[   17.351138] IOAPIC 0:
[   17.351360]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.352064]  pin01, enabled , edge , high, V(31), IRR(0), S(0), logical , D(01), M(1)
[   17.352760]  pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(1)
[   17.353558]  pin03, enabled , edge , high, V(33), IRR(0), S(0), logical , D(01), M(1)
[   17.354265]  pin04, enabled , edge , high, V(34), IRR(0), S(0), logical , D(01), M(1)
[   17.354968]  pin05, disabled, level, high, V(35), IRR(0), S(0), logical , D(01), M(1)
[   17.355664]  pin06, enabled , edge , high, V(36), IRR(0), S(0), logical , D(01), M(1)
[   17.356368]  pin07, enabled , edge , high, V(37), IRR(0), S(0), logical , D(01), M(1)
[   17.357100]  pin08, enabled , edge , high, V(38), IRR(0), S(0), logical , D(01), M(1)
[   17.358283]  pin09, disabled, level, high, V(39), IRR(0), S(0), logical , D(01), M(1)
[   17.359114]  pin0a, disabled, level, high, V(3A), IRR(0), S(0), logical , D(01), M(1)
[   17.359814]  pin0b, disabled, level, high, V(3B), IRR(0), S(0), logical , D(01), M(1)
[   17.360516]  pin0c, enabled , edge , high, V(3C), IRR(0), S(0), logical , D(01), M(1)
[   17.361224]  pin0d, enabled , edge , high, V(3D), IRR(0), S(0), logical , D(01), M(1)
[   17.362015]  pin0e, enabled , edge , high, V(3E), IRR(0), S(0), logical , D(01), M(1)
[   17.362718]  pin0f, enabled , edge , high, V(3F), IRR(0), S(0), logical , D(01), M(1)
[   17.363423]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.364123]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.364814]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.365653]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.366700]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.367841]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.368548]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.369257]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   17.369954] IRQ to pin mappings:
[   17.370252] IRQ0 -> 0:2
[   17.370492] IRQ1 -> 0:1
[   17.370734] IRQ3 -> 0:3
[   17.370981] IRQ4 -> 0:4
[   17.371222] IRQ5 -> 0:5
[   17.371465] IRQ6 -> 0:6
[   17.371740] IRQ7 -> 0:7
[   17.371976] IRQ8 -> 0:8
[   17.372206] IRQ9 -> 0:9
[   17.372435] IRQ10 -> 0:10
[   17.372679] IRQ11 -> 0:11
[   17.372928] IRQ12 -> 0:12
[   17.373172] IRQ13 -> 0:13
[   17.373517] IRQ14 -> 0:14
[   17.373905] IRQ15 -> 0:15
[   17.374285] .................................... done.
[   17.375060] Using IPI No-Shortcut mode
[   17.375695] sched_clock: Marking stable (17367767533, 0)->(17962038632, -594271099)
[   17.381044] page_owner is disabled
[   17.384646] Btrfs loaded, crc32c=crc32c-generic, debug=on, assert=on
[   17.386960] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[   17.387518] EDD information not available.
[   17.390908] debug: unmapping init [mem 0xb2371000-0xb25c0fff]
[   17.392337] Write protecting the kernel text: 12016k
[   17.393255] Write protecting the kernel read-only data: 5064k
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-nhm-dp1-yocto-i386-9, MAC , kernel  , serial console /dev/ttyS0
[   18.500034] Kernel tests: Boot OK!
[   18.500034] LKP: waiting for network...
[   23.119661] rcu-perf: rcu_perf_writer 0 has 100 measurements
[   23.229890] Dumping ftrace buffer:
[   23.230510]    (ftrace buffer empty)
[   23.231124] rcu-perf: Test complete
[   23.231743] rcu-torture: Stopping rcu_perf_writer task
[   23.232586] rcu-torture: Stopping rcu_perf_writer
[   23.233336] rcu-perf: writer 0 gps: 100
[   23.233952] rcu-perf: start: 21085399071 end: 23119633853 duration: 2034234782 gps: 100 batches: 202
[   23.235415] rcu-perf:    0 writer-duration:     0 30049240
[   23.250020] rcu-perf:    0 writer-duration:     1 20102636
[   23.250966] rcu-perf:    0 writer-duration:     2 20020988
[   23.251928] rcu-perf:    0 writer-duration:     3 20024122
[   23.252928] rcu-perf:    0 writer-duration:     4 19986244
[   23.253839] rcu-perf:    0 writer-duration:     5 20073038
[   23.254674] rcu-perf:    0 writer-duration:     6 20042096
[   23.255526] rcu-perf:    0 writer-duration:     7 20052661
[   23.256410] rcu-perf:    0 writer-duration:     8 20007732
[   23.257356] rcu-perf:    0 writer-duration:     9 20097162
[   23.258295] rcu-perf:    0 writer-duration:    10 19993089
[   23.259165] rcu-perf:    0 writer-duration:    11 20033549
[   23.260145] rcu-perf:    0 writer-duration:    12 20016198
[   23.261101] rcu-perf:    0 writer-duration:    13 20069422
[   23.262014] rcu-perf:    0 writer-duration:    14 20017718
[   23.262975] rcu-perf:    0 writer-duration:    15 20037367
[   23.263878] rcu-perf:    0 writer-duration:    16 20040477
[   23.264749] rcu-perf:    0 writer-duration:    17 20075933
[   23.265740] rcu-perf:    0 writer-duration:    18 20022841
[   23.266730] rcu-perf:    0 writer-duration:    19 20044377
[   23.267689] rcu-perf:    0 writer-duration:    20 20047213
[   23.268734] rcu-perf:    0 writer-duration:    21 20037109
[   23.269730] rcu-perf:    0 writer-duration:    22 20036490
[   23.270720] rcu-perf:    0 writer-duration:    23 20060272
[   23.271658] rcu-perf:    0 writer-duration:    24 20046067
[   23.272626] rcu-perf:    0 writer-duration:    25 20042232
[   23.273616] rcu-perf:    0 writer-duration:    26 20037079
[   23.274541] rcu-perf:    0 writer-duration:    27 20044740
[   23.275449] rcu-perf:    0 writer-duration:    28 20033258
[   23.276333] rcu-perf:    0 writer-duration:    29 20043039
[   23.277191] rcu-perf:    0 writer-duration:    30 20046921
[   23.278084] rcu-perf:    0 writer-duration:    31 20041586
[   23.279005] rcu-perf:    0 writer-duration:    32 20016654
[   23.280013] rcu-perf:    0 writer-duration:    33 20088395
[   23.280945] rcu-perf:    0 writer-duration:    34 19996486
[   23.281870] rcu-perf:    0 writer-duration:    35 20030555
[   23.282725] rcu-perf:    0 writer-duration:    36 20070261
[   23.283607] rcu-perf:    0 writer-duration:    37 20038901
[   23.285832] rcu-perf:    0 writer-duration:    38 20054947
[   23.286721] rcu-perf:    0 writer-duration:    39 20029999
[   23.287619] rcu-perf:    0 writer-duration:    40 20039203
[   23.288489] rcu-perf:    0 writer-duration:    41 20043855
[   23.289380] rcu-perf:    0 writer-duration:    42 20041042
[   23.290333] rcu-perf:    0 writer-duration:    43 20038216
[   23.291256] rcu-perf:    0 writer-duration:    44 20015188
[   23.292190] rcu-perf:    0 writer-duration:    45 20068262
[   23.293083] rcu-perf:    0 writer-duration:    46 20074624
[   23.293939] rcu-perf:    0 writer-duration:    47 20011541
[   23.294810] rcu-perf:    0 writer-duration:    48 20042346
[   23.295732] rcu-perf:    0 writer-duration:    49 20045464
[   23.296655] rcu-perf:    0 writer-duration:    50 20066223
[   23.297558] rcu-perf:    0 writer-duration:    51 20070693
[   23.298428] rcu-perf:    0 writer-duration:    52 19982524
[   23.299284] rcu-perf:    0 writer-duration:    53 20094729
[   23.300214] rcu-perf:    0 writer-duration:    54 20009719
[   23.301138] rcu-perf:    0 writer-duration:    55 20024002
[   23.302057] rcu-perf:    0 writer-duration:    56 20014086
[   23.302938] rcu-perf:    0 writer-duration:    57 20101222
[   23.303873] rcu-perf:    0 writer-duration:    58 19978755
[   23.304755] rcu-perf:    0 writer-duration:    59 20098386
[   23.305607] rcu-perf:    0 writer-duration:    60 20001991
[   23.306457] rcu-perf:    0 writer-duration:    61 20037744
[   23.307340] rcu-perf:    0 writer-duration:    62 20014371
[   23.308260] rcu-perf:    0 writer-duration:    63 20039116
[   23.309164] rcu-perf:    0 writer-duration:    64 20041134
[   23.310056] rcu-perf:    0 writer-duration:    65 20041298
[   23.310910] rcu-perf:    0 writer-duration:    66 20039753
[   23.311780] rcu-perf:    0 writer-duration:    67 20044398
[   23.312684] rcu-perf:    0 writer-duration:    68 20038000
[   23.313607] rcu-perf:    0 writer-duration:    69 20046724
[   23.314532] rcu-perf:    0 writer-duration:    70 20035642
[   23.315411] rcu-perf:    0 writer-duration:    71 20160736
[   23.316278] rcu-perf:    0 writer-duration:    72 19980545
[   23.317165] rcu-perf:    0 writer-duration:    73 20023919
[   23.318093] rcu-perf:    0 writer-duration:    74 20057298
[   23.319020] rcu-perf:    0 writer-duration:    75 20039789
[   23.319926] rcu-perf:    0 writer-duration:    76 20017383
[   23.320813] rcu-perf:    0 writer-duration:    77 20044221
[   23.321682] rcu-perf:    0 writer-duration:    78 20049907
[   23.322583] rcu-perf:    0 writer-duration:    79 20044207
[   23.323505] rcu-perf:    0 writer-duration:    80 20004778
[   23.324443] rcu-perf:    0 writer-duration:    81 20041482
[   23.325325] rcu-perf:    0 writer-duration:    82 20038929
[   23.326172] rcu-perf:    0 writer-duration:    83 20059418
[   23.327058] rcu-perf:    0 writer-duration:    84 20047526
[   23.327991] rcu-perf:    0 writer-duration:    85 20037312
[   23.328919] rcu-perf:    0 writer-duration:    86 20042887
[   23.329811] rcu-perf:    0 writer-duration:    87 20037868
[   23.330713] rcu-perf:    0 writer-duration:    88 20074782
[   23.331594] rcu-perf:    0 writer-duration:    89 20017828
[   23.332498] rcu-perf:    0 writer-duration:    90 20039897
[   23.333418] rcu-perf:    0 writer-duration:    91 20038749
[   23.334352] rcu-perf:    0 writer-duration:    92 20006103
[   23.335230] rcu-perf:    0 writer-duration:    93 20067757
[   23.336094] rcu-perf:    0 writer-duration:    94 20039337
[   23.336979] rcu-perf:    0 writer-duration:    95 20018733
[   23.337889] rcu-perf:    0 writer-duration:    96 20074738
[   23.338799] rcu-perf:    0 writer-duration:    97 20052219
[   23.339727] rcu-perf:    0 writer-duration:    98 20033224
[   23.340672] rcu-perf:    0 writer-duration:    99 20033840
[   23.341559] rcu-perf:    0 writer-duration:   100 20019731
[   23.361723] ACPI: Preparing to enter system sleep state S5
[   23.362646] reboot: Power down
[   23.364273] acpi_power_off called

Elapsed time: 1550
BUG: kernel hang in test stage

initrds=(
	/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
	/lkp/scheduled/vm-lkp-nhm-dp1-yocto-i386-9/boot-1-yocto-tiny-i386-2016-04-22.cgz-2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b-20171028-21522-vhp700-0.cgz
	/lkp/lkp/lkp-i386.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz
	/pkg/linux/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/modules.cgz
)

cat "${initrds[@]}" > /fs/sda3/initrd-vm-lkp-nhm-dp1-yocto-i386-9

kvm=(
	qemu-system-i386
	-enable-kvm
	-kernel /pkg/linux/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/vmlinuz-4.14.0-rc6-00050-g2dcd5ad
	-initrd /fs/sda3/initrd-vm-lkp-nhm-dp1-yocto-i386-9
	-m 256
	-smp 1
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-pidfile /dev/shm/kboot/pid-vm-lkp-nhm-dp1-yocto-i386-9
	-serial file:/dev/shm/kboot/vm-lkp-nhm-dp1-yocto-i386-9/serial
	-serial file:/dev/shm/kboot/vm-lkp-nhm-dp1-yocto-i386-9/kmsg
	-daemonize
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-nhm-dp1-yocto-i386-9::dhcp
	root=/dev/ram0
	user=lkp
	job=/lkp/scheduled/vm-lkp-nhm-dp1-yocto-i386-9/boot-1-yocto-tiny-i386-2016-04-22.cgz-2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b-20171028-21522-vhp700-0.yaml
	ARCH=i386
	kconfig=i386-randconfig-h1-10271316
	branch=linux-devel/devel-spot-201710271052
	commit=2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b
	BOOT_IMAGE=/pkg/linux/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/vmlinuz-4.14.0-rc6-00050-g2dcd5ad
	max_uptime=600
	RESULT_ROOT=/result/boot/1/vm-lkp-nhm-dp1-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-h1-10271316/gcc-5/2dcd5adfb7401b762ddbe4b86dcacc2f3de6b97b/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
	drbd.minor_count=8
	systemd.log_level=err
	ignore_loglevel
	console=tty0
	earlyprintk=ttyS0,115200
	console=ttyS0,115200
	vga=normal
	rw
	drbd.minor_count=8
)

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