early console in setup code
early console in extract_kernel
input_data: 0x00000000036df276
input_len: 0x0000000000bbf6d1
output: 0x0000000001000000
output_len: 0x00000000028fc7c8
kernel_total_size: 0x00000000032ce000
booted via startup_32()
Physical KASLR using RDTSC...
Virtual KASLR using RDTSC...

Decompressing Linux... Parsing ELF... Performing relocations... done.
Booting the kernel.
[    0.000000] Linux version 4.16.0-rc1-00044-g11d88c812 (kbuild@lkp-g5) (gcc version 7.3.0 (Debian 7.3.0-1)) #153 SMP Fri Feb 23 08:37:24 CST 2018
[    0.000000] Command line: ip=::::vm-lkp-nex04-1G-21::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-nex04-1G-21/boot-1-debian-x86_64-2016-08-31.cgz-11d88c812043b17c016b8dbe931244f74f082281-20180223-51874-17lnnhk-0.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-catchup-201802230718 commit=11d88c812043b17c016b8dbe931244f74f082281 BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-7/11d88c812043b17c016b8dbe931244f74f082281/vmlinuz-4.16.0-rc1-00044-g11d88c812 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-nex04-1G/debian-x86_64-2016-08-31.cgz/x86_64-nfsroot/gcc-7/11d88c812043b17c016b8dbe931244f74f082281/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.shutdown=0
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   Centaur CentaurHauls
[    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-0x000000003ffdffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] 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 = 0x3ffe0 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  
Memory KASLR using RDTSC...
[    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 [        (ptrval)]
[    0.000000]   mpc: f6a90-f6b74
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [        (ptrval)] 99000 size 24576
[    0.000000] BRK [0x332a3000, 0x332a3fff] PGTABLE
[    0.000000] BRK [0x332a4000, 0x332a4fff] PGTABLE
[    0.000000] BRK [0x332a5000, 0x332a5fff] PGTABLE
[    0.000000] BRK [0x332a6000, 0x332a6fff] PGTABLE
[    0.000000] BRK [0x332a7000, 0x332a7fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x371c8000-0x3ffdffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000003FFE1628 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000003FFE147C 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000003FFE0040 00143C (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000003FFE0000 000040
[    0.000000] ACPI: APIC 0x000000003FFE1570 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000003FFE15F0 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-0x000000003ffdffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x371c4000-0x371c7fff]
[    0.000000] kvm-clock: cpu 0, msr 0:371c2001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 1011584143 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x000000003ffdffff]
[    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-0x000000003ffdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdffff]
[    0.000000] On node 0 totalpages: 262014
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 4032 pages used for memmap
[    0.000000]   DMA32 zone: 258016 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] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 493 pages/cpu @        (ptrval) s1979992 r8192 d31144 u2097152
[    0.000000] pcpu-alloc: s1979992 r8192 d31144 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 35415540
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 257897
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: ip=::::vm-lkp-nex04-1G-21::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-nex04-1G-21/boot-1-debian-x86_64-2016-08-31.cgz-11d88c812043b17c016b8dbe931244f74f082281-20180223-51874-17lnnhk-0.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-catchup-201802230718 commit=11d88c812043b17c016b8dbe931244f74f082281 BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-7/11d88c812043b17c016b8dbe931244f74f082281/vmlinuz-4.16.0-rc1-00044-g11d88c812 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-nex04-1G/debian-x86_64-2016-08-31.cgz/x86_64-nfsroot/gcc-7/11d88c812043b17c016b8dbe931244f74f082281/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.shutdown=0
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] Memory: 830156K/1048056K available (18460K kernel code, 2053K rwdata, 5968K rodata, 3656K init, 15376K bss, 217900K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] ftrace: allocating 56518 entries in 221 pages
[    0.001000] Running RCU self tests
[    0.001000] Hierarchical RCU implementation.
[    0.001000] 	RCU event tracing is enabled.
[    0.001000] 	RCU lockdep checking is enabled.
[    0.001000] 	RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2.
[    0.001000] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    0.001000] 	Tasks RCU enabled.
[    0.001000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.001000] NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16
[    0.001000] Console: colour VGA+ 80x25
[    0.001000] console [tty0] enabled
[    0.001000] console [ttyS0] enabled
[    0.001000] console [ttyS0] enabled
[    0.001000] bootconsole [earlyser0] disabled
[    0.001000] bootconsole [earlyser0] disabled
[    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: 2688 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] ACPI: Core revision 20180105
[    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.002084] enabled ExtINT on CPU#0
[    0.004431] ENABLING IO-APIC IRQs
[    0.006049] init IO_APIC IRQs
[    0.007012]  apic 0 pin 0 not connected
[    0.008157] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.009130] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.010000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.010000]  apic 0 pin 16 not connected
[    0.010000]  apic 0 pin 17 not connected
[    0.010000]  apic 0 pin 18 not connected
[    0.010000]  apic 0 pin 19 not connected
[    0.010000]  apic 0 pin 20 not connected
[    0.010000]  apic 0 pin 21 not connected
[    0.010000]  apic 0 pin 22 not connected
[    0.010000]  apic 0 pin 23 not connected
[    0.010000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.010000] tsc: Detected 2260.998 MHz processor
[    0.011024] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[    0.013034] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998)
[    0.014011] pid_max: default: 32768 minimum: 301
[    0.016528] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.017387] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.018114] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.019081] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[    0.023607] CPU: Physical Processor ID: 0
[    0.024035] mce: CPU supports 10 MCE banks
[    0.025119] numa_add_cpu cpu 0 node 0: mask now 0
[    0.026016] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.027009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.028018] Spectre V2 : Mitigation: Full generic retpoline
[    0.030226] Freeing SMP alternatives memory: 52K
[    0.034263] TSC deadline timer enabled
[    0.035000] smpboot: CPU0: Intel(R) Xeon(R) CPU           X7560  @ 2.27GHz (family: 0x6, model: 0x2e, stepping: 0x6)
[    0.036764] Performance Events: Nehalem events, Intel PMU driver.
[    0.037016] core: CPUID marked event: 'bus cycles' unavailable
[    0.038075] ... version:                2
[    0.039015] ... bit width:              48
[    0.040014] ... generic registers:      4
[    0.041015] ... value mask:             0000ffffffffffff
[    0.042014] ... max period:             000000007fffffff
[    0.043014] ... fixed-purpose events:   3
[    0.044014] ... event mask:             000000070000000f
[    0.045555] Hierarchical SRCU implementation.
[    0.047165] smp: Bringing up secondary CPUs ...
[    0.049425] x86: Booting SMP configuration:
[    0.050016] .... node  #0, CPUs:      #1
[    0.001000] kvm-clock: cpu 1, msr 0:371c2041, secondary cpu clock
[    0.001000] masked ExtINT on CPU#1
[    0.001000] numa_add_cpu cpu 1 node 0: mask now 0-1
[    0.057530] KVM setup async PF for cpu 1
[    0.058000] kvm-stealtime: cpu 1, msr 35615540
[    0.061032] smp: Brought up 1 node, 2 CPUs
[    0.062130] smpboot: Max logical packages: 2
[    0.063042] ----------------
[    0.064019] | NMI testsuite:
[    0.065100] --------------------
[    0.066020]   remote IPI:
[    0.066297] general protection fault: 0000 [#1] SMP PTI
[    0.067000] Modules linked in:
[    0.067000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc1-00044-g11d88c812 #153
[    0.067000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.067000] RIP: 0010:sync_sched_exp_handler+0x14/0x96
[    0.067000] RSP: 0000:ffffb08f40197d10 EFLAGS: 00010287
[    0.067000] RAX: deacd7b935400000 RBX: ffffffffb6a831c0 RCX: 0000000000000003
[    0.067000] RDX: 0000000000000000 RSI: ffffffffb6a7f2c0 RDI: ffffffffb6a835c8
[    0.067000] RBP: ffffffffb6a831c0 R08: 00000000e272d5c3 R09: 0000000000000004
[    0.067000] R10: ffff890c349c8040 R11: 0000000000000001 R12: 0000000fffffffe0
[    0.067000] R13: ffffffffb511fa14 R14: ffffffffb6a835c8 R15: 0000000000000000
[    0.067000] FS:  0000000000000000(0000) GS:ffff890c35400000(0000) knlGS:0000000000000000
[    0.067000] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    0.067000] CR2: 00000000ffffffff CR3: 0000000031a24000 CR4: 00000000000006f0
[    0.067000] Call Trace:
[    0.067000]  sync_rcu_exp_select_cpus+0x3da/0x44c
[    0.067000]  _synchronize_rcu_expedited+0x6e2/0x83f
[    0.067000]  ? _raw_spin_unlock_irq+0x29/0x39
[    0.067000]  ? finish_task_switch+0x163/0x22d
[    0.067000]  ? finish_task_switch+0x1dc/0x22d
[    0.067000]  ? __schedule+0xa24/0xa8d
[    0.067000]  synchronize_sched+0xca/0x102
[    0.067000]  ? trace_hardirqs_on_caller+0x177/0x191
[    0.067000]  ? unregister_nmi_handler+0xac/0xbb
[    0.067000]  test_nmi_ipi+0x6e/0x7f
[    0.067000]  dotest+0x7/0x65
[    0.067000]  nmi_selftest+0x59/0x152
[    0.067000]  native_smp_cpus_done+0x65/0xfc
[    0.067000]  kernel_init_freeable+0x123/0x25a
[    0.067000]  ? rest_init+0x22e/0x22e
[    0.067000]  kernel_init+0xa/0xf0
[    0.067000]  ret_from_fork+0x3a/0x50
[    0.067000] Code: 4a 48 89 df e8 10 88 ce 00 66 c7 83 3a 17 00 00 06 00 e9 b1 ec ff ff 66 66 66 66 90 48 8b 87 98 16 00 00 65 48 03 05 10 f7 ee 4a <48> 8b 50 20 48 8b 52 70 48 85 50 28 74 73 65 8a 05 dc 36 0c 4b 
[    0.067000] RIP: sync_sched_exp_handler+0x14/0x96 RSP: ffffb08f40197d10
[    0.067040] ---[ end trace 52ba43a846e7343b ]---
[    0.068014] Kernel panic - not syncing: Fatal exception

Elapsed time: 10

#!/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/debian/debian-x86_64-2016-08-31.cgz
	/lkp/lkp/lkp-x86_64.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-12-14.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.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
	-cpu host
	-kernel $kernel
	-initrd initrd.img
	-m 1024
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0,hostfwd=tcp::23220-:22
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-serial stdio
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-nex04-1G-21::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-nfsroot
	branch=linux-devel/devel-catchup-201802230718
	commit=11d88c812043b17c016b8dbe931244f74f082281
	BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-7/11d88c812043b17c016b8dbe931244f74f082281/vmlinuz-4.16.0-rc1-00044-g11d88c812
	max_uptime=600
	RESULT_ROOT=/result/boot/1/vm-lkp-nex04-1G/debian-x86_64-2016-08-31.cgz/x86_64-nfsroot/gcc-7/11d88c812043b17c016b8dbe931244f74f082281/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
	rcuperf.shutdown=0
)

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