early console in setup code
Probing EDD (edd=off to disable)... ok
[    0.000000] Linux version 4.14.0-04319-gd17a1d9 (kbuild@lkp-ib03) (gcc version 7.2.1 20171025 (Debian 7.2.0-12)) #30 PREEMPT Sun Nov 26 23:56:16 CST 2017
[    0.000000] Command line: ip=::::vm-lkp-hsw01-yocto-x86_64-16::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-16/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-d17a1d97dc208d664c91cc387ffb752c7f85dc61-20171126-3122-1uhy2p0-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-i0-201748 branch=linux-devel/devel-spot-201711261355 commit=d17a1d97dc208d664c91cc387ffb752c7f85dc61 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/vmlinuz-4.14.0-04319-gd17a1d9 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/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 earlypri
[    0.000000] KERNEL supported cpus:
[    0.000000]   AMD AuthenticAMD
[    0.000000] CPU: vendor_id 'GenuineIntel' unknown, using generic init.
[    0.000000] CPU: Your system may be unstable.
[    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-0x000000001ffddfff] usable
[    0.000000] BIOS-e820: [mem 0x000000001ffde000-0x000000001fffffff] 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] NX (Execute Disable) protection: active
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x1ffde max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: write-back
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0080000000 mask FF80000000 uncachable
[    0.000000]   1 disabled
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.000000] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.000000] found SMP MP-table at [mem 0x000f6a90-0x000f6a9f] mapped at [ffffffffff200a90]
[    0.000000]   mpc: f6aa0-f6b78
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] BRK [0x16630000, 0x16630fff] PGTABLE
[    0.000000] BRK [0x16631000, 0x16631fff] PGTABLE
[    0.000000] BRK [0x16632000, 0x16632fff] PGTABLE
[    0.000000] BRK [0x16633000, 0x16633fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x1f9ec000-0x1ffcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000001FFE15CE 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000001FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000001FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000001FFE0000 000040
[    0.000000] ACPI: APIC 0x000000001FFE151E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000001FFE1596 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.000000] kvm-clock: cpu 0, msr 0:1ffdc001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 242334047 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA32    [mem 0x0000000000001000-0x000000001ffddfff]
[    0.000000]   Normal   empty
[    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-0x000000001ffddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffddfff]
[    0.000000] On node 0 totalpages: 130940
[    0.000000]   DMA32 zone: 2048 pages used for memmap
[    0.000000]   DMA32 zone: 21 pages reserved
[    0.000000]   DMA32 zone: 130940 pages, LIFO batch:31
[    0.000000] Reserved but unavailable: 98 pages
[    0.000000] kasan: KernelAddressSanitizer initialized
[    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] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 13dae140
[    0.000000] e820: [mem 0x20000000-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] random: fast init done
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 128871
[    0.000000] Kernel command line: ip=::::vm-lkp-hsw01-yocto-x86_64-16::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-16/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-d17a1d97dc208d664c91cc387ffb752c7f85dc61-20171126-3122-1uhy2p0-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-i0-201748 branch=linux-devel/devel-spot-201711261355 commit=d17a1d97dc208d664c91cc387ffb752c7f85dc61 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/vmlinuz-4.14.0-04319-gd17a1d9 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/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 e
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Memory: 332184K/523760K available (32653K kernel code, 11960K rwdata, 12184K rodata, 1460K init, 39884K bss, 191576K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.001000] Running RCU self tests
[    0.001000] Preemptible hierarchical RCU implementation.
[    0.001000] 	RCU event tracing is enabled.
[    0.001000] 	RCU lockdep checking is enabled.
[    0.001000] 	RCU callback double-/use-after-free debug enabled.
[    0.001000] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    0.001000] 	Tasks RCU enabled.
[    0.001000] NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16
[    0.001000] console [ttyS0] enabled
[    0.001000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.001000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.001000] ... MAX_LOCK_DEPTH:          48
[    0.001000] ... MAX_LOCKDEP_KEYS:        8191
[    0.001000] ... CLASSHASH_SIZE:          4096
[    0.001000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.001000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.001000] ... CHAINHASH_SIZE:          32768
[    0.001000]  memory used by lock dependency info: 7903 kB
[    0.001000]  per task-struct memory footprint: 3072 bytes
[    0.001000] ------------------------
[    0.001000] | Locking API testsuite:
[    0.001000] ----------------------------------------------------------------------------
[    0.001000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.001000]   --------------------------------------------------------------------------
[    0.001000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]   --------------------------------------------------------------------------
[    0.001000]               recursive read-lock:             |  ok  |             |  ok  |
[    0.001000]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.001000]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.001000]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.001000]   mixed read-lock/lock-write ABBA:             |FAILED|             |  ok  |
[    0.001000]    mixed read-lock/lock-read ABBA:             |  ok  |             |  ok  |
[    0.001000]  mixed write-lock/lock-write ABBA:             |  ok  |             |  ok  |
[    0.001000]   --------------------------------------------------------------------------
[    0.001000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.001000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.001000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.001000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.001000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.001000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.001000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.001000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.001000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.001000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.001000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.001000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.001000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.001000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.001000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.001000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.001000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.001000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.001000]       hard-irq read-recursion/123:  ok  |
[    0.001000]       soft-irq read-recursion/123:  ok  |
[    0.001000]       hard-irq read-recursion/132:  ok  |
[    0.001000]       soft-irq read-recursion/132:  ok  |
[    0.001000]       hard-irq read-recursion/213:  ok  |
[    0.001000]       soft-irq read-recursion/213:  ok  |
[    0.001000]       hard-irq read-recursion/231:  ok  |
[    0.001000]       soft-irq read-recursion/231:  ok  |
[    0.001000]       hard-irq read-recursion/312:  ok  |
[    0.001000]       soft-irq read-recursion/312:  ok  |
[    0.001000]       hard-irq read-recursion/321:  ok  |
[    0.001000]       soft-irq read-recursion/321:  ok  |
[    0.001000]   --------------------------------------------------------------------------
[    0.001000]   | Wound/wait tests |
[    0.001000]   ---------------------
[    0.001000]                   ww api failures:  ok  |  ok  |  ok  |
[    0.001000]                ww contexts mixing:  ok  |  ok  |
[    0.001000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    0.001000]                locking mismatches:  ok  |  ok  |  ok  |
[    0.001000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.001000]            spinlock nest unlocked:  ok  |
[    0.001000]   -----------------------------------------------------
[    0.001000]                                  |block | try  |context|
[    0.001000]   -----------------------------------------------------
[    0.001000]                           context:  ok  |  ok  |  ok  |
[    0.001000]                               try:  ok  |  ok  |  ok  |
[    0.001000]                             block:  ok  |  ok  |  ok  |
[    0.001000]                          spinlock:  ok  |  ok  |  ok  |
[    0.001000] -------------------------------------------------------
[    0.001000] Good, all 261 testcases passed! |
[    0.001000] ---------------------------------
[    0.001000] ODEBUG: selftest passed
[    0.001000] ACPI: Core revision 20170831
[    0.001000] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.001000] hpet clockevent registered
[    0.001067] APIC: Switch to symmetric I/O mode setup
[    0.001477] enabled ExtINT on CPU#0
[    0.002528] ENABLING IO-APIC IRQs
[    0.002880] init IO_APIC IRQs
[    0.003013]  apic 0 pin 0 not connected
[    0.003574] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.004195] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.004863] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.005000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.005000]  apic 0 pin 16 not connected
[    0.005000]  apic 0 pin 17 not connected
[    0.005000]  apic 0 pin 18 not connected
[    0.005000]  apic 0 pin 19 not connected
[    0.005000]  apic 0 pin 20 not connected
[    0.005000]  apic 0 pin 21 not connected
[    0.005000]  apic 0 pin 22 not connected
[    0.005000]  apic 0 pin 23 not connected
[    0.005000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.005000] tsc: Detected 2593.992 MHz processor
[    0.005158] Calibrating delay loop (skipped) preset value.. 5187.98 BogoMIPS (lpj=2593992)
[    0.006017] pid_max: default: 32768 minimum: 301
[    0.007475] Security Framework initialized
[    0.008007] Yama: becoming mindful.
[    0.008565] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.009020] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.013665] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.014018] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.014761] CPU: GenuineIntel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.021570] Performance Events: no PMU driver, software events only.
[    0.022528] Hierarchical SRCU implementation.
[    0.023857] NMI watchdog: Perf event create on CPU 0 failed with -2
[    0.024018] NMI watchdog: Perf NMI watchdog permanently disabled
[    0.025574] Using local APIC timer interrupts.
[    0.025574] calibrating APIC timer ...
[    0.027000] ... lapic delta = 6249992
[    0.027000] ... PM-Timer delta = 357954
[    0.027000] ... PM-Timer result ok
[    0.027000] ..... delta 6249992
[    0.027000] ..... mult: 268435112
[    0.027000] ..... calibration result: 999998
[    0.027000] ..... CPU clock speed is 2593.0989 MHz.
[    0.027000] ..... host bus clock speed is 999.0998 MHz.
[    0.030208] devtmpfs: initialized
[    0.032057] x86/mm: Memory block size: 128MB
[    0.049131] workqueue: round-robin CPU selection forced, expect performance impact
[    0.051470] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.052039] futex hash table entries: 256 (order: 2, 26624 bytes)
[    0.054914] xor: measuring software checksum speed
[    0.065022]    prefetch64-sse: 20120.000 MB/sec
[    0.075009]    generic_sse: 18940.000 MB/sec
[    0.075347] xor: using function: prefetch64-sse (20120.000 MB/sec)
[    0.075810] prandom: seed boundary self test passed
[    0.078153] prandom: 100 self tests passed
[    0.084313] NET: Registered protocol family 16
[    0.092419] cpuidle: using governor menu
[    0.095734] ACPI: bus type PCI registered
[    0.096196] PCI: Using configuration type 1 for base access
[    0.294015] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.313039] raid6: sse2x1   gen()  2445 MB/s
[    0.330025] raid6: sse2x1   xor()  1783 MB/s
[    0.348027] raid6: sse2x2   gen()  4718 MB/s
[    0.365016] raid6: sse2x2   xor()  3277 MB/s
[    0.382009] raid6: sse2x4   gen()  7820 MB/s
[    0.399010] raid6: sse2x4   xor()  5548 MB/s
[    0.399343] raid6: using algorithm sse2x4 gen() 7820 MB/s
[    0.399741] raid6: .... xor() 5548 MB/s, rmw enabled
[    0.400018] raid6: using intx1 recovery algorithm
[    0.402104] ACPI: Added _OSI(Module Device)
[    0.402437] ACPI: Added _OSI(Processor Device)
[    0.402794] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.403013] ACPI: Added _OSI(Processor Aggregator Device)
[    0.451614] ACPI: Interpreter enabled
[    0.452361] ACPI: (supports S0 S3 S5)
[    0.452694] ACPI: Using IOAPIC for interrupt routing
[    0.453396] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.461968] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.673956] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.674089] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    0.675566] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.677902] PCI host bridge to bus 0000:00
[    0.678031] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.678579] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.679030] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.679607] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window]
[    0.680031] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.681154] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.688011] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.694071] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.698015] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    0.700035] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.700580] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.701019] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.701529] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.707147] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.708073] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.708600] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.714423] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.716020] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.720022] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.729024] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.735993] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.738014] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.739560] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.746016] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.752712] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    0.754791] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    0.756857] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    0.762016] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    0.769799] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    0.770626] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    0.780244] pci_bus 0000:00: on NUMA node 0
[    0.799867] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.804860] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.810025] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.815160] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.817713] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.836031] SCSI subsystem initialized
[    0.837668] libata version 3.00 loaded.
[    0.838134] ACPI: bus type USB registered
[    0.839570] usbcore: registered new interface driver usbfs
[    0.840480] usbcore: registered new interface driver hub
[    0.841363] usbcore: registered new device driver usb
[    0.843850] Linux video capture interface: v2.00
[    0.845178] pps_core: LinuxPPS API ver. 1 registered
[    0.845821] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.851511] FPGA manager framework
[    0.852225] PCI: Using ACPI for IRQ routing
[    0.853021] PCI: pci_cache_line_size set to 64 bytes
[    0.853827] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.854126] e820: reserve RAM buffer [mem 0x1ffde000-0x1fffffff]
[    0.863728] clocksource: Switched to clocksource kvm-clock
[    0.866622] FS-Cache: Loaded
[    0.867695] pnp: PnP ACPI init
[    0.870132] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.872998] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.875939] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.877615] pnp 00:03: [dma 2]
[    0.878826] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.883103] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.887298] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.891681] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.911661] pnp: PnP ACPI: found 7 devices
[    0.926379] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.927698] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.928195] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.928689] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.929237] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window]
[    0.931899] NET: Registered protocol family 1
[    0.932360] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.932823] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.933294] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.933822] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.934522] PCI: CLS 0 bytes, default 64
[    0.936328] Unpacking initramfs...
[    4.093212] Freeing initrd memory: 6032K
[    4.111960] PCLMULQDQ-NI instructions are not detected.
[    4.112543] AVX or AES-NI instructions are not detected.
[    4.112942] CPU feature 'AVX registers' is not supported.
[    4.113350] CPU feature 'AVX registers' is not supported.
[    4.113748] AVX2 or AES-NI instructions are not detected.
[    4.114182] AVX2 instructions are not detected.
[    4.628448] 
[    4.628678] ======================================================
[    4.629019] WARNING: possible circular locking dependency detected
[    4.629019] 4.14.0-04319-gd17a1d9 #30 Not tainted
[    4.629019] ------------------------------------------------------
[    4.629019] kworker/0:1/14 is trying to acquire lock:
[    4.629019]  (ww_class_mutex){+.+.}, at: [<ffffffffa01592f9>] test_abba_work+0x149/0x300
[    4.629019] 
[    4.629019] but now in release context of a crosslock acquired at the following:
[    4.629019]  ((completion)&abba.b_ready){+.+.}, at: [<ffffffffa015b1eb>] test_abba+0x3bb/0x5d0
[    4.629019] 
[    4.629019] which lock already depends on the new lock.
[    4.629019] 
[    4.629019] 
[    4.629019] the existing dependency chain (in reverse order) is:
[    4.629019] 
[    4.629019] -> #1 ((completion)&abba.b_ready){+.+.}:
[    4.629019]        __save_stack_trace+0x8e/0xf0
[    4.629019]        save_trace+0xb0/0x110
[    4.629019]        __lock_acquire+0x15d5/0x1ea0
[    4.629019]        __lock_acquire+0x15d5/0x1ea0
[    4.629019]        __lock_acquire+0x0/0x1ea0
[    4.629019]        match_held_lock+0xa6/0x3f0
[    4.629019]        kvm_sched_clock_read+0x5/0x10
[    4.629019]        match_held_lock+0x0/0x3f0
[    4.629019]        __lock_acquire+0x1b15/0x1ea0
[    4.629019]        __lock_acquire+0x0/0x1ea0
[    4.629019]        __lock_is_held+0x92/0xb0
[    4.629019]        __lock_acquire+0x0/0x1ea0
[    4.629019]        lock_acquire+0x99/0xd0
[    4.629019]        test_abba+0x3bb/0x5d0
[    4.629019]        wait_for_common+0xc7/0x4a0
[    4.629019]        test_abba+0x3bb/0x5d0
[    4.629019]        wait_for_common+0x0/0x4a0
[    4.629019]        sched_clock+0x5/0x10
[    4.629019]        sched_clock_local+0x10/0x80
[    4.629019]        preempt_count_add+0x15/0xc0
[    4.629019]        preempt_count_sub+0x13/0xc0
[    4.629019]        mark_lock+0xbb/0x820
[    4.629019]        mark_held_locks+0x7b/0xa0
[    4.629019]        _raw_spin_unlock_irqrestore+0x3e/0x70
[    4.629019]        test_abba+0x3bb/0x5d0
[    4.629019]        test_ww_mutex_init+0xf0/0xbef
[    4.629019]        test_abba+0x0/0x5d0
[    4.629019]        pvclock_clocksource_read+0x0/0x220
[    4.629019]        test_aa+0x212/0x2c2
[    4.629019]        kvm_clock_read+0x1e/0x30
[    4.629019]        kvm_sched_clock_read+0x5/0x10
[    4.629019]        test_abba_work+0x0/0x300
[    4.629019]        pvclock_clocksource_read+0x130/0x220
[    4.629019]        test_abba+0x3a1/0x5d0
[    4.629019]        test_aa+0x205/0x2c2
[    4.629019]        complete+0x13/0x60
[    4.629019]        test_aa+0x249/0x2c2
[    4.629019]        test_aa+0x0/0x2c2
[    4.629019]        test_aa+0x184/0x2c2
[    4.629019]        repair_env_string+0x0/0x93
[    4.629019]        test_ww_mutex_init+0xf0/0xbef
[    4.629019]        test_ww_mutex_init+0x0/0xbef
[    4.629019]        lock_release+0x0/0x790
[    4.629019]        do_raw_write_unlock+0x89/0xa0
[    4.629019]        preempt_count_sub+0x13/0xc0
[    4.629019]        _raw_write_unlock+0x29/0x40
[    4.629019]        proc_register+0x1f4/0x210
[    4.629019]        proc_create_data+0xf1/0x110
[    4.629019]        test_ww_mutex_init+0x0/0xbef
[    4.629019]        repair_env_string+0x0/0x93
[    4.629019]        do_one_initcall+0xfd/0x210
[    4.629019]        do_one_initcall+0x0/0x210
[    4.629019]        lock_release+0x0/0x790
[    4.629019]        kernel_init_freeable+0x1b3/0x286
[    4.629019]        kernel_init+0x0/0x170
[    4.629019]        kernel_init+0xf/0x170
[    4.629019]        kernel_init+0x0/0x170
[    4.629019]        ret_from_fork+0x24/0x30
[    4.629019] 
[    4.629019] -> #0 (ww_class_mutex){+.+.}:
[    4.629019]        test_abba_work+0x149/0x300
[    4.629019] 
[    4.629019] other info that might help us debug this:
[    4.629019] 
[    4.629019]  Possible unsafe locking scenario by crosslock:
[    4.629019] 
[    4.629019]        CPU0                    CPU1
[    4.629019]        ----                    ----
[    4.629019]   lock(ww_class_mutex);
[    4.629019]   lock((completion)&abba.b_ready);
[    4.629019]                                lock(ww_class_mutex);
[    4.629019]                                unlock((completion)&abba.b_ready);
[    4.629019] 
[    4.629019]  *** DEADLOCK ***
[    4.629019] 
[    4.629019] 5 locks held by kworker/0:1/14:
[    4.629019]  #0:  ((wq_completion)"events"){+.+.}, at: [<ffffffffa010f16a>] process_one_work+0x72a/0xd20
[    4.629019]  #1:  ((work_completion)(&abba.work)){+.+.}, at: [<ffffffffa010f16a>] process_one_work+0x72a/0xd20
[    4.629019]  #2:  (ww_class_acquire){+.+.}, at: [<ffffffffa010f27b>] process_one_work+0x83b/0xd20
[    4.629019]  #3:  (ww_class_mutex){+.+.}, at: [<ffffffffa01592f9>] test_abba_work+0x149/0x300
[    4.629019]  #4:  (&x->wait#6){....}, at: [<ffffffffa0140f73>] complete+0x13/0x60
[    4.629019] 
[    4.629019] stack backtrace:
[    4.629019] CPU: 0 PID: 14 Comm: kworker/0:1 Not tainted 4.14.0-04319-gd17a1d9 #30
[    4.629019] Workqueue: events test_abba_work
[    4.629019] Call Trace:
[    4.629019]  ? print_circular_bug+0x451/0x490
[    4.629019]  ? check_prev_add+0x284/0xaa0
[    4.629019]  ? zap_class+0x360/0x360
[    4.629019]  ? check_usage+0x960/0x960
[    4.629019]  ? debug_show_all_locks+0x2c0/0x2c0
[    4.629019]  ? match_held_lock+0x93/0x3f0
[    4.629019]  ? lock_commit_crosslock+0x803/0x960
[    4.629019]  ? lock_commit_crosslock+0x803/0x960
[    4.629019]  ? lockdep_sys_exit+0xa0/0xa0
[    4.629019]  ? preempt_count_sub+0x13/0xc0
[    4.629019]  ? lock_contended+0x540/0x540
[    4.629019]  ? lock_acquire+0x99/0xd0
[    4.629019]  ? complete+0x13/0x60
[    4.629019]  ? complete+0x1f/0x60
[    4.629019]  ? test_abba_work+0x155/0x300
[    4.629019]  ? process_one_work+0x83b/0xd20
[    4.629019]  ? test_mutex_work+0x90/0x90
[    4.629019]  ? lock_acquire+0x99/0xd0
[    4.629019]  ? process_one_work+0x72a/0xd20
[    4.629019]  ? process_one_work+0x83b/0xd20
[    4.629019]  ? process_one_work+0x72a/0xd20
[    4.629019]  ? pwq_dec_nr_in_flight+0x1f0/0x1f0
[    4.629019]  ? __lock_acquire+0x1b15/0x1ea0
[    4.629019]  ? lock_downgrade+0x300/0x300
[    4.629019]  ? mark_held_locks+0x1c/0xa0
[    4.629019]  ? _raw_spin_unlock_irq+0x24/0x50
[    4.629019]  ? _raw_spin_unlock_irq+0x35/0x50
[    4.629019]  ? match_held_lock+0x93/0x3f0
[    4.629019]  ? finish_task_switch+0x17c/0x230
[    4.629019]  ? static_obj+0x20/0x20
[    4.629019]  ? __schedule+0x706/0x790
[    4.629019]  ? firmware_map_remove+0x1b4/0x1b4
[    4.629019]  ? schedule+0x235/0x2a0
[    4.629019]  ? find_held_lock+0xb3/0xd0
[    4.629019]  ? check_preemption_disabled+0x110/0x120
[    4.629019]  ? preempt_count_add+0x15/0xc0
[    4.629019]  ? preempt_count_sub+0x13/0xc0
[    4.629019]  ? do_raw_spin_trylock+0x49/0x80
[    4.629019]  ? worker_thread+0xa61/0xe10
[    4.629019]  ? __lock_acquire+0x1b15/0x1ea0
[    4.629019]  ? process_one_work+0xd20/0xd20
[    4.629019]  ? match_held_lock+0x93/0x3f0
[    4.629019]  ? match_held_lock+0x93/0x3f0
[    4.629019]  ? static_obj+0x20/0x20
[    4.629019]  ? debug_show_all_locks+0x2c0/0x2c0
[    4.629019]  ? static_obj+0x20/0x20
[    4.629019]  ? pvclock_clocksource_read+0x130/0x220
[    4.629019]  ? pvclock_read_flags+0x50/0x50
[    4.629019]  ? static_obj+0x20/0x20
[    4.629019]  ? match_held_lock+0x93/0x3f0
[    4.629019]  ? __schedule+0x750/0x790
[    4.629019]  ? kvm_clock_read+0x1e/0x30
[    4.629019]  ? kvm_sched_clock_read+0x5/0x10
[    4.629019]  ? sched_clock_local+0x10/0x80
[    4.629019]  ? preempt_count_add+0x15/0xc0
[    4.629019]  ? preempt_count_sub+0x13/0xc0
[    4.629019]  ? pick_next_entity+0x1bd/0x1d0
[    4.629019]  ? mark_held_locks+0x1c/0xa0
[    4.629019]  ? preempt_count_sub+0x13/0xc0
[    4.629019]  ? _raw_spin_unlock_irq+0x35/0x50
[    4.629019]  ? __schedule+0x750/0x790
[    4.629019]  ? firmware_map_remove+0x1b4/0x1b4
[    4.629019]  ? preempt_count_sub+0x13/0xc0
[    4.629019]  ? _raw_spin_unlock_irq+0x35/0x50
[    4.629019]  ? finish_task_switch+0x1b7/0x230
[    4.629019]  ? finish_task_switch+0x17c/0x230
[    4.629019]  ? preempt_count_sub+0x13/0xc0
[    4.629019]  ? schedule+0x235/0x2a0
[    4.629019]  ? preempt_schedule_common+0x80/0x80
[    4.629019]  ? ___preempt_schedule+0x16/0x18
[    4.629019]  ? process_one_work+0xd20/0xd20
[    4.629019]  ? preempt_schedule_common+0x73/0x80
[    4.629019]  ? ___preempt_schedule+0x16/0x18
[    4.629019]  ? process_one_work+0xd20/0xd20
[    4.629019]  ? kthread+0x22f/0x250
[    4.629019]  ? __kthread_create_on_node+0x2e0/0x2e0
[    4.629019]  ? ret_from_fork+0x24/0x30
[    5.152096] tsc: Refined TSC clocksource calibration: 2593.988 MHz
[    5.152843] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x25640cb3a67, max_idle_ns: 440795258696 ns
[   10.763584] 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 stall_cpu_irqsoff=0 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0
[   10.766045] rcu-torture: Creating rcu_torture_writer task
[   10.766698] rcu-torture: Creating rcu_torture_fakewriter task
[   10.767294] rcu-torture: rcu_torture_writer task started
[   10.767691] rcu-torture: GP expediting controlled from boot/sysfs for rcu,
[   10.768189] rcu-torture: Disabled dynamic grace-period expediting.
[   10.768691] rcu-torture: Creating rcu_torture_fakewriter task
[   10.769259] rcu-torture: rcu_torture_fakewriter task started
[   10.769731] rcu-torture: Creating rcu_torture_fakewriter task
[   10.770795] rcu-torture: rcu_torture_fakewriter task started
[   10.771307] rcu-torture: Creating rcu_torture_fakewriter task
[   10.771864] rcu-torture: rcu_torture_fakewriter task started
[   10.772363] rcu-torture: Creating rcu_torture_reader task
[   10.772895] rcu-torture: rcu_torture_fakewriter task started
[   10.773388] rcu-torture: Creating rcu_torture_stats task
[   10.773915] rcu-torture: rcu_torture_reader task started
[   10.774365] rcu-torture: Creating torture_shuffle task
[   10.774876] rcu-torture: rcu_torture_stats task started
[   10.775344] rcu-torture: Creating torture_stutter task
[   10.775857] rcu-torture: torture_shuffle task started
[   10.776310] rcu-torture: Creating rcu_torture_cbflood task
[   10.776847] rcu-torture: torture_stutter task started
[   10.781652] Initialise system trusted keyrings
[   10.782862] rcu-torture: rcu_torture_cbflood task started
[   10.783868] Key type blacklist registered
[   10.784441] workingset: timestamp_bits=62 max_order=17 bucket_order=0
[   10.807899] zbud: loaded
[   10.818481] fuse init (API version 7.26)
[   10.821522] JFS: nTxBlock = 2642, nTxLock = 21138
[   10.825225] NILFS version 2 loaded
[   10.829567] gfs2: GFS2 installed
[   10.849661] Key type asymmetric registered
[   10.849966] Asymmetric key parser 'x509' registered
[   10.851567] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[   10.852384] io scheduler noop registered (default)
[   10.852745] io scheduler deadline registered
[   10.853920] io scheduler cfq registered
[   10.854385] io scheduler mq-deadline registered
[   10.854723] io scheduler kyber registered
[   10.859143] test_hexdump: all 1184 tests passed
[   10.860134] test_firmware: interface ready
[   10.860545] Running rhashtable test nelem=8, max_size=0, shrinking=0
[   10.861032] Test 00:
[   10.863372]   Adding 50000 keys
[   11.039522]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.095983]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.096602]   Deleting 50000 keys
[   11.139245]   Duration of test: 275624737 ns
[   11.139715] Test 01:
[   11.141981]   Adding 50000 keys
[   11.264909]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.319128]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.319720]   Deleting 50000 keys
[   11.362070]   Duration of test: 219819714 ns
[   11.362530] Test 02:
[   11.364816]   Adding 50000 keys
[   11.487579]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.543651]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.544256]   Deleting 50000 keys
[   11.587220]   Duration of test: 222147107 ns
[   11.587700] Test 03:
[   11.590051]   Adding 50000 keys
[   11.716134]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.772821]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   11.773429]   Deleting 50000 keys
[   11.830606]   Duration of test: 240300361 ns
[   11.831094] Average test time: 239472979
[   11.831378] Testing concurrent rhashtable access from 10 threads
[   18.017673] Started 10 threads, 0 failed
[   18.026408] test passed
[   18.028573] test_printf: all 260 tests passed
[   18.110125] crc32: CRC_LE_BITS = 1, CRC_BE BITS = 1
[   18.110514] crc32: self tests passed, processed 225944 bytes in 17741232 nsec
[   18.154100] crc32c: CRC_LE_BITS = 1
[   18.154395] crc32c: self tests passed, processed 225944 bytes in 9040196 nsec
[   19.287156] crc32_combine: 8373 self tests passed
[   20.104107] crc32c_combine: 8373 self tests passed
[   20.104824] xz_dec_test: module loaded
[   20.105141] xz_dec_test: Create a device node with 'mknod xz_dec_test c 246 0' and write .xz files to it.
[   20.106195] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[   20.106704] glob: 64 self-tests passed, 0 failed
[   20.108967] rbtree testing
[   23.183627]  -> test 1 (latency of nnodes insert+delete): 79756 cycles
[   26.269854]  -> test 2 (latency of nnodes cached insert+delete): 80038 cycles
[   26.516205]  -> test 3 (latency of inorder traversal): 6348 cycles
[   26.519730]  -> test 4 (latency to fetch first node)
[   26.520091]         non-cached: 79 cycles
[   26.520766]         cached: 10 cycles
[   26.651256] augmented rbtree testing
[   31.201785]  -> test 1 (latency of nnodes insert+delete): 118040 cycles
[   35.658427]  -> test 2 (latency of nnodes cached insert+delete): 115558 cycles
[   35.846704] interval tree insert/remove
[   40.494960]  -> 120574 cycles
[   40.495495] interval tree search
[   42.682596]  -> 567324 cycles (2692 results)
[   42.684618] gpio_it87: no device
[   42.686941] cpcihp_generic: Generic port I/O CompactPCI Hot Plug Driver version: 0.1
[   42.687497] cpcihp_generic: not configured, disabling.
[   42.688128] switchtec: loaded.
[   42.693128] VIA Graphics Integration Chipset framebuffer 2.4 initializing
[   42.694815] no IO addresses supplied
[   42.695727] usbcore: registered new interface driver udlfb
[   42.696262] usbcore: registered new interface driver smscufx
[   42.698074] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   42.698752] ACPI: Power Button [PWRF]
[   45.758989] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[   45.763583] r3964: Philips r3964 Driver $Revision: 1.10 $
[   45.763982] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   45.788621] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   45.813673] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   45.821221] lp: driver loaded but no devices found
[   45.821571] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $
[   45.822098] ac.o: No PCI boards found.
[   45.822368] ac.o: For an ISA board you must supply memory and irq parameters.
[   45.823261] Non-volatile memory driver v1.3
[   45.824528] ppdev: user-space parallel port driver
[   45.824955] telclk_interrupt = 0xf non-mcpbl0010 hw.
[   45.825363] smapi::smapi_init, ERROR invalid usSmapiID
[   45.825727] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine
[   45.826369] mwave: mwavedd::mwave_init: Error: Failed to initialize board data
[   45.826871] mwave: mwavedd::mwave_init: Error: Failed to initialize
[   45.827313] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[   45.829637] [drm] radeon kernel modesetting enabled.
[   45.831191] [drm] amdgpu kernel modesetting enabled.
[   45.832407] usbcore: registered new interface driver udl
[   45.834138] [drm] Found bochs VGA, ID 0xb0c0.
[   45.834454] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[   45.835408] [TTM] Zone  kernel: Available graphics memory: 169108 kiB
[   45.835871] [TTM] Initializing pool allocator
[   45.836290] [TTM] Initializing DMA pool allocator
[   45.841876] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[   45.842490] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[   45.844129] parport_pc 00:04: reported by Plug and Play ACPI
[   45.844931] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
[   45.846124] lp0: using parport0 (interrupt-driven).
[   45.846477] lp0: console ready
[   45.875484] loop: module loaded
[   45.878128] MM: desc_per_page = 128
[   45.884989] ibmasm: IBM ASM Service Processor Driver version 1.0 loaded
[   45.885851] dummy-irq: no IRQ given.  Use irq=N
[   45.886707] Phantom Linux Driver, version n0.9.8, init OK
[   45.889179] Guest personality initialized and is inactive
[   45.889964] VMCI host device registered (name=vmci, major=10, minor=60)
[   45.890448] Initialized host personality
[   45.891646] usbcore: registered new interface driver rtsx_usb
[   45.895904] usbcore: registered new interface driver dln2
[   45.896754] Uniform Multi-Platform E-IDE driver
[   45.897433] piix 0000:00:01.1: IDE controller (0x8086:0x7010 rev 0x00)
[   45.898255] piix 0000:00:01.1: not 100% native mode: will probe irqs later
[   45.899220]     ide0: BM-DMA at 0xc080-0xc087
[   45.899545]     ide1: BM-DMA at 0xc088-0xc08f
[   45.899862] Probing IDE interface ide0...
[   46.429590] Probing IDE interface ide1...
[   47.115174] hdc: QEMU DVD-ROM, ATAPI CD/DVD-ROM drive
[   47.739596] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO0
[   47.740167] hdc: MWDMA2 mode selected
[   47.740618] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[   47.741077] ide1 at 0x170-0x177,0x376 on irq 15
[   47.745953] ide-cd driver 5.00
[   47.747284] ide-cd: hdc: ATAPI 4X DVD-ROM drive, 512kB Cache
[   47.747952] cdrom: Uniform CD-ROM driver Revision: 3.20
[   47.756251] snic:Cisco SCSI NIC Driver, ver 0.0.1.18
[   47.757169] snic:Trace Facility Enabled.
[   47.757169]  Trace Buffer SZ 16 Pages.
[   47.760681] Loading Adaptec I2O RAID: Version 2.4 Build 5go
[   47.761383] Detecting Adaptec I2O RAID controllers...
[   47.762902] aic94xx: Adaptec aic94xx SAS/SATA driver version 1.0.3 loaded
[   47.766125] megasas: 07.703.05.00-rc1
[   47.766973] mpt3sas version 17.100.00.00 loaded
[   47.769695] 3ware Storage Controller device driver for Linux v1.26.02.003.
[   47.770812] 3ware 9000 Storage Controller device driver for Linux v2.26.02.014.
[   47.771915] LSI 3ware SAS/SATA-RAID Controller device driver for Linux v3.26.02.000.
[   47.773169] imm: Version 2.05 (for Linux 2.4.0)
[   47.775877] ipr: IBM Power RAID SCSI Device Driver version: 2.6.4 (March 14, 2017)
[   47.777091] RocketRAID 3xxx/4xxx Controller driver v1.10.0
[   47.777996] stex: Promise SuperTrak EX Driver version: 6.02.0000.01
[   47.779550] VMware PVSCSI driver - version 1.0.7.0-k
[   47.780692] st: Version 20160209, fixed bufsize 32768, s/g segs 256
[   47.781846] osst :I: Tape driver with OnStream support version 0.99.4
[   47.781846] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $
[   47.799522] Rounding down aligned max_sectors from 4294967295 to 4294967288
[   47.801512] HSI/SSI char device loaded
[   47.801802] Fusion MPT base driver 3.04.20
[   47.802103] Copyright (c) 1999-2008 LSI Corporation
[   47.802497] Fusion MPT SPI Host driver 3.04.20
[   47.803247] Fusion MPT SAS Host driver 3.04.20
[   47.803928] parport0: cannot grant exclusive access for device ks0108
[   47.804395] ks0108: ERROR: parport didn't register new device
[   47.914794] panel: panel driver registered on parport0 (io=0x378).
[   47.915750] usbcore: registered new interface driver hwa-rc
[   47.916297] usbcore: registered new interface driver i1480-dfu-usb
[   47.919077] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   47.919676] ohci-platform: OHCI generic platform driver
[   47.920307] driver u132_hcd
[   47.921266] usbcore: registered new interface driver hwa-hc
[   47.921875] fotg210_hcd: FOTG210 Host Controller (EHCI) Driver
[   47.922294] Warning! fotg210_hcd should always be loaded before uhci_hcd and ohci_hcd, not after
[   47.923646] usbcore: registered new interface driver wusb-cbaf
[   47.924614] usbcore: registered new interface driver cdc_acm
[   47.925130] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[   47.925827] usbcore: registered new interface driver usblp
[   47.926389] usbcore: registered new interface driver cdc_wdm
[   47.926927] usbcore: registered new interface driver usb-storage
[   47.927482] usbcore: registered new interface driver ums-alauda
[   47.928062] usbcore: registered new interface driver ums-cypress
[   47.928594] usbcore: registered new interface driver ums-freecom
[   47.929149] usbcore: registered new interface driver ums-isd200
[   47.929681] usbcore: registered new interface driver ums-jumpshot
[   47.930524] usbcore: registered new interface driver ums-karma
[   47.931084] usbcore: registered new interface driver ums-onetouch
[   47.931622] usbcore: registered new interface driver ums-realtek
[   47.932203] usbcore: registered new interface driver ums-sddr09
[   47.932743] usbcore: registered new interface driver ums-sddr55
[   47.933395] usbcore: registered new interface driver mdc800
[   47.933795] mdc800: v0.7.5 (30/10/2000):USB Driver for Mustek MDC800 Digital Camera
[   47.934757] usbcore: registered new interface driver ark3116
[   47.935332] usbserial: USB Serial support registered for ark3116
[   47.935889] usbcore: registered new interface driver cp210x
[   47.936456] usbserial: USB Serial support registered for cp210x
[   47.937034] usbcore: registered new interface driver cyberjack
[   47.937580] usbserial: USB Serial support registered for Reiner SCT Cyberjack USB card reader
[   47.938366] usbcore: registered new interface driver usb_debug
[   47.938913] usbserial: USB Serial support registered for debug
[   47.939484] usbserial: USB Serial support registered for xhci_dbc
[   47.940092] usbcore: registered new interface driver io_edgeport
[   47.940663] usbserial: USB Serial support registered for Edgeport 2 port adapter
[   47.941337] usbserial: USB Serial support registered for Edgeport 4 port adapter
[   47.942003] usbserial: USB Serial support registered for Edgeport 8 port adapter
[   47.942713] usbserial: USB Serial support registered for EPiC device
[   47.943313] usbcore: registered new interface driver f81232
[   47.943848] usbserial: USB Serial support registered for f81232
[   47.944437] usbcore: registered new interface driver ipaq
[   47.944959] usbserial: USB Serial support registered for PocketPC PDA
[   47.945563] usbcore: registered new interface driver iuu_phoenix
[   47.947083] usbserial: USB Serial support registered for iuu_phoenix
[   47.947670] usbcore: registered new interface driver keyspan
[   47.948248] usbserial: USB Serial support registered for Keyspan - (without firmware)
[   47.948943] usbserial: USB Serial support registered for Keyspan 1 port adapter
[   47.949616] usbserial: USB Serial support registered for Keyspan 2 port adapter
[   47.950329] usbserial: USB Serial support registered for Keyspan 4 port adapter
[   47.950980] usbcore: registered new interface driver keyspan_pda
[   47.951554] usbserial: USB Serial support registered for Keyspan PDA
[   47.952198] usbserial: USB Serial support registered for Keyspan PDA - (prerenumeration)
[   47.952914] usbserial: USB Serial support registered for Xircom / Entrega PGS - (prerenumeration)
[   47.953681] usbcore: registered new interface driver kl5kusb105
[   47.954275] usbserial: USB Serial support registered for KL5KUSB105D / PalmConnect
[   47.954950] usbcore: registered new interface driver kobil_sct
[   47.955510] usbserial: USB Serial support registered for KOBIL USB smart card terminal
[   47.956263] usbcore: registered new interface driver mct_u232
[   47.956805] usbserial: USB Serial support registered for MCT U232
[   47.957385] usbcore: registered new interface driver metro_usb
[   47.957931] usbserial: USB Serial support registered for Metrologic USB to Serial
[   47.958621] usbcore: registered new interface driver mos7840
[   47.959193] usbserial: USB Serial support registered for Moschip 7840/7820 USB Serial Driver
[   47.959963] usbcore: registered new interface driver opticon
[   47.960537] usbserial: USB Serial support registered for opticon
[   47.961121] usbcore: registered new interface driver option
[   47.961657] usbserial: USB Serial support registered for GSM modem (1-port)
[   47.962320] usbcore: registered new interface driver pl2303
[   47.962850] usbserial: USB Serial support registered for pl2303
[   47.963419] usbcore: registered new interface driver quatech2
[   47.963964] usbserial: USB Serial support registered for Quatech 2nd gen USB to Serial Driver
[   47.964731] usbcore: registered new interface driver safe_serial
[   47.965310] usbserial: USB Serial support registered for safe_serial
[   47.965898] usbcore: registered new interface driver usb_serial_simple
[   47.966529] usbserial: USB Serial support registered for carelink
[   47.967168] usbserial: USB Serial support registered for zio
[   47.967739] usbserial: USB Serial support registered for funsoft
[   47.968355] usbserial: USB Serial support registered for flashloader
[   47.968953] usbserial: USB Serial support registered for google
[   47.969534] usbserial: USB Serial support registered for vivopay
[   47.970156] usbserial: USB Serial support registered for moto_modem
[   47.970760] usbserial: USB Serial support registered for novatel_gps
[   47.971375] usbserial: USB Serial support registered for hp4x
[   47.971938] usbserial: USB Serial support registered for suunto
[   47.972543] usbserial: USB Serial support registered for siemens_mpi
[   47.973161] usbcore: registered new interface driver spcp8x5
[   47.973713] usbserial: USB Serial support registered for SPCP8x5
[   47.974312] usbcore: registered new interface driver symbolserial
[   47.974877] usbserial: USB Serial support registered for symbol
[   47.975463] usbcore: registered new interface driver upd78f0730
[   47.976069] usbserial: USB Serial support registered for upd78f0730
[   47.976642] usbcore: registered new interface driver wishbone_serial
[   47.977264] usbserial: USB Serial support registered for wishbone_serial
[   47.977868] usbcore: registered new interface driver xsens_mt
[   47.978445] usbserial: USB Serial support registered for xsens_mt
[   47.979040] usbcore: registered new interface driver adutux
[   47.979568] usbcore: registered new interface driver appledisplay
[   47.980175] usbcore: registered new interface driver cypress_cy7c63
[   47.980753] usbcore: registered new interface driver emi26 - firmware loader
[   47.981261] ftdi_elan: driver ftdi-elan
[   47.981684] usbcore: registered new interface driver ftdi-elan
[   47.982266] usbcore: registered new interface driver idmouse
[   47.982813] usbcore: registered new interface driver iowarrior
[   47.983377] usbcore: registered new interface driver isight_firmware
[   47.983970] usbcore: registered new interface driver usblcd
[   47.984539] usbcore: registered new interface driver ldusb
[   47.985090] usbcore: registered new interface driver legousbtower
[   47.985656] usbcore: registered new interface driver rio500
[   47.986260] usbcore: registered new interface driver usb_ehset_test
[   47.986842] usbcore: registered new interface driver trancevibrator
[   47.987429] usbcore: registered new interface driver uss720
[   47.987837] uss720: USB Parport Cable driver for Cables using the Lucent Technologies USS720 Chip
[   47.988483] uss720: NOTE: this is a special purpose driver to allow nonstandard
[   47.989023] uss720: protocols (eg. bitbang) over USS720 usb to parallel cables
[   47.989521] uss720: If you just want to connect to a printer, use usblp instead
[   47.990246] usbcore: registered new interface driver usbsevseg
[   47.990798] usbcore: registered new interface driver yurex
[   47.991353] usbcore: registered new interface driver chaoskey
[   47.991897] usbcore: registered new interface driver sisusb
[   47.992466] usbcore: registered new interface driver lvs
[   47.993547] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   47.995306] serio: i8042 KBD port at 0x60,0x64 irq 1
[   47.996378] serio: i8042 AUX port at 0x60,0x64 irq 12
[   47.997796] parport0: cannot grant exclusive access for device parkbd
[   48.000182] mousedev: PS/2 mouse device common for all mice
[   48.001086] evbug: Connected device: input0 (Power Button at LNXPWRBN/button/input0)
[   48.003303] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   48.004528] evbug: Connected device: input1 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[   48.005389] usbcore: registered new interface driver usb_acecad
[   48.005526] usbcore: registered new interface driver gtco
[   48.005674] usbcore: registered new interface driver hanwang
[   48.010188] mk712: device not present
[   48.013611] apanel: Fujitsu BIOS signature 'FJKEYINF' not found...
[   48.014250] usbcore: registered new interface driver ati_remote2
[   48.014806] cm109: Keymap for Komunikate KIP1000 phone loaded
[   48.015401] usbcore: registered new interface driver cm109
[   48.015801] cm109: CM109 phone driver: 20080805 (C) Alfred E. Heggestad
[   48.017115] usbcore: registered new interface driver ims_pcu
[   48.017660] usbcore: registered new interface driver keyspan_remote
[   48.018733] usbcore: registered new interface driver powermate
[   48.020043] ideapad_slidebar: DMI does not match
[   48.023381] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   48.066218] usbcore: registered new interface driver i2c-diolan-u2c
[   48.075698] lirc_dev: IR Remote Control driver registered, major 510
[   48.076179] IR NEC protocol handler initialized
[   48.076501] IR RC5(x/sz) protocol handler initialized
[   48.076859] IR RC6 protocol handler initialized
[   48.077427] IR Sony protocol handler initialized
[   48.077961] IR SANYO protocol handler initialized
[   48.078473] IR Sharp protocol handler initialized
[   48.078992] IR MCE Keyboard/mouse protocol handler initialized
[   48.080541] pps pps0: new PPS source ktimer
[   48.081138] pps pps0: ktimer PPS source registered
[   48.081674] pps_ldisc: PPS line discipline registered
[   48.082259] pps_parport: parallel port PPS client
[   48.082610] parport0: cannot grant exclusive access for device pps_parport
[   48.083319] pps_parport: couldn't register with parport0
[   48.084289] Driver for 1-wire Dallas network protocol.
[   48.085947] usbcore: registered new interface driver DS9490R
[   48.086818] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko
[   48.088788] __power_supply_register: Expected proper parent device for 'test_ac'
[   48.090510] __power_supply_register: Expected proper parent device for 'test_battery'
[   48.093527] (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[   48.095754] __power_supply_register: Expected proper parent device for 'test_usb'
[   48.254128] applesmc: supported laptop not found!
[   48.254858] applesmc: driver init failed (ret=-19)!
[   48.265302] f71882fg: Not a Fintek device
[   48.266119] f71882fg: Not a Fintek device
[   48.570128] device-mapper: uevent: version 1.0.3
[   48.571877] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[   48.573989] device-mapper: multipath round-robin: version 1.2.0 loaded
[   48.574526] device-mapper: multipath queue-length: version 0.2.0 loaded
[   48.575049] device-mapper: multipath service-time: version 0.3.0 loaded
[   48.576287] device-mapper: raid: Loading target version 1.13.0
[   48.577812] sdhci: Secure Digital Host Controller Interface driver
[   48.578288] sdhci: Copyright(c) Pierre Ossman
[   48.579258] VUB300 Driver rom wait states = 1C irqpoll timeout = 0400
[   48.580701] usbcore: registered new interface driver vub300
[   48.582397] usbcore: registered new interface driver ushc
[   48.583422] sdhci-pltfm: SDHCI platform and OF driver helper
[   48.590625] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[   48.595350] hidraw: raw HID events driver (C) Jiri Kosina
[   48.604104] usbcore: registered new interface driver usbhid
[   48.604506] usbhid: USB HID core driver
[   48.604921] hdaps: supported laptop not found!
[   48.605284] hdaps: driver init failed (ret=-19)!
[   48.619820] IDT PCI-E Non-Transparent Bridge Driver 2.0
[   48.620407] Intel(R) PCI-E Non-Transparent Bridge Driver 2.0
[   48.621614]  fake-fmc-carrier: mezzanine 0
[   48.621945]       Manufacturer: fake-vendor
[   48.622250]       Product name: fake-design-for-testing
[   48.622964] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[   48.623571] fmc_trivial: probe of fake-design-for-testing-f001 failed with error -95
[   48.624237] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[   48.625201] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001"
[   48.626794] intel_rapl: driver does not support CPU family 6 model 6
[   48.631637] oprofile: using NMI timer interrupt.
[   49.157795] String selftests succeeded
[   49.159843] ... APIC ID:      00000000 (0)
[   49.160006] ... APIC VERSION: 01050014
[   49.160006] 0000000000000000000000000000000000000000000000000000000000000000
[   49.160006] 0000000000000004000000000000000000000000000000000000000000000000
[   49.160006] 0000000000000000000000000000000000000000000000000000000000004000
[   49.161982] number of MP IRQ sources: 15.
[   49.162281] number of IO-APIC #0 registers: 24.
[   49.162600] testing the IO APIC.......................
[   49.162971] IO APIC #0......
[   49.163222] .... register #00: 00000000
[   49.163499] .......    : physical APIC id: 00
[   49.163817] .......    : Delivery Type: 0
[   49.164111] .......    : LTS          : 0
[   49.164397] .... register #01: 00170011
[   49.164672] .......     : max redirection entries: 17
[   49.165071] .......     : PRQ implemented: 0
[   49.165379] .......     : IO APIC version: 11
[   49.165690] .... register #02: 00000000
[   49.165962] .......     : arbitration: 00
[   49.166253] .... IRQ redirection table:
[   49.166528] IOAPIC 0:
[   49.166713]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.167309]  pin01, enabled , edge , high, V(26), IRR(0), S(0), logical , D(01), M(1)
[   49.167877]  pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(1)
[   49.168436]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.168993]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.169586]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.170156]  pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.170714]  pin07, enabled , edge , high, V(21), IRR(0), S(0), logical , D(01), M(1)
[   49.171307]  pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.171874]  pin09, enabled , level, high, V(20), IRR(0), S(0), logical , D(01), M(1)
[   49.172431]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.172995]  pin0b, enabled , level, high, V(22), IRR(0), S(0), logical , D(01), M(1)
[   49.173593]  pin0c, enabled , edge , high, V(25), IRR(0), S(0), logical , D(01), M(1)
[   49.174180]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.174733]  pin0e, enabled , edge , high, V(23), IRR(0), S(0), logical , D(01), M(1)
[   49.175329]  pin0f, enabled , edge , high, V(24), IRR(0), S(0), logical , D(01), M(1)
[   49.175885]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.176440]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.176995]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.177596]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.178164]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.178716]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.179309]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.179865]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   49.180413] IRQ to pin mappings:
[   49.180662] IRQ0 -> 0:2
[   49.180845] IRQ1 -> 0:1
[   49.181074] IRQ3 -> 0:3
[   49.181261] IRQ4 -> 0:4
[   49.181441] IRQ5 -> 0:5
[   49.181629] IRQ6 -> 0:6
[   49.181819] IRQ7 -> 0:7
[   49.181999] IRQ8 -> 0:8
[   49.182194] IRQ9 -> 0:9
[   49.182375] IRQ10 -> 0:10
[   49.182573] IRQ11 -> 0:11
[   49.182768] IRQ12 -> 0:12
[   49.182960] IRQ13 -> 0:13
[   49.183199] IRQ14 -> 0:14
[   49.183398] IRQ15 -> 0:15
[   49.183590] .................................... done.
[   49.184043] sched_clock: Marking stable (49184008144, 0)->(50169007585, -984999441)
[   49.186412] Loading compiled-in X.509 certificates
[   49.193504] Btrfs loaded, crc32c=crc32c-generic, ref-verify=on
[   49.194575] Key type trusted registered
[   49.194871] ima: No TPM chip found, activating TPM-bypass! (rc=-19)
[   49.197973] RIO: rio_register_scan for mport_id=-1
[   49.199473] BIOS EDD facility v0.16 2004-Jun-25, 1 devices found
[   49.209304] Freeing unused kernel memory: 1460K
[   49.209654] Write protecting the kernel read-only data: 45056k
[   49.212432] Freeing unused kernel memory: 92K
[   49.214559] Freeing unused kernel memory: 104K
[   49.214902] rodata_test: all tests were successful
/etc/rcS.d/S00fbsetup: line 3: /sbin/modprobe: not found

Please wait: booting...
[   49.286895] gfs2: path_lookup on /dev/root returned error -2
Starting udev
[   49.377834] udevd[199]: starting version 3.1.5
bootlogd: cannot allocate pseudo tty: No such file or directory
Populating dev cache
hwclock: can't open '/dev/misc/rtc': No such file or directory
Configuring network interfaces... ifconfig: socket: Address family not supported by protocol
hwclock: can't open '/dev/misc/rtc': No such file or directory
Starting syslogd/klogd: done
PATH=/sbin:/usr/sbin:/bin:/usr/bin:/lkp/lkp/src/bin
LKP: HOSTNAME vm-lkp-hsw01-yocto-x86_64-16, MAC , kernel 4.14.0-04319-gd17a1d9 30, serial console /dev/ttyS0
Poky (Yocto Project Reference Distro) 2.1 vm-lkp-hsw01-yocto-x86_64-16 /dev/ttyS0
[   52.286117] LKP: waiting for network...
[   69.303142] /lkp/lkp/src/bin/run-lkp
[   69.303142] RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/0
[   69.303142] job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-16/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-d17a1d97dc208d664c91cc387ffb752c7f85dc61-20171126-3122-1uhy2p0-0.yaml
[   69.303142] run-job /lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-16/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-d17a1d97dc208d664c91cc387ffb752c7f85dc61-20171126-3122-1uhy2p0-0.yaml
[   69.303142] skip http request: cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-16/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-d17a1d97dc208d664c91cc387ffb752c7f85dc61-20171126-3122-1uhy2p0-0.yaml&job_state=running -O /dev/null
[   74.720099] rcu-torture: rtc: ffffffffa4678f20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 barrier: 0/0:0 cbflood: 1
[   74.721398] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[   74.721920] rcu-torture: Reader Batch:  0 2 0 0 0 0 0 0 0 0 0
[   74.722899] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[   81.752583] trinity-main uses obsolete (PF_INET,SOCK_PACKET)
[   82.316246] sock: process `trinity-main' is using obsolete setsockopt SO_BSDCOMPAT
[  136.160102] rcu-torture: rtc: ffffffffa4678f20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 barrier: 0/0:0 cbflood: 1
[  136.161190] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[  136.161607] rcu-torture: Reader Batch:  0 2 0 0 0 0 0 0 0 0 0
[  136.162406] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[  136.162911] ??? Writer stall state RTWS_STUTTER(8) g54721 c54720 f0x0 ->state 0x1 cpu 0
[  136.164061] rcu_torture_wri S    0    55      2 0x80000000
[  136.164461] Call Trace:
[  136.164648]  ? __schedule+0x6fe/0x790
[  136.164919]  ? firmware_map_remove+0x1b4/0x1b4
[  136.167043]  ? lock_downgrade+0x300/0x300
[  136.167344]  ? schedule+0x21b/0x2a0
[  136.167598]  ? do_raw_spin_unlock+0xda/0xf0
[  136.167904]  ? preempt_count_sub+0x13/0xc0
[  136.170025]  ? preempt_schedule_common+0x80/0x80
[  136.170365]  ? calc_wheel_index+0x19d/0x1b0
[  136.170677]  ? lock_downgrade+0x300/0x300
[  136.170964]  ? calc_wheel_index+0x1b0/0x1b0
[  136.173033]  ? lock_downgrade+0x300/0x300
[  136.173332]  ? schedule_timeout+0x8da/0x990
[  136.173631]  ? _raw_spin_unlock_irq+0x35/0x50
[  136.173950]  ? console_conditional_schedule+0x30/0x30
[  136.177037]  ? __schedule+0x706/0x790
[  136.177317]  ? firmware_map_remove+0x1b4/0x1b4
[  136.177634]  ? firmware_map_remove+0x1b4/0x1b4
[  136.177958]  ? trace_hardirqs_on_caller+0x11/0x260
[  136.178635]  ? trace_hardirqs_on_thunk+0x1a/0x1c
[  136.178978]  ? preempt_schedule_irq+0x66/0x70
[  136.181059]  ? retint_kernel+0x2c/0x2c
[  136.181344]  ? stutter_wait+0x241/0x350
[  136.181621]  ? __rcu_read_unlock+0x190/0x190
[  136.181935]  ? collect_expired_timers+0x200/0x200
[  136.183095]  ? round_jiffies+0x120/0x120
[  136.183394]  ? ___might_sleep+0x17/0x350
[  136.183682]  ? stutter_wait+0x26a/0x350
[  136.183959]  ? torture_shutdown_absorb+0x120/0x120
[  136.186129]  ? console_unlock+0x589/0x620
[  136.186429]  ? vprintk_emit+0x389/0x3a0
[  136.186712]  ? pvclock_clocksource_read+0x130/0x220
[  136.189029]  ? pvclock_read_flags+0x50/0x50
[  136.189340]  ? debug_locks_off+0x59/0x60
[  136.189621]  ? kvm_clock_read+0x1e/0x30
[  136.189901]  ? kvm_sched_clock_read+0x5/0x10
[  136.191084]  ? check_preemption_disabled+0x110/0x120
[  136.191447]  ? preempt_count_sub+0x13/0xc0
[  136.191745]  ? rcu_torture_writer+0x89f/0xaa0
[  136.194024]  ? rcu_torture_pipe_update+0x290/0x290
[  136.194377]  ? firmware_map_remove+0x1b4/0x1b4
[  136.194705]  ? preempt_count_sub+0x13/0xc0
[  136.194999]  ? _raw_spin_unlock_irq+0x35/0x50
[  136.197100]  ? finish_task_switch+0x1b7/0x230
[  136.197423]  ? finish_task_switch+0x17c/0x230
[  136.197738]  ? preempt_count_sub+0x13/0xc0
[  136.198324]  ? schedule+0x235/0x2a0
[  136.198590]  ? preempt_schedule_common+0x80/0x80
[  136.198926]  ? ___preempt_schedule+0x16/0x18
[  136.201042]  ? rcu_torture_pipe_update+0x290/0x290
[  136.201393]  ? preempt_schedule_common+0x73/0x80
[  136.201724]  ? rcu_torture_pipe_update+0x290/0x290
[  136.203091]  ? kthread+0x22f/0x250
[  136.203349]  ? __kthread_create_on_node+0x2e0/0x2e0
[  136.203701]  ? ret_from_fork+0x24/0x30
[  136.203970] rcu_preempt: wait state: 3 ->state: 0x402
[  136.206018] rcu_sched: wait state: 1 ->state: 0x402
[  136.206374] rcu_bh: wait state: 1 ->state: 0x402
[  177.836571] Stack depot reached limit capacity
[  177.836631] ------------[ cut here ]------------
[  177.837316] WARNING: CPU: 0 PID: 545 at lib/stackdepot.c:119 depot_save_stack+0x28e/0x550
[  177.837987] CPU: 0 PID: 545 Comm: trinity-main Not tainted 4.14.0-04319-gd17a1d9 #30
[  177.838518] task: ffff88000cd80000 task.stack: ffff88000b4f8000
[  177.838933] RIP: 0010:depot_save_stack+0x28e/0x550
[  177.839268] RSP: 0018:ffff88000b4fec18 EFLAGS: 00010086
[  177.839632] RAX: 0000000000000022 RBX: 000000006bdd725e RCX: ffffffffa014cf41
[  177.840127] RDX: 0000000000000000 RSI: dffffc0000000000 RDI: 0000000000000000
[  177.840619] RBP: 0000000000000000 R08: 0000000000000001 R09: 0000000000000001
[  177.841117] R10: 0000000032ae8775 R11: 6f706564206b6361 R12: 0000000000000040
[  177.841609] R13: 0000000000000040 R14: 00000000000d725e R15: 0000000000000220
[  177.842104] FS:  000000000104a880(0000) GS:ffffffffa2ca4000(0000) knlGS:0000000000000000
[  177.842663] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  177.843061] CR2: 00007f3301646000 CR3: 000000000b1c2000 CR4: 00000000000006b0
[  177.843553] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[  177.844046] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[  177.844535] Call Trace:
[  177.844721]  ? kasan_kmalloc+0x144/0x160
[  177.844999]  ? depot_save_stack+0x1f5/0x550
[  177.845295]  ? do_raw_spin_unlock+0xda/0xf0
[  177.845590]  ? preempt_count_sub+0x13/0xc0
[  177.845887]  ? _raw_spin_unlock_irqrestore+0x52/0x70
[  177.846236]  ? depot_save_stack+0x3b8/0x550
[  177.846533]  ? kasan_kmalloc+0x144/0x160
[  177.846816]  ? do_raw_spin_unlock+0xda/0xf0
[  177.847113]  ? preempt_count_sub+0x13/0xc0
[  177.847404]  ? _raw_spin_unlock+0x29/0x40
[  177.847693]  ? get_partial_node+0x2ca/0x300
[  177.848031]  ? preempt_count_sub+0x13/0xc0
[  177.848321]  ? __might_sleep+0x2e/0xd0
[  177.848591]  ? perf_output_begin_backward+0x840/0x840
[  177.848952]  ? __vm_enough_memory+0x333/0x390
[  177.849260]  ? preempt_count_sub+0x13/0xc0
[  177.849551]  ? in_sched_functions+0x30/0x30
[  177.849851]  ? preempt_count_sub+0x13/0xc0
[  177.850142]  ? __vm_enough_memory+0x333/0x390
[  177.850453]  ? debug_lockdep_rcu_enabled+0x27/0x60
[  177.850794]  ? in_sched_functions+0x30/0x30
[  177.851090]  ? debug_lockdep_rcu_enabled+0x27/0x60
[  177.851425]  ? in_sched_functions+0x30/0x30
[  177.851724]  ? fs_reclaim_acquire+0xd/0x30
[  177.852013]  ? debug_lockdep_rcu_enabled+0x27/0x60
[  177.852348]  ? in_sched_functions+0x30/0x30
[  177.852642]  ? fs_reclaim_acquire+0xd/0x30
[  177.852936]  ? ___slab_alloc+0x2f9/0x330
[  177.853334]  ? debug_lockdep_rcu_enabled+0x27/0x60
[  177.853673]  ? ___slab_alloc+0x2f9/0x330
[  177.854070]  ? preempt_count_add+0xa6/0xc0
[  177.854358]  ? preempt_count_sub+0x13/0xc0
[  177.854647]  ? __vm_enough_memory+0x333/0x390
[  177.854956]  ? vm_memory_committed+0x10/0x10
[  177.855257]  ? __cleanup_sighand+0x30/0x30
[  177.855545]  ? rb_erase_cached+0x43a/0x1230
[  177.855845]  ? debug_show_all_locks+0x2c0/0x2c0
[  177.856176]  ? rb_next+0x80/0x80
[  177.856409]  ? __need_fs_reclaim+0x2d/0x80
[  177.856704]  ? kmem_cache_alloc+0xd4/0xf0
[  177.856991]  ? anon_vma_clone+0x6e/0x280
[  177.857271]  ? anon_vma_fork+0xb9/0x2f0
[  177.857545]  ? rcu_segcblist_enqueue+0xe7/0x130
[  177.857868]  ? rcu_segcblist_first_pend_cb+0x40/0x40
[  177.858217]  ? anon_vma_clone+0x280/0x280
[  177.858503]  ? __rcu_read_lock+0x20/0x20
[  177.858787]  ? trace_hardirqs_on_caller+0x11/0x260
[  177.859125]  ? lock_downgrade+0x300/0x300
[  177.859409]  ? lock_acquire+0x99/0xd0
[  177.859674]  ? perf_event_task_disable+0xe0/0xe0
[  177.860000]  ? copy_process+0x209c/0x3b60
[  177.860285]  ? __cleanup_sighand+0x30/0x30
[  177.860576]  ? perf_output_begin_backward+0x840/0x840
[  177.860932]  ? rb_erase_cached+0x43a/0x1230
[  177.861228]  ? debug_show_all_locks+0x2c0/0x2c0
[  177.861545]  ? rb_next+0x80/0x80
[  177.861780]  ? lock_downgrade+0x300/0x300
[  177.862066]  ? debug_object_active_state+0xdb/0x280
[  177.862409]  ? do_raw_spin_unlock+0xda/0xf0
[  177.862708]  ? preempt_count_sub+0x13/0xc0
[  177.862998]  ? _raw_spin_unlock_irqrestore+0x52/0x70
[  177.863345]  ? debug_object_active_state+0x23a/0x280
[  177.863695]  ? debug_object_assert_init+0x290/0x290
[  177.864037]  ? path_check_mount+0x130/0x130
[  177.864332]  ? rcu_segcblist_enqueue+0xe7/0x130
[  177.864651]  ? kmem_cache_alloc+0xd4/0xf0
[  177.864940]  ? anon_vma_clone+0x6e/0x280
[  177.865217]  ? anon_vma_fork+0xb9/0x2f0
[  177.865489]  ? rcu_segcblist_enqueue+0xe7/0x130
[  177.865812]  ? rcu_segcblist_first_pend_cb+0x40/0x40
[  177.866159]  ? anon_vma_clone+0x280/0x280
[  177.866442]  ? __rcu_read_lock+0x20/0x20
[  177.866725]  ? trace_hardirqs_on_caller+0x11/0x260
[  177.867060]  ? lock_downgrade+0x300/0x300
[  177.867343]  ? lock_acquire+0x99/0xd0
[  177.867602]  ? perf_event_task_disable+0xe0/0xe0
[  177.867932]  ? copy_process+0x209c/0x3b60
[  177.868218]  ? __cleanup_sighand+0x30/0x30
[  177.868509]  ? perf_output_begin_backward+0x840/0x840
[  177.868868]  ? rb_erase_cached+0x43a/0x1230
[  177.869165]  ? debug_show_all_locks+0x2c0/0x2c0
[  177.869484]  ? rb_next+0x80/0x80
[  177.869720]  ? lock_downgrade+0x300/0x300
[  177.870003]  ? debug_object_active_state+0xdb/0x280
[  177.870350]  ? do_raw_spin_unlock+0xda/0xf0
[  177.870645]  ? preempt_count_sub+0x13/0xc0
[  177.870936]  ? _raw_spin_unlock_irqrestore+0x52/0x70
[  177.871283]  ? debug_object_active_state+0x23a/0x280
[  177.871632]  ? debug_object_assert_init+0x290/0x290
[  177.871983]  ? path_check_mount+0x130/0x130
[  177.872280]  ? rcu_segcblist_enqueue+0xe7/0x130
[  177.872601]  ? rcu_segcblist_first_pend_cb+0x40/0x40
[  177.872954]  ? __lock_acquire+0xf8/0x1ea0
[  177.873239]  ? ___might_sleep+0x15c/0x350
[  177.873524]  ? __rcu_read_unlock+0x12f/0x190
[  177.873831]  ? __rcu_read_lock+0x20/0x20
[  177.874112]  ? trace_hardirqs_on_caller+0x11/0x260
[  177.874450]  ? lock_downgrade+0x300/0x300
[  177.874739]  ? lock_acquire+0x99/0xd0
[  177.875001]  ? perf_event_task_disable+0xe0/0xe0
[  177.875327]  ? perf_event_update_userpage+0x74c/0x790
[  177.875685]  ? preempt_count_sub+0x13/0xc0
[  177.875979]  ? __perf_event_header__init_id+0x17f/0x1c0
[  177.876345]  ? __debug_object_init+0x830/0x830
[  177.876663]  ? perf_log_itrace_start+0x2e0/0x2e0
[  177.876990]  ? perf_event_switch_output+0x225/0x350
[  177.877333]  ? perf_event_switch_output+0x30a/0x350
[  177.877680]  ? __lock_acquire+0xf8/0x1ea0
[  177.877963]  ? perf_event_set_state+0x11e/0x160
[  177.878281]  ? perf_event_update_time+0x190/0x190
[  177.878610]  ? debug_show_all_locks+0x2c0/0x2c0
[  177.878935]  ? __list_del_entry_valid+0xb5/0xd0
[  177.879252]  ? event_sched_out+0x502/0x560
[  177.879541]  ? perf_pmu_sched_task+0x1d0/0x1d0
[  177.879857]  ? perf_swevent_add+0x2ac/0x300
[  177.880151]  ? cpu_clock_event_add+0x100/0x100
[  177.880464]  ? perf_log_itrace_start+0x2e0/0x2e0
[  177.880796]  ? perf_iterate_ctx+0x44b/0x4a0
[  177.881096]  ? __rcu_read_unlock+0x12f/0x190
[  177.881397]  ? __rcu_read_lock+0x20/0x20
[  177.881681]  ? lock_downgrade+0x300/0x300
[  177.881964]  ? lock_acquire+0x99/0xd0
[  177.882224]  ? __perf_read_group_add+0x570/0x570
[  177.882546]  ? perf_iterate_sb+0x64c/0x6d0
[  177.882840]  ? perf_log_itrace_start+0x2e0/0x2e0
[  177.883164]  ? __perf_read_group_add+0x570/0x570
[  177.883486]  ? perf_pmu_nop_int+0x5/0x10
[  177.883767]  ? group_sched_in+0x189/0x1a0
[  177.884052]  ? __lock_acquire+0xf8/0x1ea0
[  177.884336]  ? lock_downgrade+0x300/0x300
[  177.884620]  ? lock_contended+0x540/0x540
[  177.884908]  ? debug_show_all_locks+0x2c0/0x2c0
[  177.885228]  ? check_preemption_disabled+0x110/0x120
[  177.885576]  ? __perf_event_task_sched_in+0x68e/0x6d0
[  177.885933]  ? perf_sched_cb_inc+0x160/0x160
[  177.886235]  ? perf_event_context_sched_out+0xb50/0xb50
[  177.886600]  ? __lock_acquire+0xf8/0x1ea0
[  177.886888]  ? debug_show_all_locks+0x2c0/0x2c0
[  177.887207]  ? _do_fork+0x17c/0x960
[  177.887457]  ? fork_idle+0x140/0x140
[  177.887715]  ? do_raw_spin_unlock+0xda/0xf0
[  177.888010]  ? preempt_count_sub+0x13/0xc0
[  177.888301]  ? _raw_spin_unlock_irq+0x35/0x50
[  177.888608]  ? finish_task_switch+0x1b7/0x230
[  177.888919]  ? finish_task_switch+0x17c/0x230
[  177.889226]  ? __schedule+0x706/0x790
[  177.889487]  ? firmware_map_remove+0x1b4/0x1b4
[  177.889803]  ? lock_downgrade+0x300/0x300
[  177.890088]  ? lock_acquire+0x99/0xd0
[  177.890351]  ? pvclock_clocksource_read+0x130/0x220
[  177.890696]  ? debug_lockdep_rcu_enabled+0x27/0x60
[  177.891033]  ? lock_downgrade+0x300/0x300
[  177.891317]  ? lock_acquire+0x99/0xd0
[  177.891578]  ? __might_fault+0x83/0x100
[  177.891856]  ? ptregs_sys_rt_sigreturn+0x10/0x10
[  177.892181]  ? do_syscall_64+0x343/0x5c0
[  177.892457]  ? syscall_return_slowpath+0x2b0/0x2b0
[  177.892796]  ? put_timespec64+0xb4/0xf0
[  177.893067]  ? get_timespec64+0x100/0x100
[  177.893350]  ? SyS_clock_gettime+0x128/0x170
[  177.893650]  ? trace_hardirqs_off_caller+0x11/0x100
[  177.893993]  ? trace_hardirqs_off_thunk+0x1a/0x1c
[  177.894322]  ? entry_SYSCALL64_slow_path+0x25/0x25
[  177.894653] Code: 8d 50 01 81 fa ff 1f 00 00 7e 27 80 3d 4f 97 da 02 00 0f 85 49 02 00 00 48 c7 c7 e0 ac b2 a2 c6 05 3b 97 da 02 01 e8 fd 50 76 ff <0f> ff e9 2f 02 00 00 83 c0 02 89 15 8e 6d 5b 05 48 c7 05 77 6d 
[  177.896048] ---[ end trace 4d3b89de53b313e4 ]---
[  197.600128] rcu-torture: rtc: ffffffffa4678f20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 barrier: 0/0:0 cbflood: 1
[  197.602642] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[  197.606721] rcu-torture: Reader Batch:  0 2 0 0 0 0 0 0 0 0 0
[  197.608213] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[  197.610781] ??? Writer stall state RTWS_STUTTER(8) g90795 c90794 f0x0 ->state 0x1 cpu 0
[  197.613428] rcu_preempt: wait state: 3 ->state: 0x402
[  197.614571] rcu_sched: wait state: 1 ->state: 0x402
[  197.616417] rcu_bh: wait state: 1 ->state: 0x402
[  259.040083] rcu-torture: rtc: ffffffffa4678f20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 barrier: 0/0:0 cbflood: 1
[  259.041817] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[  259.043055] rcu-torture: Reader Batch:  0 2 0 0 0 0 0 0 0 0 0
[  259.043770] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[  259.044906] ??? Writer stall state RTWS_STUTTER(8) g90907 c90906 f0x0 ->state 0x1 cpu 0
[  259.046083] rcu_preempt: wait state: 1 ->state: 0x402
[  259.046682] rcu_sched: wait state: 1 ->state: 0x402
[  259.048518] rcu_bh: wait state: 1 ->state: 0x402
[  320.480090] rcu-torture: rtc: ffffffffa4678f20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 1 barrier: 0/0:0 cbflood: 1
[  320.481911] rcu-torture: Reader Pipe:  2 0 0 0 0 0 0 0 0 0 0
[  320.484882] rcu-torture: Reader Batch:  0 2 0 0 0 0 0 0 0 0 0
[  320.486296] rcu-torture: Free-Block Circulation:  0 0 0 0 0 0 0 0 0 0 0
[  320.487356] ??? Writer stall state RTWS_STUTTER(8) g91004 c91003 f0x0 ->state 0x1 cpu 0
[  320.489499] rcu_preempt: wait state: 1 ->state: 0x402
[  320.490332] rcu_sched: wait state: 1 ->state: 0x402
[  320.490934] rcu_bh: wait state: 1 ->state: 0x402
[  370.609167] sh: can't kill pid 542: No such process
[  373.641475] sysrq: SysRq : Emergency Sync
[  373.642557] Emergency Sync complete
[  373.643295] sysrq: SysRq : Resetting

Elapsed time: 380

#!/bin/bash

# To reproduce,
# 1) save job-script and this script (both are attached in 0day report email)
# 2) run this script with your compiled kernel and optional env $INSTALL_MOD_PATH

kernel=$1

initrds=(
	/osimage/yocto/yocto-minimal-x86_64-2016-04-22.cgz
	/lkp/lkp/lkp-x86_64.cgz
	/osimage/pkg/debian-x86_64-2016-08-31.cgz/trinity-static-x86_64-x86_64-6ddabfd2_2017-11-10.cgz
)

HTTP_PREFIX=https://github.com/0day-ci/lkp-qemu/raw/master
wget --timestamping "${initrds[@]/#/$HTTP_PREFIX}"

{
	cat "${initrds[@]//*\//}"
	[[ $INSTALL_MOD_PATH ]] && (
		cd "$INSTALL_MOD_PATH"
		find lib | cpio -o -H newc --quiet | gzip
	)
	echo  job-script | cpio -o -H newc --quiet | gzip
} > initrd.img

qemu-img create -f qcow2 disk-vm-lkp-hsw01-yocto-x86_64-16-0 256G

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-kernel $kernel
	-initrd initrd.img
	-m 512
	-smp 1
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=disk-vm-lkp-hsw01-yocto-x86_64-16-0,media=disk,if=virtio
	-serial stdio
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-hsw01-yocto-x86_64-16::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-randconfig-i0-201748
	branch=linux-devel/devel-spot-201711261355
	commit=d17a1d97dc208d664c91cc387ffb752c7f85dc61
	BOOT_IMAGE=/pkg/linux/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/vmlinuz-4.14.0-04319-gd17a1d9
	max_uptime=1500
	RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-i0-201748/gcc-7/d17a1d97dc208d664c91cc387ffb752c7f85dc61/0
	result_service=tmpfs
	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[*]}"
