early console in setup code
early console in extract_kernel
input_data: 0x0000000003515276
input_len: 0x0000000000b7850e
output: 0x0000000001000000
output_len: 0x00000000026ea06c
kernel_total_size: 0x00000000030bd000
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.15.0-rc7-00430-g8e130b0 (kbuild@ivytown2) (gcc version 7.3.0 (Debian 7.3.0-1)) #35 SMP Wed Feb 7 20:53:49 CST 2018
[    0.000000] Command line: ip=::::vm-lkp-hsw01-yocto-x86_64-26::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-26/boot-1-yocto-minimal-x86_64-2016-04-22.cgz-8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112-20180207-70148-15cyihi-0.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-review/Joel-Fernandes/tracing-Improve-design-of-preemptirq-tracepoints-and-its-users/20180207-195707 commit=8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112 BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/vmlinuz-4.15.0-rc7-00430-g8e130b0 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/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_loglev
[    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-0x000000001ffddfff] usable
[    0.000000] BIOS-e820: [mem 0x000000001ffde000-0x000000001fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] 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 = 0x1ffde max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: write-back
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0080000000 mask FF80000000 uncachable
[    0.000000]   1 disabled
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: PAT not supported by CPU.
[    0.000000] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
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 0x000f6a90-0x000f6a9f] mapped at [        (ptrval)]
[    0.000000]   mpc: f6aa0-f6b78
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [        (ptrval)] 99000 size 24576
[    0.000000] BRK [0x0b092000, 0x0b092fff] PGTABLE
[    0.000000] BRK [0x0b093000, 0x0b093fff] PGTABLE
[    0.000000] BRK [0x0b094000, 0x0b094fff] PGTABLE
[    0.000000] BRK [0x0b095000, 0x0b095fff] PGTABLE
[    0.000000] BRK [0x0b096000, 0x0b096fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x1f966000-0x1ffcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000001FFE15CE 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000001FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000001FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000001FFE0000 000040
[    0.000000] ACPI: APIC 0x000000001FFE151E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000001FFE1596 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fd000 (        fee00000)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000001ffddfff]
[    0.000000] NODE_DATA(0) allocated [mem 0x1ffda000-0x1ffddfff]
[    0.000000] kvm-clock: cpu 0, msr 0:1ffd8001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 308371405 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-0x000000001ffddfff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000001ffddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffddfff]
[    0.000000] On node 0 totalpages: 130940
[    0.000000]   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: 1984 pages used for memmap
[    0.000000]   DMA32 zone: 126942 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 1 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 0x20000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] percpu: Embedded 493 pages/cpu @        (ptrval) s1979416 r8192 d31720 u2097152
[    0.000000] pcpu-alloc: s1979416 r8192 d31720 u2097152 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 1f615540
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 128871
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: ip=::::vm-lkp-hsw01-yocto-x86_64-26::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-26/boot-1-yocto-minimal-x86_64-2016-04-22.cgz-8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112-20180207-70148-15cyihi-0.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-review/Joel-Fernandes/tracing-Improve-design-of-preemptirq-tracepoints-and-its-users/20180207-195707 commit=8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112 BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/vmlinuz-4.15.0-rc7-00430-g8e130b0 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/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
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] Memory: 457080K/523760K available (16412K kernel code, 2049K rwdata, 5972K rodata, 3644K init, 15376K bss, 66680K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] ftrace: allocating 56250 entries in 220 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=1.
[    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=1
[    0.001000] NR_IRQS: 4352, nr_irqs: 256, 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] ------------[ cut here ]------------
[    0.001000] IRQs not disabled as expected
[    0.001000] WARNING: CPU: 0 PID: 0 at kernel/rcu/tree.c:892 rcu_irq_exit+0x4d/0x19a
[    0.001000] Modules linked in:
[    0.001000] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.15.0-rc7-00430-g8e130b0 #35
[    0.001000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.001000] RIP: 0010:rcu_irq_exit+0x4d/0x19a
[    0.001000] RSP: 0000:ffffffff92803e18 EFLAGS: 00010082
[    0.001000] RAX: 000000000000001d RBX: 0000000000000082 RCX: 70a3d70a3d70a3e0
[    0.001000] RDX: 0000000000000001 RSI: 0000000000000001 RDI: 0000000000000046
[    0.001000] RBP: ffffffff910fa412 R08: 00000000c5610c9a R09: 0000000000000004
[    0.001000] R10: 0000000000000001 R11: ffffffff93dc0469 R12: 0000000000000000
[    0.001000] R13: ffffffff93dc0880 R14: 0000000000000002 R15: 0000000000000048
[    0.001000] FS:  0000000000000000(0000) GS:ffff8bfa5f600000(0000) knlGS:0000000000000000
[    0.001000] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    0.001000] CR2: 00000000ffffffff CR3: 0000000009824000 CR4: 00000000000006b0
[    0.001000] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[    0.001000] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[    0.001000] Call Trace:
[    0.001000]  ? vprintk_emit+0x26c/0x29c
[    0.001000]  rcu_irq_exit_irqson+0x21/0x48
[    0.001000]  trace_hardirqs_on+0xc2/0xd0
[    0.001000]  vprintk_emit+0x26c/0x29c
[    0.001000]  printk+0x43/0x4b
[    0.001000]  lockdep_init+0x36/0xcf
[    0.001000]  start_kernel+0x2fd/0x416
[    0.001000]  secondary_startup_64+0xa5/0xb0
[    0.001000] Code: 08 00 00 00 75 27 83 b8 88 08 00 00 00 74 1e 80 3d ef 68 8c 01 00 75 15 48 c7 c7 a9 e3 5e 92 c6 05 df 68 8c 01 01 e8 31 9d f8 ff <0f> ff 48 c7 c5 a0 9d 1d 00 65 48 03 2d 70 cf ef 6e 83 7d 08 00 
[    0.001000] ---[ end trace 2507864299958132 ]---
[    0.001000] ------------[ cut here ]------------
[    0.001000] IRQs not disabled as expected
[    0.001000] WARNING: CPU: 0 PID: 0 at kernel/rcu/tree.c:1042 rcu_irq_enter+0x4b/0x198
[    0.001000] Modules linked in:
[    0.001000] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W        4.15.0-rc7-00430-g8e130b0 #35
[    0.001000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.001000] RIP: 0010:rcu_irq_enter+0x4b/0x198
[    0.001000] RSP: 0000:ffffffff92803df0 EFLAGS: 00010082
[    0.001000] RAX: 000000000000001d RBX: 0000000000000046 RCX: 70a3d70a3d70a3e0
[    0.001000] RDX: 0000000000000001 RSI: 0000000000000001 RDI: 0000000000000046
[    0.001000] RBP: ffffffff910f896f R08: 00000000c5610c9a R09: 0000000000000004
[    0.001000] R10: 0000000000000001 R11: ffffffff93dc0469 R12: ffffffff910fa427
[    0.001000] R13: ffffffff93dc0880 R14: 0000000000000002 R15: 0000000000000048
[    0.001000] FS:  0000000000000000(0000) GS:ffff8bfa5f600000(0000) knlGS:0000000000000000
[    0.001000] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    0.001000] CR2: 00000000ffffffff CR3: 0000000009824000 CR4: 00000000000006b0
[    0.001000] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[    0.001000] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[    0.001000] Call Trace:
[    0.001000]  ? __down_trylock_console_sem+0x22/0x93
[    0.001000]  ? vprintk_emit+0x281/0x29c
[    0.001000]  rcu_irq_enter_irqson+0x21/0x48
[    0.001000]  trace_hardirqs_off+0x5f/0xd2
[    0.001000]  __down_trylock_console_sem+0x22/0x93
[    0.001000]  console_trylock+0x10/0x77
[    0.001000]  vprintk_emit+0x281/0x29c
[    0.001000]  printk+0x43/0x4b
[    0.001000]  lockdep_init+0x36/0xcf
[    0.001000]  start_kernel+0x2fd/0x416
[    0.001000]  secondary_startup_64+0xa5/0xb0
[    0.001000] Code: 08 00 00 00 75 27 83 b8 88 08 00 00 00 74 1e 80 3d 35 66 8c 01 00 75 15 48 c7 c7 a9 e3 5e 92 c6 05 25 66 8c 01 01 e8 79 9a f8 ff <0f> ff 48 c7 c0 a0 9d 1d 00 65 48 03 05 b8 cc ef 6e 83 78 08 00 
[    0.001000] ---[ end trace 2507864299958133 ]---
[    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 20170831
[    0.001000] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.001000] hpet clockevent registered
[    0.001020] APIC: Switch to symmetric I/O mode setup
[    0.002024] enabled ExtINT on CPU#0
[    0.003430] ENABLING IO-APIC IRQs
[    0.003983] init IO_APIC IRQs
[    0.004006]  apic 0 pin 0 not connected
[    0.005053] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.006047] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.007000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.007000]  apic 0 pin 16 not connected
[    0.007000]  apic 0 pin 17 not connected
[    0.007000]  apic 0 pin 18 not connected
[    0.007000]  apic 0 pin 19 not connected
[    0.007000]  apic 0 pin 20 not connected
[    0.007000]  apic 0 pin 21 not connected
[    0.007000]  apic 0 pin 22 not connected
[    0.007000]  apic 0 pin 23 not connected
[    0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.007000] tsc: Detected 2593.992 MHz processor
[    0.007007] Calibrating delay loop (skipped) preset value.. 5187.98 BogoMIPS (lpj=2593992)
[    0.008004] pid_max: default: 32768 minimum: 301
[    0.009346] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.010103] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.011042] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.011842] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.013896] CPU: Physical Processor ID: 0
[    0.014016] mce: CPU supports 10 MCE banks
[    0.015048] numa_add_cpu cpu 0 node 0: mask now 0
[    0.015687] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.016004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.028175] Freeing SMP alternatives memory: 52K
[    0.030435] Using local APIC timer interrupts.
[    0.030435] calibrating APIC timer ...
[    0.032000] ... lapic delta = 6373610
[    0.032000] ... PM-Timer delta = 365036
[    0.032000] APIC calibration not consistent with PM-Timer: 101ms instead of 100ms
[    0.032000] APIC delta adjusted to PM-Timer: 6249956 (6373610)
[    0.032000] TSC delta adjusted to PM-Timer: 259399711 (264531848)
[    0.032000] ..... delta 6249956
[    0.032000] ..... mult: 268433566
[    0.032000] ..... calibration result: 999992
[    0.032000] ..... CPU clock speed is 2593.0997 MHz.
[    0.032000] ..... host bus clock speed is 999.0992 MHz.
[    0.032037] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.033509] Performance Events: PMU not available due to virtualization, using software events only.
[    0.035238] Hierarchical SRCU implementation.
[    0.036495] smp: Bringing up secondary CPUs ...
[    0.037007] smp: Brought up 1 node, 1 CPU
[    0.037584] smpboot: Max logical packages: 1
[    0.038012] ----------------
[    0.038501] | NMI testsuite:
[    0.039005] --------------------
[    0.040029]   remote IPI:  ok  |
[    0.040559]    local IPI:  ok  |
[    0.041006] --------------------
[    0.041517] Good, all   2 testcases passed! |
[    0.042005] ---------------------------------
[    0.042607] smpboot: Total of 1 processors activated (5187.98 BogoMIPS)
[    0.044684] devtmpfs: initialized
[    0.045602] x86/mm: Memory block size: 128MB
[    0.049924] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.050038] futex hash table entries: 256 (order: 3, 32768 bytes)
[    0.051635] xor: measuring software checksum speed
[    0.062004]    prefetch64-sse: 25252.000 MB/sec
[    0.063057] kworker/u2:0 (15) used greatest stack depth: 13560 bytes left
[    0.073006]    generic_sse: 22256.000 MB/sec
[    0.073668] xor: using function: prefetch64-sse (25252.000 MB/sec)
[    0.074298] RTC time: 21:01:27, date: 02/07/18
[    0.076425] NET: Registered protocol family 16
[    0.078757] audit: initializing netlink subsys (disabled)
[    0.080818] cpuidle: using governor ladder
[    0.082235] cpuidle: using governor menu
[    0.083599] audit: type=2000 audit(1518008486.718:1): state=initialized audit_enabled=0 res=1
[    0.084076] ACPI: bus type PCI registered
[    0.086265] kworker/u2:1 (19) used greatest stack depth: 13240 bytes left
[    0.087628] dca service started, version 1.12.1
[    0.089148] PCI: Using configuration type 1 for base access
[    0.298190] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.317018] raid6: sse2x1   gen()  9695 MB/s
[    0.335012] raid6: sse2x1   xor()  3712 MB/s
[    0.353008] raid6: sse2x2   gen() 10433 MB/s
[    0.370009] raid6: sse2x2   xor()  8224 MB/s
[    0.387008] raid6: sse2x4   gen() 15183 MB/s
[    0.404007] raid6: sse2x4   xor()  9882 MB/s
[    0.404617] raid6: using algorithm sse2x4 gen() 15183 MB/s
[    0.405006] raid6: .... xor() 9882 MB/s, rmw enabled
[    0.405660] raid6: using intx1 recovery algorithm
[    0.407241] ACPI: Added _OSI(Module Device)
[    0.407847] ACPI: Added _OSI(Processor Device)
[    0.408007] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.409006] ACPI: Added _OSI(Processor Aggregator Device)
[    0.424268] ACPI: Interpreter enabled
[    0.424992] ACPI: (supports S0 S3 S4 S5)
[    0.425009] ACPI: Using IOAPIC for interrupt routing
[    0.426150] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.429284] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.498460] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.499164] acpi PNP0A03:00: _OSC: OS supports [Segments MSI]
[    0.500175] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.501281] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.506881] PCI host bridge to bus 0000:00
[    0.507097] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.508029] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.509029] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.510069] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window]
[    0.511030] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.512079] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.514732] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.517212] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.521009] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    0.523413] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.524021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.525064] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.525898] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.528161] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.529523] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.530027] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.534339] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.537016] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.542018] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.551017] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.555096] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.557704] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.559010] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.569014] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.578527] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    0.582010] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    0.586011] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    0.594012] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    0.600394] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    0.602876] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    0.622195] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.625351] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.628797] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.632220] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.634324] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.639141] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.640000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.640012] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.642178] vgaarb: loaded
[    0.645267] SCSI subsystem initialized
[    0.647288] libata version 3.00 loaded.
[    0.649362] ACPI: bus type USB registered
[    0.650578] usbcore: registered new interface driver usbfs
[    0.652539] usbcore: registered new interface driver hub
[    0.653152] usbcore: registered new device driver usb
[    0.656379] pps_core: LinuxPPS API ver. 1 registered
[    0.657097] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.659274] PTP clock support registered
[    0.661505] dell_smbios: Unable to run on non-Dell system
[    0.663890] Advanced Linux Sound Architecture Driver Initialized.
[    0.664639] PCI: Using ACPI for IRQ routing
[    0.665054] PCI: pci_cache_line_size set to 64 bytes
[    0.668070] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.669044] e820: reserve RAM buffer [mem 0x1ffde000-0x1fffffff]
[    0.672989] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.673058] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.674248] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.680339] clocksource: Switched to clocksource kvm-clock
[    0.901075] pnp: PnP ACPI init
[    0.903038] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.905574] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.907683] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.909437] pnp 00:03: [dma 2]
[    0.910852] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.913477] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.916179] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.918778] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.925700] pnp: PnP ACPI: found 7 devices
[    0.968805] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.970744] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.971565] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.972499] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.973443] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window]
[    0.974996] NET: Registered protocol family 2
[    0.976659] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    0.977659] TCP bind hash table entries: 4096 (order: 6, 327680 bytes)
[    0.979763] TCP: Hash tables configured (established 4096 bind 4096)
[    0.980697] UDP hash table entries: 256 (order: 3, 49152 bytes)
[    0.981645] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes)
[    0.983092] NET: Registered protocol family 1
[    0.984275] RPC: Registered named UNIX socket transport module.
[    0.985036] RPC: Registered udp transport module.
[    0.985669] RPC: Registered tcp transport module.
[    0.986308] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.987087] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.988112] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.988937] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.989730] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.990885] PCI: CLS 0 bytes, default 64
[    0.991911] Trying to unpack rootfs image as initramfs...
[    2.023999] Freeing initrd memory: 6568K
[    2.026462] kvm: no hardware support
[    2.032341] Machine check injector initialized
[    2.037515] Scanning for low memory corruption every 60 seconds
[    2.039452] rcu-torture:--- Start of test: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 stall_cpu_irqsoff=0 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0
[    2.044199] rcu-torture: Creating rcu_torture_writer task
[    2.045172] rcu-torture: Creating rcu_torture_fakewriter task
[    2.045944] rcu-torture: rcu_torture_writer task started
[    2.046661] rcu-torture: GP expediting controlled from boot/sysfs for rcu,
[    2.047516] rcu-torture: Creating rcu_torture_fakewriter task
[    2.047580] rcu-torture: rcu_torture_fakewriter task started
[    2.049130] rcu-torture: Disabled dynamic grace-period expediting.
[    2.050408] rcu-torture: Creating rcu_torture_fakewriter task
[    2.051213] rcu-torture: rcu_torture_fakewriter task started
[    2.052374] rcu-torture: Creating rcu_torture_fakewriter task
[    2.053168] rcu-torture: rcu_torture_fakewriter task started
[    2.053904] rcu-torture: Creating rcu_torture_reader task
[    2.055182] rcu-torture: rcu_torture_fakewriter task started
[    2.055922] rcu-torture: Creating rcu_torture_stats task
[    2.056687] rcu-torture: rcu_torture_reader task started
[    2.057530] rcu-torture: Creating torture_shuffle task
[    2.058291] rcu-torture: rcu_torture_stats task started
[    2.058998] rcu-torture: Creating torture_stutter task
[    2.060780] rcu-torture: torture_shuffle task started
[    2.061505] rcu-torture: Creating rcu_torture_cbflood task
[    2.063306] rcu-torture: torture_stutter task started
[    2.065688] Kprobe smoke test: started
[    2.070879] rcu-torture: rcu_torture_cbflood task started
[    2.117116] Kprobe smoke test: passed successfully
[    2.120429] workingset: timestamp_bits=56 max_order=17 bucket_order=0
[    2.220092] NFS: Registering the id_resolver key type
[    2.221665] Key type id_resolver registered
[    2.222495] Key type id_legacy registered
[    2.223272] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    2.229763] fuse init (API version 7.26)
[    2.233069] JFS: nTxBlock = 3622, nTxLock = 28981
[    2.237899] SGI XFS with ACLs, security attributes, no debug enabled
[    2.250694] 9p: Installing v9fs 9p2000 file system support
[    2.253697] NILFS version 2 loaded
[    2.259313] async_tx: api initialized (async)
[    2.260586] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    2.261859] io scheduler noop registered
[    2.262521] io scheduler deadline registered
[    2.263871] io scheduler cfq registered (default)
[    2.264870] io scheduler mq-deadline registered
[    2.265924] io scheduler kyber registered
[    2.271742] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[    2.276059] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    2.278489] ACPI: Power Button [PWRF]
[    2.281844] ioatdma: Intel(R) QuickData Technology Driver 4.00
[    3.040169] tsc: Refined TSC clocksource calibration: 2593.987 MHz
[    3.040962] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x25640c04e41, max_idle_ns: 440795289050 ns
[    3.483732] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[    3.721720] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    3.748517] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    3.775897] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[    3.783424] Initializing Nozomi driver 2.1d
[    3.788152] Non-volatile memory driver v1.3
[    3.790510] Linux agpgart interface v0.103
[    3.791608] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[    3.797101] Floppy drive(s): fd0 is 2.88M AMI BIOS
[    3.809502] FDC 0 is a S82078B
[    3.826731] brd: module loaded
[    3.843561] loop: module loaded
[    3.848642] ------------[ cut here ]------------
[    3.849012] IRQs not disabled as expected
[    3.849012] WARNING: CPU: 0 PID: 0 at kernel/rcu/tree.c:776 rcu_eqs_enter_common+0x5a/0x291
[    3.849012] Modules linked in:
[    3.849012] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W        4.15.0-rc7-00430-g8e130b0 #35
[    3.849012] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    3.849012] RIP: 0010:rcu_eqs_enter_common+0x5a/0x291
[    3.849012] RSP: 0000:ffffffff92803e78 EFLAGS: 00010082
[    3.849012] RAX: 000000000000001d RBX: 0000000000000082 RCX: 70a3d70a3d70a3e0
[    3.849012] RDX: 0000000000000001 RSI: 0000000000000001 RDI: 0000000000000046
[    3.849012] RBP: ffff8bfa5f7d9da0 R08: 00000000c5610c9a R09: 0000000000000004
[    3.849012] R10: 0000000000000000 R11: ffffffff93dc0467 R12: 0000000000000000
[    3.849012] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[    3.849012] FS:  0000000000000000(0000) GS:ffff8bfa5f600000(0000) knlGS:0000000000000000
[    3.849012] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    3.849012] CR2: 0000000000000000 CR3: 0000000009824000 CR4: 00000000000006f0
[    3.849012] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[    3.849012] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[    3.849012] Call Trace:
[    3.849012]  ? default_idle+0xbc/0x17c
[    3.849012]  rcu_irq_exit_irqson+0x21/0x48
[    3.849012]  trace_hardirqs_on+0xc2/0xd0
[    3.849012]  default_idle+0xbc/0x17c
[    3.849012]  default_idle_call+0x31/0x36
[    3.849012]  do_idle+0xd6/0x19f
[    3.849012]  cpu_startup_entry+0x1d/0x1f
[    3.849012]  start_kernel+0x40e/0x416
[    3.849012]  secondary_startup_64+0xa5/0xb0
[    3.849012] Code: 08 00 00 00 75 27 83 b8 88 08 00 00 00 74 1e 80 3d 8a ad 8c 01 00 75 15 48 c7 c7 a9 e3 5e 92 c6 05 7a ad 8c 01 01 e8 ca e1 f8 ff <0f> ff 8b 05 02 e0 8c 01 85 c0 0f 8e 95 00 00 00 65 8b 05 fb 13 
[    3.849012] ---[ end trace 2507864299958134 ]---
[    3.876781] lkdtm: No crash points registered, enable through debugfs
[    3.878625] Uniform Multi-Platform E-IDE driver
[    3.880149] piix 0000:00:01.1: IDE controller (0x8086:0x7010 rev 0x00)
[    3.882413] piix 0000:00:01.1: not 100% native mode: will probe irqs later
[    3.884755]     ide0: BM-DMA at 0xc080-0xc087
[    3.885517]     ide1: BM-DMA at 0xc088-0xc08f
[    3.886375] Probing IDE interface ide0...
[    4.416512] Probing IDE interface ide1...
[    5.102146] hdc: QEMU DVD-ROM, ATAPI CD/DVD-ROM drive
[    5.726635] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO0
[    5.728099] hdc: MWDMA2 mode selected
[    5.729471] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[    5.730625] ide1 at 0x170-0x177,0x376 on irq 15
[    5.733291] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports
[    5.735878] ide-gd driver 1.18
[    5.737984] Loading iSCSI transport class v2.0-870.
[    5.744449] Loading Adaptec I2O RAID: Version 2.4 Build 5go
[    5.747204] Detecting Adaptec I2O RAID controllers...
[    5.748766] Adaptec aacraid driver 1.2.1[50834]-custom
[    5.750763] aic94xx: Adaptec aic94xx SAS/SATA driver version 1.0.3 loaded
[    5.753927] isci: Intel(R) C600 SAS Controller Driver - version 1.2.0
[    5.756843] qla2xxx [0000:00:00.0]-0005: : QLogic Fibre Channel HBA Driver: 10.00.00.02-k.
[    5.760709] iscsi: registered transport (qla4xxx)
[    5.762302] QLogic iSCSI HBA Driver
[    5.763715] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006)
[    5.766339] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006)
[    5.767991] megasas: 07.703.05.00-rc1
[    5.769456] mpt3sas version 17.100.00.00 loaded
[    5.771677] GDT-HA: Storage RAID Controller Driver. Version: 3.05
[    5.802179] tun: Universal TUN/TAP device driver, 1.6
[    5.805514] Atheros(R) L2 Ethernet Driver - version 2.2.3
[    5.807309] Copyright (c) 2007 Atheros Corporation.
[    5.809663] cnic: QLogic cnicDriver v2.5.22 (July 20, 2015)
[    5.811904] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[    5.813401] e100: Copyright(c) 1999-2006 Intel Corporation
[    5.815122] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    5.816613] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    7.244782] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
[    7.575066] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[    7.577131] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[    7.579260] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[    7.580898] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    7.582624] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[    7.584471] igb: Copyright (c) 2007-2014 Intel Corporation.
[    7.586177] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[    7.588546] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    7.590184] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k
[    7.592544] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[    7.594344] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI
[    7.596027] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[    7.597565] jme: JMicron JMC2XX ethernet driver version 1.0.8
[    7.599692] sky2: driver version 1.30
[    7.602575] usbcore: registered new interface driver catc
[    7.605207] usbcore: registered new interface driver kaweth
[    7.606613] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver
[    7.609333] usbcore: registered new interface driver pegasus
[    7.610927] usbcore: registered new interface driver rtl8150
[    7.612661] usbcore: registered new interface driver asix
[    7.614376] usbcore: registered new interface driver ax88179_178a
[    7.616346] usbcore: registered new interface driver cdc_ether
[    7.617984] usbcore: registered new interface driver cdc_eem
[    7.619726] usbcore: registered new interface driver dm9601
[    7.621503] usbcore: registered new interface driver smsc75xx
[    7.623494] usbcore: registered new interface driver smsc95xx
[    7.625350] usbcore: registered new interface driver gl620a
[    7.626960] usbcore: registered new interface driver net1080
[    7.628645] usbcore: registered new interface driver plusb
[    7.630507] usbcore: registered new interface driver rndis_host
[    7.632324] usbcore: registered new interface driver cdc_subset
[    7.634280] usbcore: registered new interface driver zaurus
[    7.635831] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[    7.638385] usbcore: registered new interface driver int51x1
[    7.639995] usbcore: registered new interface driver cdc_ncm
[    7.641518] Fusion MPT base driver 3.04.20
[    7.642874] Copyright (c) 1999-2008 LSI Corporation
[    7.644173] Fusion MPT SPI Host driver 3.04.20
[    7.645613] Fusion MPT FC Host driver 3.04.20
[    7.647171] Fusion MPT SAS Host driver 3.04.20
[    7.648747] Fusion MPT misc device (ioctl) driver 3.04.20
[    7.650783] mptctl: Registered with Fusion MPT base driver
[    7.652150] mptctl: /dev/mptctl @ (major,minor=10,220)
[    7.653832] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    7.655503] ehci-pci: EHCI PCI platform driver
[    7.656991] uhci_hcd: USB Universal Host Controller Interface driver
[    7.659876] usbcore: registered new interface driver usb-storage
[    7.661682] usbcore: registered new interface driver ums-alauda
[    7.663458] usbcore: registered new interface driver ums-cypress
[    7.664391] usbcore: registered new interface driver ums-datafab
[    7.665640] usbcore: registered new interface driver ums-freecom
[    7.667485] usbcore: registered new interface driver ums-isd200
[    7.669114] usbcore: registered new interface driver ums-jumpshot
[    7.671211] usbcore: registered new interface driver ums-karma
[    7.673110] usbcore: registered new interface driver ums-onetouch
[    7.674369] usbcore: registered new interface driver ums-sddr09
[    7.676296] usbcore: registered new interface driver ums-sddr55
[    7.677896] usbcore: registered new interface driver ums-usbat
[    7.679966] usbcore: registered new interface driver usbserial_generic
[    7.681968] usbserial: USB Serial support registered for generic
[    7.683821] usbcore: registered new interface driver belkin_sa
[    7.685703] usbserial: USB Serial support registered for Belkin / Peracom / GoHubs USB Serial Adapter
[    7.688480] usbcore: registered new interface driver mct_u232
[    7.690127] usbserial: USB Serial support registered for MCT U232
[    7.692241] usbcore: registered new interface driver usbtest
[    7.694453] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    7.698864] serio: i8042 KBD port at 0x60,0x64 irq 1
[    7.700615] serio: i8042 AUX port at 0x60,0x64 irq 12
[    7.704310] mousedev: PS/2 mouse device common for all mice
[    7.708720] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    7.712591] rtc_cmos 00:00: RTC can wake from S4
[    7.713536] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    7.713900] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[    7.714494] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05
[    7.715388] i6300esb: initialized (0x0000000069484bc2). heartbeat=30 sec (nowayout=0)
[    7.715654] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[    7.715852] iTCO_vendor_support: vendor-support=0
[    7.716009] watchdog: Software Watchdog: cannot register miscdev on minor=130 (err=-16).
[    7.716037] watchdog: Software Watchdog: a legacy watchdog module is probably present.
[    7.716627] softdog: initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=0)
[    7.728038] device-mapper: uevent: version 1.0.3
[    7.730394] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[    7.738941] device-mapper: multipath round-robin: version 1.2.0 loaded
[    7.738951] device-mapper: multipath queue-length: version 0.2.0 loaded
[    7.738958] device-mapper: multipath service-time: version 0.3.0 loaded
[    7.741372] device-mapper: dm-log-userspace: version 1.3.0 loaded
[    7.742226] usbcore: registered new interface driver usbhid
[    7.742229] usbhid: USB HID core driver
[    7.743944] acer_wmi: Acer Laptop ACPI-WMI Extras
[    7.743954] acer_wmi: No or unsupported WMI interface, unable to load
[    7.754366] oprofile: using NMI interrupt.
[    7.754544] netem: version 1.3
[    7.754563] Netfilter messages via NETLINK v0.30.
[    7.755859] nf_conntrack version 0.5.0 (4096 buckets, 16384 max)
[    7.761039] ctnetlink v0.93: registering with nfnetlink.
[    7.763962] xt_time: kernel timezone is -0000
[    7.764316] ip_tables: (C) 2000-2006 Netfilter Core Team
[    7.764681] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
[    7.764713] arp_tables: arp_tables: (C) 2002 David S. Miller
[    7.764858] Initializing XFRM netlink socket
[    7.780663] NET: Registered protocol family 10
[    7.793514] Segment Routing with IPv6
[    7.793622] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    7.795458] NET: Registered protocol family 17
[    7.795499] NET: Registered protocol family 15
[    7.808567] Bridge firewalling registered
[    7.808573] Ebtables v2.0 registered
[    7.809033] 9pnet: Installing 9P2000 support
[    7.809286] Key type dns_resolver registered
[    7.809290] start plist test
[    7.811037] end plist test
[    7.813748] ... APIC ID:      00000000 (0)
[    7.813750] ... APIC VERSION: 01050014
[    7.813758] 0000000000000000000000000000000000000000000000000000000000000000
[    7.813771] 0000000000000000000000000000000000000000000000000000000000000000
[    7.813783] 0000000000000000000000000000000000000000000000000000000000000000
[    7.813803] number of MP IRQ sources: 15.
[    7.813806] number of IO-APIC #0 registers: 24.
[    7.813808] testing the IO APIC.......................
[    7.813825] IO APIC #0......
[    7.813827] .... register #00: 00000000
[    7.813829] .......    : physical APIC id: 00
[    7.813832] .......    : Delivery Type: 0
[    7.813834] .......    : LTS          : 0
[    7.813836] .... register #01: 00170011
[    7.813838] .......     : max redirection entries: 17
[    7.813840] .......     : PRQ implemented: 0
[    7.813842] .......     : IO APIC version: 11
[    7.813844] .... register #02: 00000000
[    7.813846] .......     : arbitration: 00
[    7.813848] .... IRQ redirection table:
[    7.813851] IOAPIC 0:
[    7.813863]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.813875]  pin01, enabled , edge , high, V(28), IRR(0), S(0), logical , D(01), M(0)
[    7.813886]  pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(0)
[    7.813898]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.813909]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.813921]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.813932]  pin06, enabled , edge , high, V(22), IRR(0), S(0), logical , D(01), M(0)
[    7.813943]  pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.813954]  pin08, enabled , edge , high, V(29), IRR(0), S(0), logical , D(01), M(0)
[    7.813966]  pin09, enabled , level, high, V(21), IRR(0), S(0), logical , D(01), M(0)
[    7.813977]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.813988]  pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814000]  pin0c, enabled , edge , high, V(27), IRR(0), S(0), logical , D(01), M(0)
[    7.814139]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814150]  pin0e, enabled , edge , high, V(25), IRR(0), S(0), logical , D(01), M(0)
[    7.814162]  pin0f, enabled , edge , high, V(26), IRR(0), S(0), logical , D(01), M(0)
[    7.814173]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814184]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814195]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814207]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814218]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814229]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814241]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814252]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.814254] IRQ to pin mappings:
[    7.814258] IRQ0 -> 0:2
[    7.814264] IRQ1 -> 0:1
[    7.814270] IRQ3 -> 0:3
[    7.814276] IRQ4 -> 0:4
[    7.814282] IRQ5 -> 0:5
[    7.814288] IRQ6 -> 0:6
[    7.814295] IRQ7 -> 0:7
[    7.814300] IRQ8 -> 0:8
[    7.814307] IRQ9 -> 0:9
[    7.814313] IRQ10 -> 0:10
[    7.814319] IRQ11 -> 0:11
[    7.814325] IRQ12 -> 0:12
[    7.814332] IRQ13 -> 0:13
[    7.814338] IRQ14 -> 0:14
[    7.814344] IRQ15 -> 0:15
[    7.814350] .................................... done.
[    7.814474] sched_clock: Marking stable (7814010268, 0)->(8503644081, -689633813)
[    7.816903] registered taskstats version 1
[    7.841706] Btrfs loaded, crc32c=crc32c-generic
[    7.934043]   Magic number: 6:689:44
[    7.934660] tty ttyed: hash matches
[    7.935641] console [netcon0] enabled
[    7.936305] netconsole: network logging started
[    7.937120] rtc_cmos 00:00: setting system clock to 2018-02-07 21:01:34 UTC (1518037294)
[    7.943238] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    8.554149] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    9.952569] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[    9.957066] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    9.969040] Sending DHCP requests ., OK
[    9.970658] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[    9.972188] IP-Config: Complete:
[    9.973177]      device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[    9.975424]      host=vm-lkp-hsw01-yocto-x86_64-26, domain=, nis-domain=(none)
[    9.977359]      bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=     nameserver0=10.0.2.3
[    9.980817] ALSA device list:
[    9.981827]   No soundcards found.
[   10.010422] Freeing unused kernel memory: 3644K
[   10.011651] Write protecting the kernel read-only data: 24576k
[   10.019510] Freeing unused kernel memory: 1984K
[   10.021468] Freeing unused kernel memory: 172K
/etc/rcS.d/S00fbsetup: line 3: /sbin/modprobe: not found
[   10.085659] mount (2151) used greatest stack depth: 12840 bytes left
Starting udev
[   10.153517] udevd[2164]: starting version 3.1.5
[   10.525303] input: PC Speaker as /devices/platform/pcspkr/input/input4
LKP: HOSTNAME vm-lkp-hsw01-yocto-x86_64-26, MAC de:91:90:22:f8:b2, kernel 4.15.0-rc7-00430-g8e130b0 35, serial console /dev/ttyS0
Poky (Yocto Project Reference Distro) 2.1 vm-lkp-hsw01-yocto-x86_64-26 /dev/ttyS0
[   12.904161] /lkp/lkp/src/bin/run-lkp
[   12.904161] RESULT_ROOT=/result/boot/1/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/0
[   12.904161] job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-26/boot-1-yocto-minimal-x86_64-2016-04-22.cgz-8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112-20180207-70148-15cyihi-0.yaml
[   12.904161] run-job /lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-26/boot-1-yocto-minimal-x86_64-2016-04-22.cgz-8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112-20180207-70148-15cyihi-0.yaml
[   12.904161] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-26/boot-1-yocto-minimal-x86_64-2016-04-22.cgz-8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112-20180207-70148-15cyihi-0.yaml&job_state=running -O /dev/null
[   12.904161] skip microcode check for virtual machine
[   14.919329] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-26/boot-1-yocto-minimal-x86_64-2016-04-22.cgz-8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112-20180207-70148-15cyihi-0.yaml&job_state=post_run -O /dev/null
[   16.928378] kill 2487 cat /proc/kmsg 
[   16.928378] wait for background monitors: 2490 oom-killer
[   18.706557] sysrq: SysRq : Emergency Sync
[   18.708174] Emergency Sync complete
[   18.709611] sysrq: SysRq : Resetting

Elapsed time: 20

#!/bin/bash

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

kernel=$1

initrds=(
	/osimage/yocto/yocto-minimal-x86_64-2016-04-22.cgz
	/lkp/lkp/lkp-x86_64.cgz
)

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

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

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

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

append=(
	ip=::::vm-lkp-hsw01-yocto-x86_64-26::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-nfsroot
	branch=linux-review/Joel-Fernandes/tracing-Improve-design-of-preemptirq-tracepoints-and-its-users/20180207-195707
	commit=8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112
	BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/vmlinuz-4.15.0-rc7-00430-g8e130b0
	max_uptime=600
	RESULT_ROOT=/result/boot/1/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-nfsroot/gcc-7/8e130b0d9284a0a01ca1d6ecf8f0896cfc28b112/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
	rcuperf.shutdown=0
)

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