early console in setup code
early console in extract_kernel
input_data: 0x00000000033573b4
input_len: 0x00000000005ec788
output: 0x0000000001000000
output_len: 0x0000000001e88a48
kernel_total_size: 0x0000000002968000

Decompressing Linux... Parsing ELF... done.
Booting the kernel.
[    0.000000] Linux version 4.14.0-12905-g5f3cb7e (kbuild@intel11) (gcc version 6.4.0 20171026 (Debian 6.4.0-9)) #2 SMP Tue Nov 21 23:40:30 CST 2017
[    0.000000] Command line: ip=::::vm-vp-quantal-x86_64-31::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-31/boot-1-quantal-core-x86_64.cgz-5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d-20171121-89203-uuuwc2-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-r0-11211736 branch=linux-devel/devel-catchup-201711211821 commit=5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/vmlinuz-4.14.0-12905-g5f3cb7e max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/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=n
[    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-0x000000001ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000001ffe0000-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] bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    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] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x1ffe0 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  WT  UC- UC  WB  WT  UC- UC  
[    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 0x000f6a80-0x000f6a8f] mapped at [ffffffffff200a80]
[    0.000000]   mpc: f6a90-f6b74
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] BRK [0x03943000, 0x03943fff] PGTABLE
[    0.000000] BRK [0x03944000, 0x03944fff] PGTABLE
[    0.000000] BRK [0x03945000, 0x03945fff] PGTABLE
[    0.000000] BRK [0x03946000, 0x03946fff] PGTABLE
[    0.000000] BRK [0x03947000, 0x03947fff] PGTABLE
[    0.000000] BRK [0x03948000, 0x03948fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x1c9ba000-0x1ffdffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000001FFE1628 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000001FFE147C 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000001FFE0040 00143C (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000001FFE0000 000040
[    0.000000] ACPI: APIC 0x000000001FFE1570 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000001FFE15F0 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fd000 (        fee00000)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000001ffdffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x1c9b7000-0x1c9b9fff]
[    0.000000] kvm-clock: cpu 0, msr 0:1c9b5001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 826900855 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-0x000000001ffdffff]
[    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-0x000000001ffdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffdffff]
[    0.000000] On node 0 totalpages: 130942
[    0.000000]   DMA32 zone: 1792 pages used for memmap
[    0.000000]   DMA32 zone: 21 pages reserved
[    0.000000]   DMA32 zone: 130942 pages, LIFO batch:31
[    0.000000] Reserved but unavailable: 98 pages
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000)
[    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: 7645519600211568 ns
[    0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 488 pages/cpu @ffff88001c200000 s1961984 r8192 d28672 u2097152
[    0.000000] pcpu-alloc: s1961984 r8192 d28672 u2097152 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 1c3de040
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 129129
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: ip=::::vm-vp-quantal-x86_64-31::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-31/boot-1-quantal-core-x86_64.cgz-5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d-20171121-89203-uuuwc2-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-r0-11211736 branch=linux-devel/devel-catchup-201711211821 commit=5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/vmlinuz-4.14.0-12905-g5f3cb7e max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/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,11520
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] Calgary: detecting Calgary via BIOS EBDA area
[    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.000000] Memory: 413712K/523768K available (11212K kernel code, 1349K rwdata, 4720K rodata, 3720K init, 15080K bss, 110056K reserved, 0K cma-reserved)
[    0.000000] ftrace: allocating 41300 entries in 162 pages
[    0.004000] Running RCU self tests
[    0.004000] Hierarchical RCU implementation.
[    0.004000] 	RCU lockdep checking is enabled.
[    0.004000] 	RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2.
[    0.004000] 	RCU callback double-/use-after-free debug enabled.
[    0.004000] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.004000] NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16
[    0.004000] 	Offload RCU callbacks from CPUs: .
[    0.004000] console [ttyS0] enabled
[    0.004000] console [ttyS0] enabled
[    0.004000] bootconsole [earlyser0] disabled
[    0.004000] bootconsole [earlyser0] disabled
[    0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.004000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.004000] ... MAX_LOCK_DEPTH:          48
[    0.004000] ... MAX_LOCKDEP_KEYS:        8191
[    0.004000] ... CLASSHASH_SIZE:          4096
[    0.004000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.004000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.004000] ... CHAINHASH_SIZE:          32768
[    0.004000]  memory used by lock dependency info: 7903 kB
[    0.004000]  per task-struct memory footprint: 3072 bytes
[    0.004000] ------------------------
[    0.004000] | Locking API testsuite:
[    0.004000] ----------------------------------------------------------------------------
[    0.004000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]               recursive read-lock:             |  ok  |             |  ok  |
[    0.004000]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.004000]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.004000]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.004000]   mixed read-lock/lock-write ABBA:             |FAILED|             |  ok  |
[    0.004000]    mixed read-lock/lock-read ABBA:             |  ok  |             |  ok  |
[    0.004000]  mixed write-lock/lock-write ABBA:             |  ok  |             |  ok  |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.004000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.004000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.004000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.004000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.004000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.004000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.004000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.004000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.004000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq read-recursion/123:  ok  |
[    0.004000]       soft-irq read-recursion/123:  ok  |
[    0.004000]       hard-irq read-recursion/132:  ok  |
[    0.004000]       soft-irq read-recursion/132:  ok  |
[    0.004000]       hard-irq read-recursion/213:  ok  |
[    0.004000]       soft-irq read-recursion/213:  ok  |
[    0.004000]       hard-irq read-recursion/231:  ok  |
[    0.004000]       soft-irq read-recursion/231:  ok  |
[    0.004000]       hard-irq read-recursion/312:  ok  |
[    0.004000]       soft-irq read-recursion/312:  ok  |
[    0.004000]       hard-irq read-recursion/321:  ok  |
[    0.004000]       soft-irq read-recursion/321:  ok  |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]   | Wound/wait tests |
[    0.004000]   ---------------------
[    0.004000]                   ww api failures:  ok  |  ok  |  ok  |
[    0.004000]                ww contexts mixing:  ok  |  ok  |
[    0.004000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    0.004000]                locking mismatches:  ok  |  ok  |  ok  |
[    0.004000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]            spinlock nest unlocked:  ok  |
[    0.004000]   -----------------------------------------------------
[    0.004000]                                  |block | try  |context|
[    0.004000]   -----------------------------------------------------
[    0.004000]                           context:  ok  |  ok  |  ok  |
[    0.004000]                               try:  ok  |  ok  |  ok  |
[    0.004000]                             block:  ok  |  ok  |  ok  |
[    0.004000]                          spinlock:  ok  |  ok  |  ok  |
[    0.004000] -------------------------------------------------------
[    0.004000] Good, all 261 testcases passed! |
[    0.004000] ---------------------------------
[    0.004000] ACPI: Core revision 20170831
[    0.004000] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.004000] hpet clockevent registered
[    0.004033] APIC: Switch to symmetric I/O mode setup
[    0.004711] x2apic enabled
[    0.005226] Switched APIC routing to physical x2apic.
[    0.005755] enabled ExtINT on CPU#0
[    0.006734] ENABLING IO-APIC IRQs
[    0.007103] init IO_APIC IRQs
[    0.008023]  apic 0 pin 0 not connected
[    0.008457] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:0)
[    0.009318] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[    0.010148] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:0)
[    0.010947] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:0)
[    0.011742] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:0)
[    0.012000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:0)
[    0.012000]  apic 0 pin 16 not connected
[    0.012000]  apic 0 pin 17 not connected
[    0.012000]  apic 0 pin 18 not connected
[    0.012000]  apic 0 pin 19 not connected
[    0.012000]  apic 0 pin 20 not connected
[    0.012000]  apic 0 pin 21 not connected
[    0.012000]  apic 0 pin 22 not connected
[    0.012000]  apic 0 pin 23 not connected
[    0.012000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.012000] tsc: Detected 2693.508 MHz processor
[    0.012000] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=10774032)
[    0.012000] pid_max: default: 4096 minimum: 301
[    0.012212] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.013151] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.013977] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.014766] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.016464] CPU: Physical Processor ID: 0
[    0.016951] numa_add_cpu cpu 0 node 0: mask now 0
[    0.017513] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.018131] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.018817] debug: unmapping init [mem 0xffffffff82a84000-0xffffffff82a87fff]
[    0.020469] smpboot: Max logical packages: 2
[    0.020980] Using local APIC timer interrupts.
[    0.020980] calibrating APIC timer ...
[    0.024000] ... lapic delta = 6248898
[    0.024000] ... PM-Timer delta = 357887
[    0.024000] ... PM-Timer result ok
[    0.024000] ..... delta 6248898
[    0.024000] ..... mult: 268388125
[    0.024000] ..... calibration result: 3999294
[    0.024000] ..... CPU clock speed is 2692.3954 MHz.
[    0.024000] ..... host bus clock speed is 999.3294 MHz.
[    0.024000] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.024000] Performance Events: PMU not available due to virtualization, using software events only.
[    0.029368] Hierarchical SRCU implementation.
[    0.030404] smp: Bringing up secondary CPUs ...
[    0.032758] x86: Booting SMP configuration:
[    0.033261] .... node  #0, CPUs:      #1
[    0.004000] kvm-clock: cpu 1, msr 0:1c9b5041, secondary cpu clock
[    0.004000] masked ExtINT on CPU#1
[    0.004000] numa_add_cpu cpu 1 node 0: mask now 0-1
[    0.040002] KVM setup async PF for cpu 1
[    0.040002] kvm-stealtime: cpu 1, msr 1c5de040
[    0.041034] smp: Brought up 1 node, 2 CPUs
[    0.041034] ----------------
[    0.041034] | NMI testsuite:
[    0.041042] --------------------
[    0.041377]   remote IPI:  ok  |
[    0.041788]    local IPI:  ok  |
[    0.042164] --------------------
[    0.042494] Good, all   2 testcases passed! |
[    0.042922] ---------------------------------
[    0.044011] smpboot: Total of 2 processors activated (10774.03 BogoMIPS)
[    0.045278] devtmpfs: initialized
[    0.049155] workqueue: round-robin CPU selection forced, expect performance impact
[    0.050021] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.050021] futex hash table entries: 16 (order: -1, 2048 bytes)
[    0.052291] pinctrl core: initialized pinctrl subsystem
[    0.054133] NET: Registered protocol family 16
[    0.056309] audit: initializing netlink subsys (disabled)
[    0.057019] audit: type=2000 audit(1511279220.010:1): state=initialized audit_enabled=0 res=1
[    0.057057] cpuidle: using governor ladder
[    0.057621] ACPI: bus type PCI registered
[    0.060298] dca service started, version 1.12.1
[    0.060861] PCI: Using configuration type 1 for base access
[    0.096571] ACPI: Added _OSI(Module Device)
[    0.096788] ACPI: Added _OSI(Processor Device)
[    0.097244] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.097725] ACPI: Added _OSI(Processor Aggregator Device)
[    0.105485] ACPI: Interpreter enabled
[    0.105908] ACPI: (supports S0 S5)
[    0.106264] ACPI: Using IOAPIC for interrupt routing
[    0.106829] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.108701] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.126871] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.127521] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments]
[    0.128077] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.128778] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.131751] PCI host bridge to bus 0000:00
[    0.132016] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.132687] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.133364] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.134105] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window]
[    0.134843] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.135467] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.136958] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.138510] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.142070] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    0.143867] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.144717] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.145365] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.146064] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.147339] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.148382] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.149080] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.150853] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.152749] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.156813] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.163060] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.164691] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.166208] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.168012] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.173621] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.174954] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[    0.176238] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[    0.180338] pci_bus 0000:00: on NUMA node 0
[    0.182966] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.183943] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.184378] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.185295] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.186048] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.188395] ACPI: bus type USB registered
[    0.188937] usbcore: registered new interface driver usbfs
[    0.189534] usbcore: registered new interface driver hub
[    0.190128] usbcore: registered new device driver usb
[    0.190827] pps_core: LinuxPPS API ver. 1 registered
[    0.191318] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.193426] PCI: Using ACPI for IRQ routing
[    0.193846] PCI: pci_cache_line_size set to 64 bytes
[    0.194446] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.195037] e820: reserve RAM buffer [mem 0x1ffe0000-0x1fffffff]
[    0.197100] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.197592] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.202203] clocksource: Switched to clocksource kvm-clock
[    0.246676] VFS: Disk quotas dquot_6.6.0
[    0.247185] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.248042] FS-Cache: Loaded
[    0.248453] pnp: PnP ACPI init
[    0.248961] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.249744] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.250514] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.251205] pnp 00:03: [dma 2]
[    0.251570] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.252446] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.253260] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.254071] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.255637] pnp: PnP ACPI: found 7 devices
[    0.262223] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.263291] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.263891] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.264521] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.265192] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window]
[    0.266129] NET: Registered protocol family 1
[    0.266589] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.267174] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.267754] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.268439] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.269266] PCI: CLS 0 bytes, default 64
[    0.270002] Unpacking initramfs...
[    2.690498] debug: unmapping init [mem 0xffff88001c9ba000-0xffff88001ffdffff]
[    2.698193] cryptomgr_test (26) used greatest stack depth: 14784 bytes left
[    2.699507] cryptomgr_test (32) used greatest stack depth: 14528 bytes left
[    2.702846] AVX or AES-NI instructions are not detected.
[    2.703494] CPU feature 'AVX registers' is not supported.
[    2.704083] AVX2 or AES-NI instructions are not detected.
[    2.706053] Initialise system trusted keyrings
[    2.706633] Key type blacklist registered
[    2.707657] workingset: timestamp_bits=56 max_order=17 bucket_order=0
[    2.717065] NET: Registered protocol family 38
[    2.717630] Key type asymmetric registered
[    2.718043] Asymmetric key parser 'x509' registered
[    3.956613] String selftests succeeded
[    3.957710] tsc: Refined TSC clocksource calibration: 2693.512 MHz
[    3.957734] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34d96462, max_idle_ns: 440795289520 ns
[    3.962217] test_hexdump: all 1184 tests passed
[    3.963051] test_firmware: interface ready
[    3.984390] test_hash: __hash_32() has no arch implementation to test.
[    3.985032] test_hash: hash_32() has no arch implementation to test.
[    3.985641] test_hash: hash_64() has no arch implementation to test.
[    3.986248] test_hash: 33152 tests passed.
[    3.986666] test_siphash: self-tests: pass
[    3.988826] Running rhashtable test nelem=8, max_size=0, shrinking=0
[    3.989462] Test 00:
[    3.989743]   Adding 50000 keys
[    4.108709]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.163779]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.164656]   Deleting 50000 keys
[    4.233813]   Duration of test: 243736810 ns
[    4.234376] Test 01:
[    4.234739]   Adding 50000 keys
[    4.349409]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.402845]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.403667]   Deleting 50000 keys
[    4.472051]   Duration of test: 236997890 ns
[    4.472609] Test 02:
[    4.472969]   Adding 50000 keys
[    4.587073]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.641027]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.641843]   Deleting 50000 keys
[    4.710608]   Duration of test: 237320196 ns
[    4.711163] Test 03:
[    4.711530]   Adding 50000 keys
[    4.831468]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.886589]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    4.887433]   Deleting 50000 keys
[    4.957928]   Duration of test: 246083558 ns
[    4.982492] test if its possible to exceed max_size 8192: no, ok
[    4.983740] Average test time: 241034613
[    4.984167] Testing concurrent rhashtable access from 10 threads
[    9.500062] rhashtable_thra (119) used greatest stack depth: 14408 bytes left
[    9.800345] rhashtable_thra (128) used greatest stack depth: 14112 bytes left
[    9.810901] test 3125 add/delete pairs into rhlist
[   10.098475] test 3125 random rhlist add/delete operations
[   10.397584] Started 10 threads, 0 failed, rhltable test returns 0
[   10.398508] test passed
[   10.398988] rbtree testing
[   10.408611]  -> test 1 (latency of nnodes insert+delete): 25890 cycles
[   10.418502]  -> test 2 (latency of nnodes cached insert+delete): 24158 cycles
[   10.420190]  -> test 3 (latency of inorder traversal): 2551 cycles
[   10.420802]  -> test 4 (latency to fetch first node)
[   10.421288]         non-cached: 31 cycles
[   10.421681]         cached: 14 cycles
[   10.511984] augmented rbtree testing
[   10.533007]  -> test 1 (latency of nnodes insert+delete): 56588 cycles
[   10.555148]  -> test 2 (latency of nnodes cached insert+delete): 56843 cycles
[   10.674207] gpio_it87: no device
[   10.674663] gpio-mockup: probe of gpio-mockup failed with error -22
[   10.675922] switchtec: loaded.
[   10.676837] usbcore: registered new interface driver udlfb
[   10.677553] ipmi message handler version 39.2
[   10.678033] IPMI System Interface driver.
[   10.678707] ipmi_si: Unable to find any System Interface(s)
[   10.679271] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot.
[   10.681285] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   10.682054] ACPI: Power Button [PWRF]
[   10.683081] ioatdma: Intel(R) QuickData Technology Driver 4.00
[   10.718552] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   10.742669] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   10.766677] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   10.768449] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $
[   10.769169] ac.o: No PCI boards found.
[   10.769553] ac.o: For an ISA board you must supply memory and irq parameters.
[   10.770641] Linux agpgart interface v0.103
[   10.771583] [drm] radeon kernel modesetting enabled.
[   10.772324] [drm] amdgpu kernel modesetting enabled.
[   10.773928] [drm] Found bochs VGA, ID 0xb0c0.
[   10.774399] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[   10.775514] [TTM] Zone  kernel: Available graphics memory: 206856 kiB
[   10.776194] [TTM] Initializing pool allocator
[   10.776886] [TTM] Initializing DMA pool allocator
[   10.780809] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[   10.781630] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[   10.783104] dummy-irq: no IRQ given.  Use irq=N
[   10.784674] eql: Equalizer2002: Simon Janes (simon@ncm.com) and David S. Miller (davem@redhat.com)
[   10.787170] PPP generic driver version 2.4.2
[   10.787812] NET: Registered protocol family 24
[   10.788409] usbcore: registered new interface driver catc
[   10.788995] usbcore: registered new interface driver lan78xx
[   10.789574] usbcore: registered new interface driver ipheth
[   10.791535] uhci_hcd: USB Universal Host Controller Interface driver
[   10.792333] driver u132_hcd
[   10.792792] fotg210_hcd: FOTG210 Host Controller (EHCI) Driver
[   10.793421] Warning! fotg210_hcd should always be loaded before uhci_hcd and ohci_hcd, not after
[   10.794348] usbcore: registered new interface driver usbtmc
[   10.794964] usbcore: registered new interface driver mdc800
[   10.795513] mdc800: v0.7.5 (30/10/2000):USB Driver for Mustek MDC800 Digital Camera
[   10.796337] ftdi_elan: driver ftdi-elan
[   10.796789] usbcore: registered new interface driver ftdi-elan
[   10.797389] usbcore: registered new interface driver iowarrior
[   10.798011] usbcore: registered new interface driver isight_firmware
[   10.798662] usbcore: registered new interface driver ldusb
[   10.799262] usbcore: registered new interface driver legousbtower
[   10.799885] usbcore: registered new interface driver rio500
[   10.800508] usbcore: registered new interface driver usb_ehset_test
[   10.801455] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   10.803294] serio: i8042 KBD port at 0x60,0x64 irq 1
[   10.803849] serio: i8042 AUX port at 0x60,0x64 irq 12
[   10.805044] mousedev: PS/2 mouse device common for all mice
[   10.805655] evbug: Connected device: input0 (Power Button at LNXPWRBN/button/input0)
[   10.806590] i2c /dev entries driver
[   10.807510] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   10.808794] evbug: Connected device: input1 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[   10.810038] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   10.952618] usbcore: registered new interface driver i2c-diolan-u2c
[   10.953572] pps pps0: new PPS source ktimer
[   10.954007] pps pps0: ktimer PPS source registered
[   10.954551] Driver for 1-wire Dallas network protocol.
[   10.955182] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko
[   11.220164] applesmc: supported laptop not found!
[   11.220656] applesmc: driver init failed (ret=-19)!
[   11.580337] pc87360: PC8736x not detected, module not inserted
[   11.581542] usbcore: registered new interface driver pcwd_usb
[   11.582112] advantechwdt: WDT driver for Advantech single board computer initialising
[   11.583654] advantechwdt: initialized. timeout=60 sec (nowayout=1)
[   11.584342] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver v0.05
[   11.585255] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05
[   11.586059] i6300esb: cannot register miscdev on minor=130 (err=-16)
[   11.586930] i6300ESB timer: probe of 0000:00:04.0 failed with error -16
[   11.587716] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[   11.588365] nv_tco: NV TCO WatchDog Timer Driver v0.01
[   11.589035] sbc60xxwdt: I/O address 0x0443 already in use
[   11.589583] smsc37b787_wdt: SMsC 37B787 watchdog component driver 1.1 initialising...
[   11.591427] smsc37b787_wdt: Unable to register miscdev on minor 130
[   11.592115] machzwd: MachZ ZF-Logic Watchdog driver initializing
[   11.592695] machzwd: no ZF-Logic found
[   11.595854] hidraw: raw HID events driver (C) Jiri Kosina
[   11.598545] IDT PCI-E Non-Transparent Bridge Driver 2.0
[   11.599245]  fake-fmc-carrier: mezzanine 0
[   11.599652]       Manufacturer: fake-vendor
[   11.600115]       Product name: fake-design-for-testing
[   11.600739] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[   11.601450] fmc_trivial: probe of fake-design-for-testing-f001 failed with error -95
[   11.602217] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[   11.602923] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: no busid passed, refusing all cards
[   11.604002] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[   11.604900] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001"
[   11.606053] NET: Registered protocol family 26
[   11.606538] GACT probability on
[   11.606857] Mirror/redirect action on
[   11.607308] netem: version 1.3
[   11.607670] NET: Registered protocol family 17
[   11.608374] NET: Registered protocol family 35
[   11.608901] 8021q: 802.1Q VLAN Support v1.8
[   11.609421] NET: Registered protocol family 37
[   11.611023] ... APIC ID:      00000000 (0)
[   11.611478] ... APIC VERSION: 01050014
[   11.611860] 0000000000000000000000000000000000000000000000000000000000000000
[   11.612589] 0000000000000000000000000000000000000000000000000000000000000000
[   11.613320] 0000000000000000000000000000000000000000000000000000000020004000
[   11.614044] number of MP IRQ sources: 15.
[   11.614480] number of IO-APIC #0 registers: 24.
[   11.614931] testing the IO APIC.......................
[   11.615489] IO APIC #0......
[   11.615773] .... register #00: 00000000
[   11.616193] .......    : physical APIC id: 00
[   11.616621] .......    : Delivery Type: 0
[   11.617009] .......    : LTS          : 0
[   11.617426] .... register #01: 00170011
[   11.617811] .......     : max redirection entries: 17
[   11.618456] .......     : PRQ implemented: 0
[   11.619136] .......     : IO APIC version: 11
[   11.619806] .... register #02: 00000000
[   11.620463] .......     : arbitration: 00
[   11.621125] .... IRQ redirection table:
[   11.621692] IOAPIC 0:
[   11.621942]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.622843]  pin01, enabled , edge , high, V(23), IRR(0), S(0), physical, D(00), M(0)
[   11.624052]  pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[   11.625384]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.626456]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.627837]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.629229]  pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.630538]  pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.631902]  pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.633297]  pin09, enabled , level, high, V(21), IRR(0), S(0), physical, D(00), M(0)
[   11.634644]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.636073]  pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.637420]  pin0c, enabled , edge , high, V(22), IRR(0), S(0), physical, D(00), M(0)
[   11.638748]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.640156]  pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.641498]  pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.642818]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.644246]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.645508]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.646269]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.647114]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.647873]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.648738]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.649510]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   11.650287] IRQ to pin mappings:
[   11.650609] IRQ0 -> 0:2
[   11.650859] IRQ1 -> 0:1
[   11.651139] IRQ3 -> 0:3
[   11.651430] IRQ4 -> 0:4
[   11.651679] IRQ5 -> 0:5
[   11.651927] IRQ6 -> 0:6
[   11.652259] IRQ7 -> 0:7
[   11.652513] IRQ8 -> 0:8
[   11.652761] IRQ9 -> 0:9
[   11.653036] IRQ10 -> 0:10
[   11.653322] IRQ11 -> 0:11
[   11.653626] IRQ12 -> 0:12
[   11.653889] IRQ13 -> 0:13
[   11.654153] IRQ14 -> 0:14
[   11.654451] IRQ15 -> 0:15
[   11.654716] .................................... done.
[   11.655304] sched_clock: Marking stable (11652232828, 0)->(12379644272, -727411444)
[   11.656528] Loading compiled-in X.509 certificates
[   11.657524] kworker/u4:0 (143) used greatest stack depth: 14000 bytes left
[   11.659653] cryptomgr_probe (145) used greatest stack depth: 13472 bytes left
[   11.660445] Key type big_key registered
[   11.661652] Key type trusted registered
[   11.662906] console [netcon0] enabled
[   11.663311] netconsole: network logging started
[   11.664309] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[   11.664922] EDD information not available.
[   11.670921] debug: unmapping init [mem 0xffffffff826e2000-0xffffffff82a83fff]
[   11.672684] Write protecting the kernel read-only data: 18432k
[   11.675637] debug: unmapping init [mem 0xffff880001af7000-0xffff880001bfffff]
[   11.676632] debug: unmapping init [mem 0xffff88000209c000-0xffff8800021fffff]
[   11.680452] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[   11.681119] rodata_test: all tests were successful
[   11.682228] ------------[ cut here ]------------
[   11.682707] IRQs not disabled as expected
[   11.683114] 
[   11.683293] =============================
[   11.683695] WARNING: suspicious RCU usage
[   11.684103] 4.14.0-12905-g5f3cb7e #2 Not tainted
[   11.684572] -----------------------------
[   11.684976] arch/x86/kernel/traps.c:289 entry code didn't wake RCU!
[   11.685785] 
[   11.685785] other info that might help us debug this:
[   11.685785] 
[   11.686579] 
[   11.686579] RCU used illegally from idle CPU!
[   11.686579] rcu_scheduler_active = 2, debug_locks = 1
[   11.687646] RCU used illegally from extended quiescent state!
[   11.688228] no locks held by init/1.
[   11.688587] 
[   11.688587] stack backtrace:
[   11.689025] CPU: 1 PID: 1 Comm: init Not tainted 4.14.0-12905-g5f3cb7e #2
[   11.689697] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   11.690515] Call Trace:
[   11.690776]  dump_stack+0xa9/0xf7
[   11.691116]  do_error_trap+0x5a/0x117
[   11.691495]  ? trace_hardirqs_off_caller+0x3b/0x9d
[   11.691975]  ? irq_trace+0x2e/0x32
[   11.692330]  ? trace_hardirqs_off_thunk+0x1a/0x1c
[   11.692798]  ? time_hardirqs_off+0x12/0x2d
[   11.693222]  ? trace_hardirqs_off_caller+0x3b/0x9d
[   11.693704]  ? trace_hardirqs_off_thunk+0x1a/0x1c
[   11.694176]  ? restore_regs_and_return_to_kernel+0x32/0x32
[   11.694730]  invalid_op+0x1f/0x40
[   11.695074] RIP: 0010:rcu_eqs_exit+0x45/0x9a
[   11.695778] RSP: 0018:ffffc900000fff10 EFLAGS: 00010086
[   11.696484] RAX: 000000000000001d RBX: 0000000000000001 RCX: ffff88000029a040
[   11.697183] RDX: 0000001d0029a040 RSI: ffffffff810ec321 RDI: ffffffff810ea8e9
[   11.697888] RBP: 0000000000000001 R08: 0000000424d37a7e R09: 0000000000000001
[   11.698591] R10: 000000000000037f R11: ffff88000029a040 R12: ffff88000029a040
[   11.699309] R13: 0000000000000000 R14: 0000560473939524 R15: 0000000000001000
[   11.700013]  ? vprintk_func+0x9d/0xa5
[   11.700392]  ? console_unlock+0x4da/0x5cd
[   11.700801]  __context_tracking_exit+0x4d/0x1c0
[   11.701265]  enter_from_user_mode+0x55/0x5a
[   11.701689]  do_syscall_64+0x23/0x96
[   11.702052]  entry_SYSCALL64_slow_path+0x25/0x25
[   11.702522] RIP: 0033:0x7ff2ed2e9e7a
[   11.702882] RSP: 002b:00007fffaa6c90a8 EFLAGS: 00000246 ORIG_RAX: 000000000000000c
[   11.703632] RAX: ffffffffffffffda RBX: 0000000000000009 RCX: 00007ff2ed2e9e7a
[   11.704339] RDX: 0000000000000000 RSI: 00007ff2ed2ee720 RDI: 0000000000000000
[   11.705039] RBP: 00000000078afbfd R08: 00007fffaa6c9369 R09: 00007fffaa768000
[   11.705746] R10: 000000000000037f R11: 0000000000000246 R12: 00007fffaa6c9379
[   11.706456] R13: 0000000000000000 R14: 0000560473939524 R15: 0000000000001000
[   11.707176] WARNING: CPU: 1 PID: 1 at kernel/rcu/tree.c:962 rcu_eqs_exit+0x45/0x9a
[   11.708231] Modules linked in:
[   11.708547] CPU: 1 PID: 1 Comm: init Not tainted 4.14.0-12905-g5f3cb7e #2
[   11.709216] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   11.710034] task: ffff88000029a040 task.stack: ffffc900000fc000
[   11.710629] RIP: 0010:rcu_eqs_exit+0x45/0x9a
[   11.711166] RSP: 0018:ffffc900000fff10 EFLAGS: 00010086
[   11.711702] RAX: 000000000000001d RBX: 0000000000000001 RCX: ffff88000029a040
[   11.712406] RDX: 0000001d0029a040 RSI: ffffffff810ec321 RDI: ffffffff810ea8e9
[   11.713101] RBP: 0000000000000001 R08: 0000000424d37a7e R09: 0000000000000001
[   11.713805] R10: 000000000000037f R11: ffff88000029a040 R12: ffff88000029a040
[   11.714534] R13: 0000000000000000 R14: 0000560473939524 R15: 0000000000001000
[   11.715237] FS:  0000000000000000(0000) GS:ffff88001c400000(0000) knlGS:0000000000000000
[   11.716026] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   11.716599] CR2: 00007ff2ed2edf3c CR3: 000000000fc24000 CR4: 00000000000006a0
[   11.717307] Call Trace:
[   11.717569]  __context_tracking_exit+0x4d/0x1c0
[   11.718025]  enter_from_user_mode+0x55/0x5a
[   11.718452]  do_syscall_64+0x23/0x96
[   11.718817]  entry_SYSCALL64_slow_path+0x25/0x25
[   11.719283] RIP: 0033:0x7ff2ed2e9e7a
[   11.719645] RSP: 002b:00007fffaa6c90a8 EFLAGS: 00000246 ORIG_RAX: 000000000000000c
[   11.720394] RAX: ffffffffffffffda RBX: 0000000000000009 RCX: 00007ff2ed2e9e7a
[   11.721092] RDX: 0000000000000000 RSI: 00007ff2ed2ee720 RDI: 0000000000000000
[   11.721798] RBP: 00000000078afbfd R08: 00007fffaa6c9369 R09: 00007fffaa768000
[   11.722508] R10: 000000000000037f R11: 0000000000000246 R12: 00007fffaa6c9379
[   11.723209] R13: 0000000000000000 R14: 0000560473939524 R15: 0000000000001000
[   11.723911] Code: 01 00 00 00 75 27 83 b8 c4 00 00 00 00 74 1e 80 3d 99 2a 24 01 00 75 15 48 c7 c7 f7 b2 f3 81 c6 05 89 2a 24 01 01 e8 35 ca f9 ff <0f> ff 48 c7 c0 40 0f 1d 00 65 48 03 05 8a cb f0 7e 48 8b 10 48 
[   11.725883] ---[ end trace acb57807445c80ab ]---
[   11.726527] ------------[ cut here ]------------
[   11.727019] IRQs not disabled as expected
[   11.727447] WARNING: CPU: 1 PID: 1 at kernel/sched/cputime.c:262 get_vtime_delta+0x5a/0xd1
[   11.728451] Modules linked in:
[   11.728769] CPU: 1 PID: 1 Comm: init Tainted: G        W        4.14.0-12905-g5f3cb7e #2
[   11.729565] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   11.730406] task: ffff88000029a040 task.stack: ffffc900000fc000
[   11.730995] RIP: 0010:get_vtime_delta+0x5a/0xd1
[   11.731454] RSP: 0018:ffffc900000ffed8 EFLAGS: 00010086
[   11.731973] RAX: 000000000000001d RBX: ffff88000029a040 RCX: ffff88000029a040
[   11.732683] RDX: 0000001d0029a040 RSI: ffffffff810ec321 RDI: ffffffff810ea8e9
[   11.733392] RBP: 0000000000000fc0 R08: 00000004286234a9 R09: 0000000000000001
[   11.734094] R10: ffff88000029a040 R11: 0000000000000002 R12: ffff88000029a410
[   11.734801] R13: 00007fffaa7687a8 R14: 000000000000003c R15: 00007fffaa768120
[   11.735512] FS:  0000000000000000(0000) GS:ffff88001c400000(0000) knlGS:0000000000000000
[   11.736307] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   11.736877] CR2: 00007fffaa768038 CR3: 000000000fc24000 CR4: 00000000000006a0
[   11.737586] Call Trace:
[   11.737850]  vtime_user_exit+0x21/0x61
[   11.738239]  __context_tracking_exit+0x68/0x1c0
[   11.738695]  enter_from_user_mode+0x55/0x5a
[   11.739118]  do_syscall_64+0x23/0x96
[   11.739491]  entry_SYSCALL64_slow_path+0x25/0x25
[   11.739955] RIP: 0033:0x7ff2ed2eb317
[   11.740324] RSP: 002b:00007fffaa6c8e58 EFLAGS: 00000246 ORIG_RAX: 0000000000000015
[   11.741073] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007ff2ed2eb317
[   11.741789] RDX: 00007ff2ed4f5dc0 RSI: 0000000000000000 RDI: 00007ff2ed2ef628
[   11.742502] RBP: 00007fffaa6c8f00 R08: 0000000000000000 R09: 0000000000000001
[   11.743219] R10: 756e694c00000000 R11: 0000000000000246 R12: 0000000000000000
[   11.743927] R13: 00007fffaa7687a8 R14: 000000000000003c R15: 00007fffaa768120
[   11.744644] Code: 01 00 00 00 75 27 83 b8 c4 00 00 00 00 74 1e 80 3d c3 47 27 01 00 75 15 48 c7 c7 f7 b2 f3 81 c6 05 b3 47 27 01 01 e8 68 e7 fc ff <0f> ff 8b 05 92 19 9d 01 85 c0 7f 0e 31 db 41 83 7c 24 48 00 75 
[   11.747099] ---[ end trace acb57807445c80ac ]---
[   11.770806] process 1 (init) attempted a POSIX timer syscall while CONFIG_POSIX_TIMERS is not set
[   11.852764] init: Failed to create pty - disabling logging for job
[   11.853583] init: Temporary process spawn error: No such file or directory
[   11.871117] init: Failed to create pty - disabling logging for job
[   11.871888] init: Temporary process spawn error: No such file or directory
[   11.913577] init: Failed to create pty - disabling logging for job
[   11.914346] init: Temporary process spawn error: No such file or directory
[   11.917184] init: Failed to create pty - disabling logging for job
[   11.917975] init: Temporary process spawn error: No such file or directory
[   11.962647] init: Failed to create pty - disabling logging for job
[   11.963408] init: Temporary process spawn error: No such file or directory
[   11.966209] init: Failed to create pty - disabling logging for job
[   11.966950] init: Temporary process spawn error: No such file or directory
[   11.973261] init: Failed to create pty - disabling logging for job
[   11.974035] init: Temporary process spawn error: No such file or directory
[   11.981756] init: Failed to create pty - disabling logging for job
[   11.982518] init: Temporary process spawn error: No such file or directory
[   11.984236] udevd[225]: starting version 175
[   11.990082] init: Failed to create pty - disabling logging for job
[   11.990841] init: Temporary process spawn error: No such file or directory
[   11.993879] init: Failed to create pty - disabling logging for job
[   11.994646] init: Temporary process spawn error: No such file or directory
[   12.000353] init: Failed to create pty - disabling logging for job
[   12.001110] init: Temporary process spawn error: No such file or directory
[   12.014148] init: Failed to create pty - disabling logging for job
[   12.014910] init: Temporary process spawn error: No such file or directory
udevd[237]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXSYSTM:': No such file or directory
udevd[243]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00007000sv00001AF4sd00001100bc06sc01i00': No such file or directory
udevd[246]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00001237sv00001AF4sd00001100bc06sc00i00': No such file or directory
udevd[256]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d0000100Esv00001AF4sd00001100bc02sc00i00': No such file or directory
udevd[258]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00007010sv00001AF4sd00001100bc01sc01i80': No such file or directory
udevd[259]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:QEMU0002:': No such file or directory
udevd[260]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXSYBUS:': No such file or directory
udevd[264]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:gpio-mockup': No such file or directory
udevd[265]: failed to execute '/sbin/modprobe' '/sbin/modprobe -q fbcon': No such file or directory
[   12.128365] init: Failed to create pty - disabling logging for job
[   12.129106] init: Temporary process spawn error: No such file or directory
[   12.131724] init: Failed to create pty - disabling logging for job
[   12.132545] init: Temporary process spawn error: No such file or directory
udevd[271]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:platform-framebuffer': No such file or directory
udevd[247]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv input:b0019v0000p0001e0000-e0,1,k74,ramlsfw': No such file or directory
udevd[261]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXSYBUS:': No such file or directory
udevd[272]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:pcspkr': No such file or directory
[   12.190660] init: Temporary process spawn error: No such file or directory
[   12.202177] init: Failed to create pty - disabling logging for job
[   12.202947] init: Temporary process spawn error: No such file or directory

udevd[257]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:NV_TCO': No such file or directory
udevd[283]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d000025ABsv00001AF4sd00001100bc08sc80i00': No such file or directory
udevd[275]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv dmi:bvnSeaBIOS:bvr1.10.2-1:bd04/01/2014:svnQEMU:pnStandardPC(i440FX+PIIX,1996):pvrpc-i440fx-2.10:cvnQEMU:ct1:cvrpc-i440fx-2.10:': No such file or directory
udevd[262]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:SP5100 TCO timer': No such file or directory
udevd[285]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0103:': No such file or directory
udevd[284]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv serio:ty01pr00id00ex00': No such file or directory
udevd[287]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv input:b0011v0001p0001eAB41-e0,1,4,11,14,k71,72,73,74,75,76,77,79,7A,7B,7C,7D,7E,7F,80,8C,8E,8F,9B,9C,9D,9E,9F,A3,A4,A5,A6,AC,AD,B7,B8,B9,D9,E2,ram4,l0,1,2,sfw': No such file or directory
[   12.263392] init: Failed to create pty - disabling logging for job
[   12.264207] init: Temporary process spawn error: No such file or directory
udevd[297]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory
udevd[298]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory
udevd[293]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:ACPI0010:PNP0A05:': No such file or directory
udevd[296]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A03:': No such file or directory

[   12.309261] ip (313) used greatest stack depth: 13136 bytes left
udevd[295]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0103:': No such file or directory
udevd[303]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory
udevd[315]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory
udevd[305]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory
udevd[316]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:QEMU0002:': No such file or directory
udevd[314]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXCPU:': No such file or directory
udevd[312]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory
udevd[317]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXCPU:': No such file or directory
udevd[320]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory
udevd[318]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory
[   12.372919] init: failsafe main process (300) killed by TERM signal
udevd[361]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0303:': No such file or directory
udevd[366]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0501:': No such file or directory
udevd[364]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0700:': No such file or directory
udevd[363]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0501:': No such file or directory
udevd[365]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0F13:': No such file or directory
udevd[360]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0400:': No such file or directory
udevd[368]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0B00:': No such file or directory
[   12.471537] init: Failed to create pty - disabling logging for job
[   12.472505] init: Temporary process spawn error: No such file or directory
[   12.474506] init: Failed to create pty - disabling logging for job
[   12.474611] init: Temporary process spawn error: No such file or directory
[   12.478805] init: Failed to create pty - disabling logging for job
[   12.479566] init: Temporary process spawn error: No such file or directory
[   12.484605] init: Failed to create pty - disabling logging for job
[   12.485371] init: Temporary process spawn error: No such file or directory
[   12.492875] init: Failed to create pty - disabling logging for job
[   12.493637] init: Temporary process spawn error: No such file or directory
[   12.499103] init: rc-sysinit main process (335) killed by TERM signal
start: Job is already running: rc
PATH=/sbin:/usr/sbin:/bin:/usr/bin:/lkp/lkp/src/bin
Kernel tests: Boot OK!
Kernel tests: Boot OK!
umount: /tmp: device is busy.
        (In some cases useful info about processes that use
         the device is found by lsof(8) or fuser(1))
Trinity v1.4pre  Dave Jones <davej@redhat.com>
LKP: HOSTNAME vm-vp-quantal-x86_64-31, MAC e2:ff:47:95:1a:58, kernel 4.14.0-12905-g5f3cb7e 2, serial console /dev/ttyS0
[init] Marking syscall get_robust_list (64bit:274 32bit:312) as to be disabled.
Done parsing arguments.
Marking all syscalls as enabled.
[init] Disabling syscalls marked as disabled by command line options
[init] Marked 64-bit syscall get_robust_list (274) as deactivated.
[init] Marked 32-bit syscall get_robust_list (312) as deactivated.
[init] 32-bit syscalls: 350 enabled, 1 disabled.  64-bit syscalls: 313 enabled, 1 disabled.
DANGER: RUNNING AS ROOT.
Unless you are running in a virtual machine, this could cause serious problems such as overwriting CMOS
or similar which could potentially make this machine unbootable without a firmware reset.

ctrl-c now unless you really know what you are doing.
[   12.969697] 
[   13.052749] hostname: the specified hostname is invalid
[   13.052759] 
[   13.068681] init: Failed to create pty - disabling logging for job
[   13.069448] init: Temporary process spawn error: No such file or directory
[   13.074387] init: Failed to create pty - disabling logging for job
[   13.075149] init: Temporary process spawn error: No such file or directory
[   13.107661] init: networking main process (470) terminated with status 1
[   13.119238] LKP: waiting for network...
[   13.119247] 
[   22.478993] init: tty4 main process ended, respawning
[   22.480982] init: tty5 main process (378) terminated with status 1
[   22.482277] init: tty5 main process ended, respawning
[   22.503318] init: tty2 main process (379) terminated with status 1
[   22.504624] init: tty2 main process ended, respawning
[   22.507160] init: tty3 main process (380) terminated with status 1
[   22.508466] init: tty3 main process ended, respawning
[   22.511638] init: tty6 main process (382) terminated with status 1
[   22.513032] init: tty6 main process ended, respawning
[init] Using pid_max = 4096
[init] Kernel was tainted on startup. Will ignore flags that are already set.
[init] Started watchdog process, PID is 2708
[main] Main thread is alive.
[main] Setsockopt(1 12 cee000 4) on fd 7 [1:2:1]
[main] Setsockopt(1 24 cee000 4) on fd 8 [17:3:768]
[main] Setsockopt(1 2f cee000 4) on fd 9 [26:2:184]
[main] Setsockopt(113 4 cee000 94) on fd 10 [35:5:0]
[main] Setsockopt(1 19 cee000 30) on fd 11 [37:5:2]
[main] Setsockopt(10c 7 cee000 4) on fd 14 [26:1:208]
[main] Setsockopt(1 24 cee000 18) on fd 16 [37:5:2]
[main] Setsockopt(10e 5 cee000 41) on fd 18 [16:3:2]
[main] Setsockopt(107 9 cee000 4) on fd 19 [17:3:768]
[main] Setsockopt(10c 8 cee000 4) on fd 20 [26:1:190]
[main] Setsockopt(1 24 cee000 c9) on fd 21 [35:2:0]
[main] Setsockopt(116 7f cee000 f0) on fd 22 [37:1:0]
[main] Setsockopt(1 1d cee000 b6) on fd 23 [1:5:1]
[main] Setsockopt(1 5 cee000 4) on fd 25 [26:2:243]
[main] Setsockopt(1 21 cee000 a8) on fd 26 [35:2:0]
[main] Setsockopt(1 5 cee000 4) on fd 27 [37:1:0]
[main] Setsockopt(10e 5 cee000 4) on fd 29 [16:3:4]
[main] Setsockopt(107 5 cee000 1c) on fd 30 [17:3:768]
[main] Setsockopt(1 6 cee000 4) on fd 31 [26:1:187]
[main] Setsockopt(1 f cee000 b4) on fd 33 [37:5:5]
[main] Setsockopt(1 2d cee000 4) on fd 34 [1:2:1]
[main] Setsockopt(1 10 cee000 da) on fd 35 [17:10:768]
[main] Setsockopt(1 20 cee000 4) on fd 36 [26:1:168]
[main] Setsockopt(116 7f cee000 4) on fd 38 [37:5:2]
[main] Setsockopt(1 9 cee000 f) on fd 40 [17:10:768]
[main] Setsockopt(1 19 cee000 2e) on fd 41 [26:2:139]
[main] Setsockopt(1 24 cee000 4) on fd 42 [35:2:0]
[main] Setsockopt(116 7f cee000 4) on fd 43 [37:5:2]
[main] Setsockopt(1 2a cee000 8a) on fd 44 [1:5:1]
[main] Setsockopt(1 2 cee000 d5) on fd 45 [17:10:768]
[main] Setsockopt(1 b cee000 e) on fd 46 [26:2:188]
[main] Setsockopt(1 d cee000 8) on fd 47 [35:5:0]
[main] Setsockopt(116 7f cee000 78) on fd 53 [37:1:0]
[main] Setsockopt(1 f cee000 4) on fd 54 [1:2:1]
[main] Setsockopt(10e 3 cee000 cf) on fd 55 [16:2:4]
[main] Setsockopt(116 7f cee000 41) on fd 59 [37:1:2]
[main] Setsockopt(1 10 cee000 a4) on fd 61 [16:3:11]
[main] Setsockopt(1 20 cee000 4) on fd 64 [35:2:0]
[main] Setsockopt(1 a cee000 38) on fd 65 [37:1:4]
[main] Setsockopt(1 2a cee000 4) on fd 66 [1:1:1]
[main] Setsockopt(107 12 cee000 4) on fd 68 [17:3:768]
[main] Setsockopt(1 2b cee000 4) on fd 69 [26:2:69]
[main] Setsockopt(1 12 cee000 ae) on fd 70 [35:5:0]
[main] Setsockopt(1 29 cee000 8c) on fd 71 [37:1:2]
[main] Setsockopt(1 a cee000 5d) on fd 72 [1:1:1]
[main] Setsockopt(1 2b cee000 4) on fd 73 [16:2:15]
[main] Setsockopt(107 5 cee000 10) on fd 74 [17:3:768]
[main] Setsockopt(1 5 cee000 46) on fd 76 [35:2:0]
[main] Setsockopt(116 7f cee000 4) on fd 77 [37:5:2]
[main] Setsockopt(1 6 cee000 e1) on fd 79 [17:2:768]
[main] Setsockopt(1 1d cee000 d4) on fd 80 [26:1:252]
[main] Setsockopt(1 f cee000 4) on fd 81 [35:5:0]
[main] Setsockopt(116 7f cee000 4) on fd 82 [37:1:1]
[main] Setsockopt(1 f cee000 4) on fd 84 [16:3:16]
[main] Setsockopt(1 2e cee000 4) on fd 85 [17:2:768]
[main] Setsockopt(1 24 cee000 d1) on fd 87 [35:5:0]
[main] Setsockopt(1 25 cee000 4) on fd 90 [17:3:768]
[main] Setsockopt(1 21 cee000 2f) on fd 91 [26:1:28]
[main] Setsockopt(1 1 cee000 4) on fd 92 [35:5:0]
[main] Setsockopt(1 12 cee000 c7) on fd 93 [37:5:1]
[main] Setsockopt(107 e cee000 4) on fd 95 [17:2:768]
[main] Setsockopt(1 c cee000 34) on fd 96 [26:2:109]
[main] Setsockopt(1 7 cee000 3d) on fd 97 [35:5:0]
[main] Setsockopt(10e 4 cee000 4) on fd 100 [16:3:15]
[main] Setsockopt(1 21 cee000 4) on fd 101 [17:3:9]
[main] Setsockopt(10c 2 cee000 4) on fd 102 [26:2:96]
[main] Setsockopt(1 9 cee000 f0) on fd 103 [35:5:0]
[main] Setsockopt(1 23 cee000 e5) on fd 104 [37:5:2]
[main] Setsockopt(1 21 cee000 d7) on fd 105 [1:5:1]
[main] Setsockopt(1 1d cee000 90) on fd 107 [26:2:140]
[main] Setsockopt(1 c cee000 4) on fd 109 [37:5:0]
[main] Setsockopt(1 23 cee000 fe) on fd 110 [1:2:1]
[main] Setsockopt(10c 9 cee000 4) on fd 112 [26:1:163]
[main] Setsockopt(116 7f cee000 4) on fd 114 [37:5:2]
[main] Setsockopt(1 22 cee000 4) on fd 115 [1:5:1]
[main] Setsockopt(107 5 cee000 10) on fd 116 [17:2:768]
[main] Setsockopt(1 e cee000 4) on fd 117 [26:1:232]
[main] Setsockopt(116 7f cee000 4) on fd 119 [37:1:2]
[main] Setsockopt(1 10 cee000 d0) on fd 120 [1:2:1]
[main] Setsockopt(10e 3 cee000 84) on fd 121 [16:2:11]
[main] Setsockopt(1 9 cee000 4) on fd 123 [26:1:190]
[main] Setsockopt(1 8 cee000 8a) on fd 124 [35:5:0]
[main] Setsockopt(1 2d cee000 4) on fd 125 [37:1:0]
[main] Setsockopt(1 2c cee000 a4) on fd 128 [26:2:119]
[main] Setsockopt(1 e cee000 4) on fd 131 [1:1:1]
[main] Setsockopt(1 a cee000 4) on fd 133 [17:3:768]
[main] Setsockopt(10c 2 cee000 4) on fd 134 [26:2:3]
[main] Setsockopt(1 20 cee000 4) on fd 135 [35:2:0]
[main] Setsockopt(116 7f cee000 4) on fd 136 [37:1:1]
[main] Setsockopt(1 10 cee000 4) on fd 137 [1:2:1]
[main] Setsockopt(107 f cee000 21) on fd 138 [17:3:768]
[main] Setsockopt(1 1d cee000 9f) on fd 139 [26:1:186]
[main] Setsockopt(1 2e cee000 4) on fd 140 [35:5:0]
[main] Setsockopt(1 9 cee000 4) on fd 141 [37:1:2]
[main] Setsockopt(1 9 cee000 4) on fd 142 [1:5:1]
[main] Setsockopt(1 2b cee000 4) on fd 145 [26:2:132]
[main] Setsockopt(1 12 cee000 a0) on fd 146 [35:2:0]
[main] Setsockopt(1 2e cee000 4) on fd 147 [37:1:1]
[main] Setsockopt(1 1 cee000 fa) on fd 149 [17:2:768]
[main] Setsockopt(10c 9 cee000 4) on fd 150 [26:1:119]
[main] Setsockopt(1 c cee000 4) on fd 151 [35:2:0]
[main] Setsockopt(1 a cee000 4) on fd 152 [37:1:3]
[main] Setsockopt(1 29 cee000 21) on fd 153 [1:1:1]
[main] Setsockopt(1 24 cee000 d0) on fd 154 [16:3:15]
[main] Setsockopt(1 2b cee000 61) on fd 159 [1:1:1]
[main] Setsockopt(107 9 cee000 4) on fd 160 [17:2:768]
[main] Setsockopt(1 19 cee000 4) on fd 162 [35:2:0]
[main] Setsockopt(1 f cee000 39) on fd 163 [37:1:4]
[main] Setsockopt(1 8 cee000 4) on fd 164 [1:2:1]
[main] Setsockopt(1 2d cee000 4) on fd 165 [17:3:768]
[main] Setsockopt(1 8 cee000 cd) on fd 167 [35:5:0]
[main] Setsockopt(1 19 cee000 9d) on fd 168 [37:1:2]
[main] Setsockopt(10e 5 cee000 4) on fd 170 [16:2:2]
[main] Setsockopt(1 10 cee000 4) on fd 171 [17:2:768]
[main] Setsockopt(1 23 cee000 4) on fd 174 [37:1:5]
[main] Setsockopt(107 9 cee000 4) on fd 176 [17:3:768]
[main] Setsockopt(1 9 cee000 4) on fd 177 [26:1:164]
[main] Setsockopt(1 d cee000 8) on fd 178 [35:5:0]
[main] Setsockopt(1 2e cee000 4) on fd 180 [1:5:1]
[main] Setsockopt(1 10 cee000 ef) on fd 181 [17:10:768]
[main] Setsockopt(1 29 cee000 4) on fd 182 [26:1:8]
[main] Setsockopt(1 a cee000 a0) on fd 183 [35:2:0]
[main] Setsockopt(116 7f cee000 4) on fd 184 [37:1:2]
[main] Setsockopt(1 2a cee000 68) on fd 185 [1:5:1]
[main] Setsockopt(10e 3 cee000 4) on fd 186 [16:3:9]
[main] Setsockopt(1 12 cee000 4) on fd 187 [17:10:768]
[main] Setsockopt(1 2e cee000 9a) on fd 188 [24:2:0]
[main] Setsockopt(116 7f cee000 f7) on fd 191 [37:5:2]
[main] Setsockopt(1 c cee000 f) on fd 192 [1:2:1]
[main] Setsockopt(1 2e cee000 74) on fd 193 [17:3:768]
[main] Setsockopt(1 24 cee000 4) on fd 194 [26:2:137]
[main] Setsockopt(1 1 cee000 2c) on fd 195 [35:5:0]
[main] Setsockopt(116 7f cee000 4) on fd 196 [37:5:1]
[main] Setsockopt(1 2f cee000 4) on fd 199 [26:2:197]
[main] Setsockopt(116 7f cee000 4) on fd 201 [37:1:0]
[main] Setsockopt(10e 4 0 0) on fd 203 [16:3:9]
[main] Setsockopt(1 12 cee000 4) on fd 205 [26:2:183]
[main] Setsockopt(1 28 cee000 4) on fd 207 [37:1:0]
[main] Setsockopt(1 9 cee000 53) on fd 209 [16:2:16]
[main] Setsockopt(1 8 cee000 80) on fd 210 [17:10:768]
[main] Setsockopt(1 2e cee000 4) on fd 211 [26:1:71]
[main] Setsockopt(1 d cee000 8) on fd 212 [35:5:0]
[main] Setsockopt(1 5 cee000 de) on fd 213 [37:5:5]
[main] Setsockopt(1 d cee000 8) on fd 216 [26:2:189]
[main] Setsockopt(1 19 cee000 9a) on fd 218 [37:1:0]
[main] Setsockopt(1 2e cee000 4) on fd 219 [1:5:1]
[main] Setsockopt(107 5 cee000 10) on fd 220 [17:2:768]
[main] Setsockopt(1 28 cee000 4) on fd 222 [35:2:0]
[main] Setsockopt(116 7f cee000 4) on fd 223 [37:1:1]
[main] Setsockopt(1 9 cee000 cf) on fd 224 [1:1:1]
[main] Setsockopt(107 5 cee000 1c) on fd 225 [17:2:21]
[main] Setsockopt(1 24 cee000 1c) on fd 226 [26:1:76]
[main] Setsockopt(113 4 cee000 11) on fd 227 [35:5:0]
[main] Setsockopt(1 28 cee000 cc) on fd 228 [37:5:2]
[main] Setsockopt(1 b cee000 4) on fd 229 [1:1:1]
[main] Setsockopt(1 2c cee000 4) on fd 230 [16:2:0]
[main] Setsockopt(107 12 cee000 4) on fd 231 [17:2:768]
[main] Setsockopt(1 5 cee000 4) on fd 232 [26:2:36]
[main] Setsockopt(116 7f cee000 8a) on fd 234 [37:1:1]
[main] Setsockopt(107 d cee000 10) on fd 236 [17:3:512]
[main] Setsockopt(1 1 cee000 98) on fd 238 [35:2:0]
[main] Setsockopt(1 2f cee000 4) on fd 239 [37:5:1]
[main] Setsockopt(1 1d cee000 4) on fd 240 [1:1:1]
[main] Setsockopt(1 1 cee000 fc) on fd 243 [26:1:146]
[main] Setsockopt(1 2d cee000 4) on fd 245 [37:1:1]
[main] Setsockopt(1 21 cee000 23) on fd 246 [1:2:1]
[main] Setsockopt(107 f cee000 38) on fd 247 [17:3:768]
[main] Setsockopt(116 80 cee000 4) on fd 250 [37:5:3]
[main] Setsockopt(1 21 cee000 4a) on fd 251 [1:5:1]
[main] Setsockopt(1 29 cee000 4) on fd 253 [26:2:59]
[main] Setsockopt(1 29 cee000 4) on fd 254 [35:2:0]
[main] Setsockopt(1 1 cee000 4) on fd 255 [37:5:4]
[main] Setsockopt(1 2b cee000 4) on fd 257 [17:3:768]
[main] Setsockopt(1 2c cee000 4) on fd 258 [26:1:51]
[main] Setsockopt(116 7f cee000 4) on fd 260 [37:1:0]
[main] Setsockopt(1 9 cee000 4) on fd 261 [1:1:1]
[main] Setsockopt(10c 9 cee000 4) on fd 263 [26:1:37]
[main] Setsockopt(1 20 cee000 4) on fd 264 [35:5:0]
[main] Setsockopt(1 22 cee000 4) on fd 265 [37:1:4]
[main] Setsockopt(1 29 cee000 78) on fd 269 [35:2:0]
[main] Setsockopt(116 7f cee000 4) on fd 270 [37:5:5]
[main] Setsockopt(1 2e cee000 3e) on fd 271 [1:5:1]
[main] Setsockopt(107 11 cee000 4) on fd 272 [17:3:768]
[main] Setsockopt(1 5 cee000 4) on fd 274 [35:5:0]
[main] Setsockopt(1 f cee000 9) on fd 277 [17:2:768]
[main] Setsockopt(1 d cee000 8) on fd 279 [35:5:0]
[main] Setsockopt(1 d cee000 8) on fd 281 [1:1:1]
[main] Setsockopt(1 f cee000 79) on fd 282 [16:3:15]
[main] Setsockopt(1 21 cee000 41) on fd 283 [17:3:768]
[main] Setsockopt(1 25 cee000 8c) on fd 285 [35:2:0]
[main] Setsockopt(10e 3 cee000 4) on fd 288 [16:2:2]
[main] Setsockopt(1 5 cee000 84) on fd 289 [17:10:768]
[main] Setsockopt(1 22 cee000 78) on fd 290 [26:2:153]
[main] Setsockopt(1 9 cee000 4) on fd 291 [35:5:0]
[main] Setsockopt(1 e cee000 4) on fd 292 [37:1:4]
[main] Setsockopt(116 7f cee000 27) on fd 297 [37:5:4]
[main] Setsockopt(1 1 cee000 5a) on fd 298 [1:1:1]
[main] Setsockopt(107 5 cee000 1c) on fd 299 [17:2:768]
[main] Setsockopt(10c 9 cee000 4) on fd 300 [26:1:209]
[main] Setsockopt(116 7f cee000 34) on fd 302 [37:1:3]
[main] Setsockopt(1 e cee000 4) on fd 303 [1:2:1]
[main] Setsockopt(1 2f cee000 4) on fd 304 [17:2:768]
[main] Setsockopt(10c 2 cee000 4) on fd 305 [26:1:119]
[main] Setsockopt(1 24 cee000 77) on fd 306 [35:2:0]
[main] Setsockopt(1 f cee000 82) on fd 307 [37:1:2]
[main] Setsockopt(1 22 cee000 3a) on fd 308 [1:2:1]
[main] Setsockopt(1 10 cee000 4) on fd 311 [35:5:0]
[main] Setsockopt(116 7f cee000 4) on fd 312 [37:1:3]
[main] Setsockopt(1 24 cee000 97) on fd 314 [17:10:34916]
[main] Setsockopt(1 e cee000 4) on fd 315 [26:2:144]
[main] Setsockopt(1 25 cee000 4) on fd 317 [37:1:2]
[main] Setsockopt(1 23 cee000 1d) on fd 319 [16:3:2]
[main] Setsockopt(1 9 cee000 4) on fd 320 [17:10:768]
[main] Setsockopt(1 2c cee000 22) on fd 321 [26:1:178]
[main] Setsockopt(1 d cee000 8) on fd 322 [35:5:0]
[main] Setsockopt(116 7f cee000 4) on fd 323 [37:5:4]
[main] Setsockopt(1 25 cee000 5b) on fd 324 [1:5:1]
[main] Setsockopt(1 5 cee000 4) on fd 325 [17:10:768]
[main] Setsockopt(1 f cee000 76) on fd 326 [26:1:129]
[main] Setsockopt(1 1d cee000 4) on fd 327 [35:2:0]
[main] Setsockopt(1 2 cee000 99) on fd 330 [17:3:768]
[main] Setsockopt(116 7f cee000 4) on fd 333 [37:5:3]
[main] Setsockopt(1 8 cee000 8) on fd 334 [1:2:1]
[main] Setsockopt(1 20 cee000 4) on fd 336 [17:3:768]
[main] Setsockopt(1 a cee000 4) on fd 337 [26:2:26]
[main] Setsockopt(1 b cee000 1d) on fd 344 [35:2:0]
[main] Setsockopt(116 7f cee000 70) on fd 345 [37:1:4]
[main] Setsockopt(1 a cee000 74) on fd 347 [16:2:15]
[main] Setsockopt(1 a cee000 4) on fd 348 [17:10:768]
[main] Setsockopt(1 22 cee000 4) on fd 350 [35:2:0]
[main] Setsockopt(1 e cee000 4) on fd 351 [37:5:0]
[main] Setsockopt(1 1d cee000 8) on fd 352 [1:2:1]
[main] Setsockopt(1 28 cee000 d3) on fd 354 [17:2:768]
[main] Setsockopt(1 21 cee000 61) on fd 355 [26:2:134]
[main] Setsockopt(113 3 cee000 4) on fd 356 [35:5:0]
[main] Setsockopt(116 7f cee000 8b) on fd 357 [37:5:0]
[main] Setsockopt(1 2c cee000 4) on fd 358 [1:5:1]
[main] Setsockopt(1 29 cee000 17) on fd 359 [16:3:0]
[main] Setsockopt(1 8 cee000 4) on fd 360 [17:3:768]
[main] Setsockopt(1 25 cee000 a) on fd 361 [26:1:196]
[main] Setsockopt(1 25 cee000 65) on fd 362 [35:2:0]
[main] Setsockopt(116 80 cee000 6e) on fd 363 [37:1:3]
[main] Setsockopt(1 5 cee000 95) on fd 364 [1:1:1]
[main] Setsockopt(1 7 cee000 98) on fd 366 [26:1:15]
[main] Setsockopt(1 14 cee000 10) on fd 368 [37:5:1]
[main] Setsockopt(1 5 cee000 af) on fd 369 [1:1:1]
[main] Setsockopt(1 5 cee000 b3) on fd 370 [17:10:768]
[main] Setsockopt(1 6 cee000 4) on fd 371 [26:1:126]
[main] Setsockopt(1 10 cee000 2d) on fd 372 [35:2:0]
[main] Setsockopt(1 2e cee000 4) on fd 375 [17:2:768]
[main] Setsockopt(1 f cee000 9d) on fd 377 [35:2:0]
[main] Setsockopt(116 7f cee000 b4) on fd 378 [37:5:0]
[main] Setsockopt(10e 4 cee000 7f) on fd 380 [16:2:0]
[main] 375 sockets created based on info from socket cachefile.
[main] Generating file descriptors
[main] Added 296 filenames from /dev
[main] Added 9868 filenames from /proc
[main] Added 5885 filenames from /sys
[child1:2718] ioprio_get (252) returned ENOSYS, marking as inactive.
[child1:2718] timer_settime (223) returned ENOSYS, marking as inactive.
[child1:2718] mq_timedreceive (243) returned ENOSYS, marking as inactive.
*** glibc detected *** /trinity: free(): invalid pointer: 0x000000000121c000 ***
======= Backtrace: =========
/lib/x86_64-linux-gnu/libc.so.6(+0x7e626)[0x7f613cdfd626]
/trinity[0x411a8e]
/trinity[0x40a78b]
/trinity[0x40d5dc]
/trinity[0x40320a]
/trinity[0x406f7e]
/trinity[0x402b07]
/lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xed)[0x7f613cda076d]
/trinity[0x402dd3]
======= Memory map: ========
00400000-00429000 r-xp 00000000 00:01 10279                              /trinity
00629000-0068a000 rw-p 00029000 00:01 10279                              /trinity
00ced000-00f40000 rw-p 00000000 00:00 0                                  [heap]
00f40000-01244000 rw-p 00000000 00:00 0                                  [heap]
01244000-01286000 rw-p 00000000 00:00 0                                  [heap]
7f6139869000-7f613987e000 r-xp 00000000 00:01 5796                       /lib/x86_64-linux-gnu/libgcc_s.so.1
7f613987e000-7f6139a7d000 ---p 00015000 00:01 5796                       /lib/x86_64-linux-gnu/libgcc_s.so.1
7f6139a7d000-7f6139a7e000 r--p 00014000 00:01 5796                       /lib/x86_64-linux-gnu/libgcc_s.so.1
7f6139a7e000-7f6139a7f000 rw-p 00015000 00:01 5796                       /lib/x86_64-linux-gnu/libgcc_s.so.1
7f6139a7f000-7f613a47f000 -w-s 00000000 00:04 21550                      /dev/zero (deleted)
7f613a47f000-7f613ae7f000 r--s 00000000 00:04 21549                      /dev/zero (deleted)
7f613ae7f000-7f613b87f000 rw-s 00000000 00:04 21548                      /dev/zero (deleted)
7f613b87f000-7f613bc7f000 -w-s 00000000 00:04 21547                      /dev/zero (deleted)
7f613bc7f000-7f613c07f000 r--s 00000000 00:04 21546                      /dev/zero (deleted)
7f613c07f000-7f613c47f000 rw-s 00000000 00:04 21545                      /dev/zero (deleted)
7f613c47f000-7f613c67f000 -w-s 00000000 00:04 21544                      /dev/zero (deleted)
7f613c67f000-7f613c87f000 r--s 00000000 00:04 21543                      /dev/zero (deleted)
7f613c87f000-7f613ca7f000 rw-s 00000000 00:04 21542                      /dev/zero (deleted)
7f613ca7f000-7f613cb7f000 -w-s 00000000 00:04 21541                      /dev/zero (deleted)
7f613cb7f000-7f613cc7f000 r--s 00000000 00:04 21540                      /dev/zero (deleted)
7f613cc7f000-7f613cd7f000 rw-s 00000000 00:04 21539                      /dev/zero (deleted)
7f613cd7f000-7f613cf32000 r-xp 00000000 00:01 5781                       /lib/x86_64-linux-gnu/libc-2.15.so
7f613cf32000-7f613d131000 ---p 001b3000 00:01 5781                       /lib/x86_64-linux-gnu/libc-2.15.so
7f613d131000-7f613d135000 r--p 001b2000 00:01 5781                       /lib/x86_64-linux-gnu/libc-2.15.so
7f613d135000-7f613d137000 rw-p 001b6000 00:01 5781                       /lib/x86_64-linux-gnu/libc-2.15.so
7f613d137000-7f613d13c000 rw-p 00000000 00:00 0 
7f613d13c000-7f613d15e000 r-xp 00000000 00:01 5882                       /lib/x86_64-linux-gnu/ld-2.15.so
7f613d258000-7f613d259000 rw-p 00000000 00:00 0 
7f613d259000-7f613d25b000 -w-s 00000000 00:04 21538                      /dev/zero (deleted)
7f613d25b000-7f613d25d000 r--s 00000000 00:04 21537                      /dev/zero (deleted)
7f613d25d000-7f613d25f000 rw-s 00000000 00:04 21536                      /dev/zero (deleted)
7f613d25f000-7f613d261000 rw-p 00000000 00:00 0 
7f613d261000-7f613d27f000 ---s 00000000 00:04 18891                      /dev/zero (deleted)
7f613d27f000-7f613d28f000 rw-s 0001e000 00:04 18891                      /dev/zero (deleted)
7f613d28f000-7f613d2ad000 ---s 0002e000 00:04 18891                      /dev/zero (deleted)
7f613d2ad000-7f613d307000 rw-s 00000000 00:04 18890                      /dev/zero (deleted)
7f613d307000-7f613d357000 rw-s 00000000 00:04 18870                      /dev/zero (deleted)
7f613d357000-7f613d35b000 rw-p 00000000 00:00 0 
7f613d35b000-7f613d35e000 rw-p 00000000 00:00 0 
7f613d35e000-7f613d35f000 r--p 00022000 00:01 5882                       /lib/x86_64-linux-gnu/ld-2.15.so
7f613d35f000-7f613d361000 rw-p 00023000 00:01 5882                       /lib/x86_64-linux-gnu/ld-2.15.so
7ffc9a481000-7ffc9a4a2000 rw-p 00000000 00:00 0                          [stack]
7ffc9a59e000-7ffc9a5a1000 r--p 00000000 00:00 0                          [vvar]
7ffc9a5a1000-7ffc9a5a3000 r-xp 00000000 00:00 0                          [vdso]
ffffffffff600000-ffffffffff601000 r-xp 00000000 00:00 0                  [vsyscall]
[main] Random reseed: 2753958762
[child1:2719] mq_getsetattr (245) returned ENOSYS, marking as inactive.
[child1:2719] uid changed! Was: 0, now 7877
[child0:2717] child exiting.
Bailing main loop. Exit reason: UID changed.
[watchdog] [2708] Watchdog exiting
[init] 
Ran 25 syscalls. Successes: 7  Failures: 18
[   30.250303] ls /sys/class/net
[   30.250318] 
[   30.256191] eql
[   30.256213] 
[   30.256943] ifb0
[   30.256953] 
[   30.257659] ifb1
[   30.257669] 
[   30.258325] lo
[   30.258336] 
[   30.271596] /lkp/lkp/src/bin/run-lkp
[   30.271611] 
[   30.351941] RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/0
[   30.351956] 
[   30.358311] job=/lkp/scheduled/vm-vp-quantal-x86_64-31/boot-1-quantal-core-x86_64.cgz-5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d-20171121-89203-uuuwc2-0.yaml
[   30.358328] 
[   30.372786] run-job /lkp/scheduled/vm-vp-quantal-x86_64-31/boot-1-quantal-core-x86_64.cgz-5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d-20171121-89203-uuuwc2-0.yaml
[   30.372801] 
[   30.379818] Cannot find wget/curl.
[   30.379833] 
[   30.615028] serial8250: too much work for irq3
[   30.661376] serial8250: too much work for irq3
[   30.737032] serial8250: too much work for irq3
[   30.813453] serial8250: too much work for irq3
[   30.886360] serial8250: too much work for irq3
[   30.937893] serial8250: too much work for irq3
[   30.985997] serial8250: too much work for irq3
[   32.495084] init: tty4 main process (2336) terminated with status 1
[   32.498281] init: tty4 main process ended, respawning
[   32.507611] init: tty5 main process (2359) terminated with status 1
[   32.508863] init: tty5 main process ended, respawning
[   32.525944] init: tty2 main process (2390) terminated with status 1
[   32.527126] init: tty2 main process ended, respawning
[   32.538541] init: tty3 main process (2413) terminated with status 1
[   32.539716] init: tty3 main process ended, respawning
[   32.546142] init: tty6 main process (2419) terminated with status 1
[   32.547336] init: tty6 main process ended, respawning
[   32.588425] /lkp/lkp/src/lib/job-init.sh: line 157:  2739 Terminated              tail -n 0 -f $* > $file
[   32.588441] 
[   32.592575] /lkp/lkp/src/lib/job-init.sh: line 157:  2740 Terminated              tail -n 0 -f $* > $file
[   32.592590] 
[   32.597408] /lkp/lkp/src/lib/job-init.sh: line 157:  2741 Terminated              tail -n 0 -f $* > $file
[   32.597422] 
[   32.617107] Cannot find wget/curl.
[   32.617122] 
[   33.671990] kill 2773 cat /proc/kmsg 
[   33.672079] 
[   33.689280] wait for background monitors: 2777 oom-killer
[   33.689294] 
[   35.962817] Cannot find wget/curl.
[   35.962828] 
[   36.012695] Cannot find wget/curl.
[   36.012708] 
[   36.043416] LKP: rebooting
[   36.043428] 
[   36.047294] sysrq: SysRq : Emergency Sync
[   36.048194] sysrq: SysRq : Resetting
[   36.048397] Emergency Sync complete

Elapsed time: 40

#!/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/quantal/quantal-core-x86_64.cgz
	/lkp/lkp/lkp-x86_64.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

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-kernel $kernel
	-initrd initrd.img
	-m 512
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-serial stdio
	-display none
	-monitor null
)

append=(
	ip=::::vm-vp-quantal-x86_64-31::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-randconfig-r0-11211736
	branch=linux-devel/devel-catchup-201711211821
	commit=5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d
	BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/vmlinuz-4.14.0-12905-g5f3cb7e
	max_uptime=600
	RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-r0-11211736/gcc-6/5f3cb7eb7ce2d895658c6251fbaa4b59d93d941d/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[*]}"
