early console in setup code
early console in extract_kernel
input_data: 0x0000000003b1c295
input_len: 0x0000000000a8466c
output: 0x0000000001000000
output_len: 0x0000000002197f30
kernel_total_size: 0x00000000035c6000
trampoline_32bit: 0x000000000009d000

Decompressing Linux... Parsing ELF... done.
Booting the kernel.
[    0.000000] Linux version 4.17.0-rc3-00051-gb6da31b (kbuild@lkp-g5) (gcc version 7.3.0 (Debian 7.3.0-16)) #2 Tue May 29 04:01:30 CST 2018
[    0.000000] Command line: ip=::::vm-lkp-hsw-4ep1-yocto-x86_64-6::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw-4ep1-yocto-x86_64-6/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-b6da31b2c07c46f2dcad1d86caa835227a16d9ff-20180529-8194-l5uu07-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-b0-05281952 branch=linux-devel/devel-hourly-2018052817 commit=b6da31b2c07c46f2dcad1d86caa835227a16d9ff BOOT_IMAGE=/pkg/linux/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/vmlinuz-4.17.0-rc3-00051-gb6da31b max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw-4ep1-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/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 conso
[    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-0x000000001ffdbfff] usable
[    0.000000] BIOS-e820: [mem 0x000000001ffdc000-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] 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 = 0x1ffdc max_arch_pfn = 0x400000000
[    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 0x000f6a90-0x000f6a9f] mapped at [        (ptrval)]
[    0.000000]   mpc: f6aa0-f6b80
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [        (ptrval)] 99000 size 24576
[    0.000000] BRK [0x045a1000, 0x045a1fff] PGTABLE
[    0.000000] BRK [0x045a2000, 0x045a2fff] PGTABLE
[    0.000000] BRK [0x045a3000, 0x045a3fff] PGTABLE
[    0.000000] BRK [0x045a4000, 0x045a4fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x1f986000-0x1ffcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x000000001FFE15CE 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x000000001FFE142A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x000000001FFE0040 0013EA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x000000001FFE0000 000040
[    0.000000] ACPI: APIC 0x000000001FFE151E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x000000001FFE1596 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.000000] kvm-clock: cpu 0, msr 0:1ffda001, primary cpu clock
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: using sched offset of 2156044436 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-0x000000001ffdbfff]
[    0.000000]   Normal   empty
[    0.000000]   Device   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-0x000000001ffdbfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffdbfff]
[    0.000000] On node 0 totalpages: 130938
[    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: 126940 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] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 2a0fe80
[    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] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 128869
[    0.000000] Kernel command line: ip=::::vm-lkp-hsw-4ep1-yocto-x86_64-6::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw-4ep1-yocto-x86_64-6/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-b6da31b2c07c46f2dcad1d86caa835227a16d9ff-20180529-8194-l5uu07-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-b0-05281952 branch=linux-devel/devel-hourly-2018052817 commit=b6da31b2c07c46f2dcad1d86caa835227a16d9ff BOOT_IMAGE=/pkg/linux/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/vmlinuz-4.17.0-rc3-00051-gb6da31b max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw-4ep1-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/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_logleve
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Memory: 453216K/523752K available (16396K kernel code, 2111K rwdata, 6024K rodata, 1740K init, 22560K bss, 70536K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] ftrace: allocating 60431 entries in 237 pages
[    0.000000] 
[    0.000000] **********************************************************
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **                                                      **
[    0.000000] ** trace_printk() being used. Allocating extra memory.  **
[    0.000000] **                                                      **
[    0.000000] ** This means that this is a DEBUG kernel and it is     **
[    0.000000] ** unsafe for production use.                           **
[    0.000000] **                                                      **
[    0.000000] ** If you see this message and you are not debugging    **
[    0.000000] ** the kernel, report this immediately to your vendor!  **
[    0.000000] **                                                      **
[    0.000000] **   NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE   **
[    0.000000] **********************************************************
[    0.004000] Running RCU self tests
[    0.004000] NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16
[    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: 7391 kB
[    0.004000]  per task-struct memory footprint: 1920 bytes
[    0.004000] ODEBUG: selftest passed
[    0.004000] ACPI: Core revision 20180313
[    0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.004000] hpet clockevent registered
[    0.004145] APIC: Switch to symmetric I/O mode setup
[    0.012192] x2apic enabled
[    0.015320] Switched APIC routing to physical x2apic.
[    0.016060] enabled ExtINT on CPU#0
[    0.023324] ENABLING IO-APIC IRQs
[    0.024239] init IO_APIC IRQs
[    0.025151]  apic 0 pin 0 not connected
[    0.028640] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:0)
[    0.036166] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:0)
[    0.040000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:0)
[    0.040000]  apic 0 pin 16 not connected
[    0.040000]  apic 0 pin 17 not connected
[    0.040000]  apic 0 pin 18 not connected
[    0.040000]  apic 0 pin 19 not connected
[    0.040000]  apic 0 pin 20 not connected
[    0.040000]  apic 0 pin 21 not connected
[    0.040000]  apic 0 pin 22 not connected
[    0.040000]  apic 0 pin 23 not connected
[    0.040000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.040000] tsc: Detected 1999.998 MHz processor
[    0.040000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x39a8595ce59, max_idle_ns: 881590778713 ns
[    0.040376] Calibrating delay loop (skipped) preset value.. 3999.99 BogoMIPS (lpj=7999992)
[    0.044015] pid_max: default: 4096 minimum: 301
[    0.045693] Security Framework initialized
[    0.046704] Smack:  Initializing.
[    0.047420] Smack:  IPv6 port labeling enabled.
[    0.048347] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.052171] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.059393] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.060082] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.064016] CPU: Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3)
[    0.068040] Spectre V2 : Spectre mitigation: kernel not compiled with retpoline; no mitigation available!
[    0.088630] Performance Events: unsupported p6 CPU model 26 no PMU driver, software events only.
[    0.097739] Using local APIC timer interrupts.
[    0.097739] calibrating APIC timer ...
[    0.104000] ... lapic delta = 6240592
[    0.104000] ... PM-Timer delta = 357388
[    0.104000] ... PM-Timer result ok
[    0.104000] ..... delta 6240592
[    0.104000] ..... mult: 268031385
[    0.104000] ..... calibration result: 3993978
[    0.104000] ..... CPU clock speed is 1996.3136 MHz.
[    0.104000] ..... host bus clock speed is 998.1978 MHz.
[    0.110923] devtmpfs: initialized
[    0.113557] x86/mm: Memory block size: 128MB
[    0.124000] random: get_random_u32 called from bucket_table_alloc+0x11a/0x14b with crng_init=0
[    0.135709] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.136080] futex hash table entries: 16 (order: -2, 1280 bytes)
[    0.144604] pinctrl core: initialized pinctrl subsystem
[    0.151892] NET: Registered protocol family 16
[    0.162493] cpuidle: using governor ladder
[    0.170568] ACPI: bus type PCI registered
[    0.173626] PCI: Using configuration type 1 for base access
[    0.269795] workqueue: round-robin CPU selection forced, expect performance impact
[    0.276353] cryptd: max_cpu_qlen set to 1000
[    0.279422] gpio-f7188x: Not a Fintek device at 0x0000002e
[    0.280187] gpio-f7188x: Not a Fintek device at 0x0000004e
[    0.284431] ACPI: Added _OSI(Module Device)
[    0.286313] ACPI: Added _OSI(Processor Device)
[    0.288012] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.289949] ACPI: Added _OSI(Processor Aggregator Device)
[    0.292122] ACPI: Added _OSI(Linux-Dell-Video)
[    0.306775] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.327247] ACPI: Interpreter enabled
[    0.328385] ACPI: (supports S0 S3 S5)
[    0.329422] ACPI: Using IOAPIC for interrupt routing
[    0.332408] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.337930] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.405251] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.408154] acpi PNP0A03:00: _OSC: OS supports [Segments MSI]
[    0.412580] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.416303] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.421046] PCI host bridge to bus 0000:00
[    0.422070] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.424020] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.425724] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.428009] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window]
[    0.429693] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.431805] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.438038] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.445950] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.487797] pci 0000:00:01.1: reg 0x20: [io  0xc0c0-0xc0cf]
[    0.505812] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.508041] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.510279] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.512061] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.522615] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.525496] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.528173] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.533282] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.548617] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.576280] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.628264] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.640461] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.652071] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.664144] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.728238] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.740294] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    0.756496] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    0.768286] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    0.808190] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    0.827102] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[    0.840144] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
[    0.852120] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[    0.896126] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[    0.914463] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000
[    0.918857] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f]
[    0.944825] pci_bus 0000:00: on NUMA node 0
[    0.949574] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.951272] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.952562] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.954287] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.956276] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.959515] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.960000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.960030] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.961416] vgaarb: loaded
[    0.964747] ACPI: bus type USB registered
[    0.965616] usbcore: registered new interface driver usbfs
[    0.967038] usbcore: registered new interface driver hub
[    0.968069] usbcore: registered new device driver usb
[    0.970763] FPGA manager framework
[    0.971478] PCI: Using ACPI for IRQ routing
[    0.972010] PCI: pci_cache_line_size set to 64 bytes
[    0.973131] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.974191] e820: reserve RAM buffer [mem 0x1ffdc000-0x1fffffff]
[    0.977049] Bluetooth: Core ver 2.22
[    0.977728] NET: Registered protocol family 31
[    0.978503] Bluetooth: HCI device and connection manager initialized
[    0.980015] Bluetooth: HCI socket layer initialized
[    0.980922] Bluetooth: L2CAP socket layer initialized
[    0.981986] NET: Registered protocol family 8
[    0.982967] NET: Registered protocol family 20
[    0.983755] NetLabel: Initializing
[    0.984011] NetLabel:  domain hash size = 128
[    0.984762] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.985958] NetLabel:  unlabeled traffic allowed by default
[    0.987486] nfc: nfc_init: NFC Core ver 0.1
[    0.988103] NET: Registered protocol family 39
[    0.989326] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[    0.994331] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.995581] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.996010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    1.000279] clocksource: Switched to clocksource kvm-clock
[    1.059225] kwatchdog (20) used greatest stack depth: 15016 bytes left
[    1.229481] pnp: PnP ACPI init
[    1.231355] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    1.233691] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    1.235896] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    1.237995] pnp 00:03: [dma 2]
[    1.239126] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    1.241657] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    1.244330] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    1.246791] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    1.250761] pnp: PnP ACPI: found 7 devices
[    1.259083] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.262372] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.264162] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    1.265831] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    1.267622] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window]
[    1.269542] NET: Registered protocol family 2
[    1.271716] tcp_listen_portaddr_hash hash table entries: 256 (order: 2, 18432 bytes)
[    1.273479] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    1.274783] TCP bind hash table entries: 4096 (order: 6, 262144 bytes)
[    1.276713] TCP: Hash tables configured (established 4096 bind 4096)
[    1.278025] UDP hash table entries: 256 (order: 3, 40960 bytes)
[    1.279759] UDP-Lite hash table entries: 256 (order: 3, 40960 bytes)
[    1.281189] NET: Registered protocol family 1
[    1.282065] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.283159] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.284251] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    1.285408] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    1.287045] PCI: CLS 0 bytes, default 64
[    1.288259] Unpacking initramfs...
[    4.937038] Freeing initrd memory: 6440K
[    4.962747] Scanning for low memory corruption every 60 seconds
[    4.983238] PCLMULQDQ-NI instructions are not detected.
[    4.988084] cryptomgr_test (39) used greatest stack depth: 14808 bytes left
[    4.996196] AVX or AES-NI instructions are not detected.
[    5.001245] CPU feature 'AVX registers' is not supported.
[    5.007374] CPU feature 'AVX registers' is not supported.
[    5.012839] CPU feature 'AVX registers' is not supported.
[    5.017089] CPU feature 'AVX registers' is not supported.
[    5.020997] AVX2 or AES-NI instructions are not detected.
[    5.025281] AVX2 instructions are not detected.
[    5.034869] spin_lock-torture:--- Start of test [debug]: nwriters_stress=2 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0
[    5.048766] spin_lock-torture: Creating torture_shuffle task
[    5.057018] spin_lock-torture: Creating torture_stutter task
[    5.061914] spin_lock-torture: torture_shuffle task started
[    5.067905] spin_lock-torture: Creating lock_torture_writer task
[    5.073084] spin_lock-torture: torture_stutter task started
[    5.077416] spin_lock-torture: Creating lock_torture_writer task
[    5.081654] spin_lock-torture: lock_torture_writer task started
[    5.088895] spin_lock-torture: Creating lock_torture_stats task
[    5.093454] spin_lock-torture: lock_torture_writer task started
[    5.103810] spin_lock-torture: lock_torture_stats task started
[    6.022127] tsc: Refined TSC clocksource calibration: 1999.994 MHz
[    6.026657] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x39a8513f26b, max_idle_ns: 881590774457 ns
[   11.888653] torture_init_begin: Refusing rcu init: spin_lock running.
[   11.914865] torture_init_begin: One torture test at a time!
[   11.924731] workingset: timestamp_bits=62 max_order=17 bucket_order=0
[   11.945209] zbud: loaded
[   11.951840] DLM installed
[   11.953192] fuse init (API version 7.26)
[   11.976339] NET: Registered protocol family 38
[   11.982559] test_hexdump: all 1184 tests passed
[   11.983981] 
[   11.983981] Start testing find_bit() with random-filled bitmap
[   11.991086] find_next_bit:                 2686370 ns, 163873 iterations
[   11.995877] find_next_zero_bit:            2814295 ns, 163808 iterations
[   12.000416] find_last_bit:                 2545246 ns, 163873 iterations
[   13.132210] find_first_bit:             1129553409 ns, 163873 iterations
[   13.136486] find_next_and_bit:		1406008 cycles, 1 iterations
[   13.138525] 
[   13.138525] Start testing find_bit() with sparse bitmap
[   13.141028] find_next_bit:                   26517 ns,    656 iterations
[   13.148404] find_next_zero_bit:            5410536 ns, 327025 iterations
[   13.150258] find_last_bit:                   41723 ns,    656 iterations
[   13.157467] find_first_bit:                5285163 ns,    656 iterations
[   13.159551] find_next_and_bit:		340414 cycles, 1 iterations
[   13.250407] Running rhashtable test nelem=8, max_size=0, shrinking=0
[   13.253102] Test 00:
[   13.254315]   Adding 50000 keys
[   13.746943]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   13.828875]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   13.830893]   Deleting 50000 keys
[   13.924052]   Duration of test: 668851293 ns
[   13.926300] Test 01:
[   13.927541]   Adding 50000 keys
[   14.302964]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   14.515942]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   14.520132]   Deleting 50000 keys
[   14.721251]   Duration of test: 792673706 ns
[   14.729095] Test 02:
[   14.733522]   Adding 50000 keys
[   15.451072]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   15.686968]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   15.701743]   Deleting 50000 keys
[   15.896193]   Duration of test: 1160714941 ns
[   15.901556] Test 03:
[   15.904704]   Adding 50000 keys
[   16.493716]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   16.653487]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[   16.657180]   Deleting 50000 keys
[   16.825040]   Duration of test: 918536938 ns
[   16.907374] test if its possible to exceed max_size 8192: no, ok
[   16.916370] Average test time: 885194219
[   16.919023] test inserting duplicates
[   16.921856] ODEBUG: object is on stack, but not annotated
[   16.923012] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:366 __debug_object_init+0x379/0x439
[   16.923012] CPU: 0 PID: 1 Comm: swapper Not tainted 4.17.0-rc3-00051-gb6da31b #2
[   16.923012] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   16.923012] RIP: 0010:__debug_object_init+0x379/0x439
[   16.923012] RSP: 0000:ffff88001f6ebb90 EFLAGS: 00010086
[   16.923012] RAX: 000000000000002d RBX: ffff88001f6ebca0 RCX: ffff88001f6eba34
[   16.923012] RDX: ffff88001f6e0000 RSI: 0000000000000002 RDI: ffffffff810da8ad
[   16.923012] RBP: ffffffff83cc5988 R08: 0000000000000001 R09: 000000000000000c
[   16.923012] R10: ffff88001f6ebb10 R11: 0000000000000000 R12: ffff88001f7f4398
[   16.923012] R13: ffffffff82852900 R14: 0000000000073c00 R15: ffffffff83cc5980
[   16.923012] FS:  0000000000000000(0000) GS:ffffffff82835000(0000) knlGS:0000000000000000
[   16.923012] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   16.923012] CR2: 0000000000000000 CR3: 000000000281a000 CR4: 00000000000006b0
[   16.923012] Call Trace:
[   16.923012]  rhashtable_init+0x1bc/0x201
[   16.923012]  rhltable_init+0xf/0x1b
[   16.923012]  test_insert_dup+0x45/0x62d
[   16.923012]  ? lock_acquire+0x154/0x185
[   16.923012]  ? console_unlock+0x4b6/0x51f
[   16.923012]  ? kstrtos8_from_user+0x6c/0x6c
[   16.923012]  ? jhash+0x130/0x130
[   16.923012]  ? my_hashfn+0x10/0x10
[   16.923012]  test_rht_init+0xd2e/0x1042
[   16.923012]  ? test_rhltable+0x2e30/0x2e30
[   16.923012]  do_one_initcall+0xef/0x247
[   16.923012]  ? do_early_param+0x8d/0x8d
[   16.923012]  ? do_early_param+0x8d/0x8d
[   16.923012]  kernel_init_freeable+0x2a1/0x33d
[   16.923012]  ? rest_init+0x12d/0x12d
[   16.923012]  kernel_init+0xa/0xe6
[   16.923012]  ret_from_fork+0x24/0x30
[   16.923012] Code: f8 04 7f 29 48 8b 0c 25 40 90 83 82 48 8b 49 18 48 39 cb 0f 82 91 00 00 00 48 81 c1 00 40 00 00 48 39 cb 0f 82 a7 00 00 00 eb 7f <0f> 0b 41 8b 44 24 10 83 f8 03 74 14 72 07 83 f8 04 74 3e eb 4b 
[   16.923012] irq event stamp: 3998404
[   16.923012] hardirqs last  enabled at (3998403): [<ffffffff81d35f17>] _raw_read_unlock_irqrestore+0x44/0x57
[   16.923012] hardirqs last disabled at (3998404): [<ffffffff81d358d4>] _raw_spin_lock_irqsave+0x20/0x51
[   16.923012] softirqs last  enabled at (3998256): [<ffffffff8200033e>] __do_softirq+0x33e/0x378
[   16.923012] softirqs last disabled at (3998249): [<ffffffff810a2f44>] irq_exit+0x55/0x6b
[   16.923012] ---[ end trace 3e020352ba0e998b ]---
[   17.011053] 
[   17.011576] =============================
[   17.012898] WARNING: suspicious RCU usage
[   17.014134] 4.17.0-rc3-00051-gb6da31b #2 Tainted: G        W        
[   17.016049] -----------------------------
[   17.017535] lib/test_rhashtable.c:502 suspicious rcu_dereference_protected() usage!
[   17.020619] 
[   17.020619] other info that might help us debug this:
[   17.020619] 
[   17.023284] 
[   17.023284] rcu_scheduler_active = 2, debug_locks = 1
[   17.025472] no locks held by swapper/1.
[   17.026644] 
[   17.026644] stack backtrace:
[   17.027953] CPU: 0 PID: 1 Comm: swapper Tainted: G        W         4.17.0-rc3-00051-gb6da31b #2
[   17.030796] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   17.033466] Call Trace:
[   17.034458]  print_ht+0x68/0x275
[   17.035472]  ? __debug_object_init+0x379/0x439
[   17.037015]  test_insert_dup+0x5c3/0x62d
[   17.038259]  ? my_hashfn+0x10/0x10
[   17.039353]  ? kstrtos8_from_user+0x6c/0x6c
[   17.040752]  ? jhash+0x130/0x130
[   17.041743]  ? my_hashfn+0x10/0x10
[   17.043047]  ? rhashtable_walk_peek+0x2e/0x2e
[   17.044351]  test_rht_init+0xd2e/0x1042
[   17.046901]  ? test_rhltable+0x2e30/0x2e30
[   17.048592]  do_one_initcall+0xef/0x247
[   17.049713]  ? do_early_param+0x8d/0x8d
[   17.050406]  ? do_early_param+0x8d/0x8d
[   17.051106]  kernel_init_freeable+0x2a1/0x33d
[   17.051861]  ? rest_init+0x12d/0x12d
[   17.052735]  kernel_init+0xa/0xe6
[   17.053432]  ret_from_fork+0x24/0x30
[   17.054056] 
[   17.054328] =============================
[   17.055061] WARNING: suspicious RCU usage
[   17.055781] 4.17.0-rc3-00051-gb6da31b #2 Tainted: G        W        
[   17.057025] -----------------------------
[   17.057895] lib/test_rhashtable.c:507 suspicious rcu_dereference_protected() usage!
[   17.059766] 
[   17.059766] other info that might help us debug this:
[   17.059766] 
[   17.061863] 
[   17.061863] rcu_scheduler_active = 2, debug_locks = 1
[   17.065111] no locks held by swapper/1.
[   17.067048] 
[   17.067048] stack backtrace:
[   17.069393] CPU: 0 PID: 1 Comm: swapper Tainted: G        W         4.17.0-rc3-00051-gb6da31b #2
[   17.074123] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   17.078107] Call Trace:
[   17.079828]  print_ht+0xb4/0x275
[   17.081881]  ? __debug_object_init+0x379/0x439
[   17.084480]  test_insert_dup+0x5c3/0x62d
[   17.086362]  ? my_hashfn+0x10/0x10
[   17.088091]  ? kstrtos8_from_user+0x6c/0x6c
[   17.089143]  ? jhash+0x130/0x130
[   17.089701]  ? my_hashfn+0x10/0x10
[   17.090470]  ? rhashtable_walk_peek+0x2e/0x2e
[   17.091275]  test_rht_init+0xd2e/0x1042
[   17.091963]  ? test_rhltable+0x2e30/0x2e30
[   17.092669]  do_one_initcall+0xef/0x247
[   17.093357]  ? do_early_param+0x8d/0x8d
[   17.094073]  ? do_early_param+0x8d/0x8d
[   17.094747]  kernel_init_freeable+0x2a1/0x33d
[   17.095505]  ? rest_init+0x12d/0x12d
[   17.096144]  kernel_init+0xa/0xe6
[   17.096722]  ret_from_fork+0x24/0x30
[   17.097350] 
[   17.097622] =============================
[   17.098339] WARNING: suspicious RCU usage
[   17.099056] 4.17.0-rc3-00051-gb6da31b #2 Tainted: G        W        
[   17.100193] -----------------------------
[   17.100910] lib/test_rhashtable.c:508 suspicious rcu_dereference_protected() usage!
[   17.102608] 
[   17.102608] other info that might help us debug this:
[   17.102608] 
[   17.104496] 
[   17.104496] rcu_scheduler_active = 2, debug_locks = 1
[   17.105655] no locks held by swapper/1.
[   17.106352] 
[   17.106352] stack backtrace:
[   17.107144] CPU: 0 PID: 1 Comm: swapper Tainted: G        W         4.17.0-rc3-00051-gb6da31b #2
[   17.108800] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   17.110863] Call Trace:
[   17.111313]  print_ht+0x102/0x275
[   17.111893]  ? __debug_object_init+0x379/0x439
[   17.112683]  test_insert_dup+0x5c3/0x62d
[   17.113426]  ? my_hashfn+0x10/0x10
[   17.114021]  ? kstrtos8_from_user+0x6c/0x6c
[   17.114742]  ? jhash+0x130/0x130
[   17.115335]  ? my_hashfn+0x10/0x10
[   17.115923]  ? rhashtable_walk_peek+0x2e/0x2e
[   17.116689]  test_rht_init+0xd2e/0x1042
[   17.117401]  ? test_rhltable+0x2e30/0x2e30
[   17.118163]  do_one_initcall+0xef/0x247
[   17.118838]  ? do_early_param+0x8d/0x8d
[   17.119913]  ? do_early_param+0x8d/0x8d
[   17.121047]  kernel_init_freeable+0x2a1/0x33d
[   17.122869]  ? rest_init+0x12d/0x12d
[   17.123912]  kernel_init+0xa/0xe6
[   17.125095]  ret_from_fork+0x24/0x30
[   17.126156] 
[   17.126508] =============================
[   17.127817] WARNING: suspicious RCU usage
[   17.129055] 4.17.0-rc3-00051-gb6da31b #2 Tainted: G        W        
[   17.130983] -----------------------------
[   17.132028] lib/test_rhashtable.c:519 suspicious rcu_dereference_protected() usage!
[   17.134642] 
[   17.134642] other info that might help us debug this:
[   17.134642] 
[   17.137321] 
[   17.137321] rcu_scheduler_active = 2, debug_locks = 1
[   17.139162] no locks held by swapper/1.
[   17.140277] 
[   17.140277] stack backtrace:
[   17.141423] CPU: 0 PID: 1 Comm: swapper Tainted: G        W         4.17.0-rc3-00051-gb6da31b #2
[   17.143578] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   17.146431] Call Trace:
[   17.147322]  print_ht+0x176/0x275
[   17.148340]  test_insert_dup+0x5c3/0x62d
[   17.149574]  ? my_hashfn+0x10/0x10
[   17.150382]  ? kstrtos8_from_user+0x6c/0x6c
[   17.151854]  ? jhash+0x130/0x130
[   17.152768]  ? my_hashfn+0x10/0x10
[   17.154020]  ? rhashtable_walk_peek+0x2e/0x2e
[   17.155455]  test_rht_init+0xd2e/0x1042
[   17.156652]  ? test_rhltable+0x2e30/0x2e30
[   17.158291]  do_one_initcall+0xef/0x247
[   17.159329]  ? do_early_param+0x8d/0x8d
[   17.160755]  ? do_early_param+0x8d/0x8d
[   17.161943]  kernel_init_freeable+0x2a1/0x33d
[   17.164513]  ? rest_init+0x12d/0x12d
[   17.165540]  kernel_init+0xa/0xe6
[   17.166616]  ret_from_fork+0x24/0x30
[   17.167520] 
[   17.168175] =============================
[   17.169364] WARNING: suspicious RCU usage
[   17.170667] 4.17.0-rc3-00051-gb6da31b #2 Tainted: G        W        
[   17.173092] -----------------------------
[   17.174705] lib/test_rhashtable.c:529 suspicious rcu_dereference_protected() usage!
[   17.177657] 
[   17.177657] other info that might help us debug this:
[   17.177657] 
[   17.182535] 
[   17.182535] rcu_scheduler_active = 2, debug_locks = 1
[   17.185901] no locks held by swapper/1.
[   17.188196] 
[   17.188196] stack backtrace:
[   17.191765] CPU: 0 PID: 1 Comm: swapper Tainted: G        W         4.17.0-rc3-00051-gb6da31b #2
[   17.198634] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[   17.204272] Call Trace:
[   17.205895]  print_ht+0x20d/0x275
[   17.208540]  test_insert_dup+0x5c3/0x62d
[   17.211869]  ? my_hashfn+0x10/0x10
[   17.213559]  ? kstrtos8_from_user+0x6c/0x6c
[   17.216511]  ? jhash+0x130/0x130
[   17.218873]  ? my_hashfn+0x10/0x10
[   17.221062]  ? rhashtable_walk_peek+0x2e/0x2e
[   17.224071]  test_rht_init+0xd2e/0x1042
[   17.227389]  ? test_rhltable+0x2e30/0x2e30
[   17.230446]  do_one_initcall+0xef/0x247
[   17.232352]  ? do_early_param+0x8d/0x8d
[   17.234222]  ? do_early_param+0x8d/0x8d
[   17.236704]  kernel_init_freeable+0x2a1/0x33d
[   17.239262]  ? rest_init+0x12d/0x12d
[   17.241179]  kernel_init+0xa/0xe6
[   17.243287]  ret_from_fork+0x24/0x30
[   17.247079] 
[   17.247079] ---- ht: ----
[   17.247079] bucket[1] -> [[ val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]]
[   17.247079] -------------
[   17.270780] 
[   17.270780] ---- ht: ----
[   17.270780] bucket[1] -> [[ val 1 (tid=2),  val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]]
[   17.270780] -------------
[   17.278659] 
[   17.278659] ---- ht: ----
[   17.278659] bucket[1] -> [[ val 21 (tid=1) ]] -> [[ val 1 (tid=0) ]]
[   17.278659] -------------
[   17.284804] 
[   17.284804] ---- ht: ----
[   17.284804] bucket[1] -> [[ val 21 (tid=1) ]] -> [[ val 1 (tid=2),  val 1 (tid=0) ]]
[   17.284804] -------------
[   17.293131] Testing concurrent rhashtable access from 10 threads
[   60.925384] rhashtable_thra (129) used greatest stack depth: 14384 bytes left
[   60.969724] rhashtable_thra (132) used greatest stack depth: 14088 bytes left
[   61.253174] test 3125 add/delete pairs into rhlist
[   61.624531] test 3125 random rhlist add/delete operations
[   62.073728] Started 10 threads, 0 failed, rhltable test returns 0
[   62.121079] test passed
[   62.122993] test_printf: hashing plain 'p' has unexpected format
[   62.125093] test_printf: failed 1 out of 240 tests
[   62.126778] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 1366
[   62.135654] test_bitmap: all 1524 tests passed
[   62.137086] test_uuid: all 18 tests passed
[   62.141807] crc32: CRC_LE_BITS = 64, CRC_BE BITS = 64
[   62.143371] crc32: self tests passed, processed 225944 bytes in 334460 nsec
[   62.145657] crc32c: CRC_LE_BITS = 64
[   62.146626] crc32c: self tests passed, processed 225944 bytes in 168220 nsec
[   62.182408] crc32_combine: 8373 self tests passed
[   62.216596] crc32c_combine: 8373 self tests passed
[   62.219914] glob: 64 self-tests passed, 0 failed
[   62.221746] rbtree testing
[   62.229023]  -> test 1 (latency of nnodes insert+delete): 14420 cycles
[   62.240196]  -> test 2 (latency of nnodes cached insert+delete): 15335 cycles
[   62.243591]  -> test 3 (latency of inorder traversal): 2127 cycles
[   62.246159]  -> test 4 (latency to fetch first node)
[   62.248767]         non-cached: 37 cycles
[   62.250600]         cached: 0 cycles
[   62.326427] augmented rbtree testing
[   62.335108]  -> test 1 (latency of nnodes insert+delete): 17322 cycles
[   62.350053]  -> test 2 (latency of nnodes cached insert+delete): 18113 cycles
[   62.458269] interval tree insert/remove
[   62.466890]  -> 16965 cycles
[   62.469377] interval tree search
[   62.526513]  -> 113913 cycles (2692 results)
[   62.531592] gpio_it87: no device
[   62.538432] switchtec: loaded.
[   62.542871] VIA Graphics Integration Chipset framebuffer 2.4 initializing
[   62.544867] vmlfb: initializing
[   62.545752] Could not find Carillo Ranch MCH device.
[   62.547373] no IO addresses supplied
[   62.549635] hgafb: HGA card not detected.
[   62.552774] hgafb: probe of hgafb.0 failed with error -22
[   62.558617] intel_idle: Please enable MWAIT in BIOS SETUP
[   62.563081] ipmi message handler version 39.2
[   62.570188] IPMI System Interface driver.
[   62.574628] ipmi_si: Unable to find any System Interface(s)
[   62.578433] IPMI SSIF Interface driver
[   62.590849] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   62.593761] ACPI: Power Button [PWRF]
[   62.968667] PCI Interrupt Link [LNKD] enabled at IRQ 11
[   63.338924] PCI Interrupt Link [LNKA] enabled at IRQ 10
[   63.420759] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   63.454675] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   63.486343] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   63.491146] lp: driver loaded but no devices found
[   63.492077] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $
[   63.493512] ac.o: No PCI boards found.
[   63.494307] ac.o: For an ISA board you must supply memory and irq parameters.
[   63.496179] Non-volatile memory driver v1.3
[   63.497074] ppdev: user-space parallel port driver
[   63.498353] drm_mm: Testing DRM range manger (struct drm_mm), with random_seed=0xcd061173 max_iterations=8192 max_prime=128
[   63.500502] drm_mm: igt_sanitycheck - ok!
[   63.501390] igt_debug 0x0000000000000000-0x0000000000000200: 512: free
[   63.502801] igt_debug 0x0000000000000200-0x0000000000000600: 1024: used
[   63.503989] igt_debug 0x0000000000000600-0x0000000000000a00: 1024: free
[   63.505147] igt_debug 0x0000000000000a00-0x0000000000000e00: 1024: used
[   63.506395] igt_debug 0x0000000000000e00-0x0000000000001000: 512: free
[   63.507558] igt_debug total: 4096, used 2048 free 2048
[   67.376316] Writes:  Total: 1860480  Max/Min: 0/0   Fail: 0 
[  128.631311] Writes:  Total: 2486217  Max/Min: 0/0   Fail: 0 
[  190.356633] Writes:  Total: 3058631  Max/Min: 0/0   Fail: 0 
[  238.493808] [drm] radeon kernel modesetting enabled.
[  238.499221] [drm] Initialized vgem 1.0.0 20120112 for virtual device on minor 0
[  238.500883] usbcore: registered new interface driver udl
[  238.502873] [drm] Found bochs VGA, ID 0xb0c0.
[  238.504034] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[  238.506401] [TTM] Zone  kernel: Available graphics memory: 229828 kiB
[  238.507769] [TTM] Initializing pool allocator
[  238.508643] [TTM] Initializing DMA pool allocator
[  238.511611] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 1
[  238.518328] parport_pc 00:04: reported by Plug and Play ACPI
[  238.520749] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[  238.620759] lp0: using parport0 (interrupt-driven).
[  238.621720] lp0: console ready
[  238.624654] dummy-irq: no IRQ given.  Use irq=N
[  238.625731] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti
[  238.627246] c2port c2port0: C2 port uc added
[  238.628275] c2port c2port0: uc flash has 30 blocks x 512 bytes (15360 bytes total)
[  238.685351] Guest personality initialized and is inactive
[  238.687266] VMCI host device registered (name=vmci, major=10, minor=58)
[  238.689253] Initialized host personality
[  238.693607] usbcore: registered new interface driver pn533_usb
[  238.695470] usbcore: registered new interface driver nfcmrvl
[  238.697625] mtdoops: mtd device (mtddev=name/number) must be supplied
[  238.700086] L440GX flash mapping: failed to find PIIX4 ISA bridge, cannot continue
[  238.702484] device id = 2440
[  238.703332] device id = 2480
[  238.704199] device id = 24c0
[  238.705126] device id = 24d0
[  238.705990] device id = 25a1
[  238.707025] device id = 2670
[  238.708608] slram: not enough parameters.
[  238.709760] Ramix PMC551 PCI Mezzanine Ram Driver. (C) 1999,2000 Nortel Networks.
[  238.711843] pmc551: not detected
[  238.719377] vxcan: Virtual CAN Tunnel driver
[  238.720777] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[  238.723399] e1000: Copyright (c) 1999-2006 Intel Corporation.
[  239.092202] PCI Interrupt Link [LNKC] enabled at IRQ 11
[  239.516501] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[  239.518079] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[  239.519523] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[  239.520600] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[  239.521716] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[  239.522999] igb: Copyright (c) 2007-2014 Intel Corporation.
[  239.524033] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k
[  239.525717] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[  239.528711] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[  239.530141] ohci-pci: OHCI PCI platform driver
[  239.531246] ohci-platform: OHCI generic platform driver
[  239.532745] uhci_hcd: USB Universal Host Controller Interface driver
[  239.534169] driver u132_hcd
[  239.534912] fotg210_hcd: FOTG210 Host Controller (EHCI) Driver
[  239.536135] Warning! fotg210_hcd should always be loaded before uhci_hcd and ohci_hcd, not after
[  239.537796] usbcore: registered new interface driver usblp
[  239.538866] usbcore: registered new interface driver adutux
[  239.540021] usbcore: registered new interface driver appledisplay
[  239.541502] usbcore: registered new interface driver cypress_cy7c63
[  239.542886] usbcore: registered new interface driver emi62 - firmware loader
[  239.544150] ftdi_elan: driver ftdi-elan
[  239.544866] usbcore: registered new interface driver ftdi-elan
[  239.546231] usbcore: registered new interface driver idmouse
[  239.547340] usbcore: registered new interface driver iowarrior
[  239.548432] usbcore: registered new interface driver usblcd
[  239.549510] usbcore: registered new interface driver legousbtower
[  239.550738] usbcore: registered new interface driver usbtest
[  239.551808] usbcore: registered new interface driver uss720
[  239.552798] uss720: USB Parport Cable driver for Cables using the Lucent Technologies USS720 Chip
[  239.554749] uss720: NOTE: this is a special purpose driver to allow nonstandard
[  239.556066] uss720: protocols (eg. bitbang) over USS720 usb to parallel cables
[  239.557377] uss720: If you just want to connect to a printer, use usblp instead
[  239.558813] usbcore: registered new interface driver yurex
[  239.560257] dummy_hcd dummy_hcd.0: USB Host+Gadget Emulator, driver 02 May 2005
[  239.561585] dummy_hcd dummy_hcd.0: Dummy host controller
[  239.563286] dummy_hcd dummy_hcd.0: new USB bus registered, assigned bus number 1
[  239.565161] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.17
[  239.567468] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[  239.568819] usb usb1: Product: Dummy host controller
[  239.569898] usb usb1: Manufacturer: Linux 4.17.0-rc3-00051-gb6da31b dummy_hcd
[  239.571602] usb usb1: SerialNumber: dummy_hcd.0
[  239.573863] hub 1-0:1.0: USB hub found
[  239.574853] hub 1-0:1.0: 1 port detected
[  239.577657] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[  239.580734] serio: i8042 KBD port at 0x60,0x64 irq 1
[  239.581784] serio: i8042 AUX port at 0x60,0x64 irq 12
[  239.583348] parport0: cannot grant exclusive access for device parkbd
[  239.683693] usb usb1: dummy_bus_suspend
[  239.691176] mousedev: PS/2 mouse device common for all mice
[  239.693996] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[  239.695737] usbcore: registered new interface driver synaptics_usb
[  239.696979] usbcore: registered new interface driver aiptek
[  239.698050] usbcore: registered new interface driver kbtab
[  239.700289] usbcore: registered new interface driver pegasus_notetaker
[  239.702303] usbcore: registered new interface driver usbtouchscreen
[  239.704533] i2c /dev entries driver
[  239.705653] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[  239.766767] usbcore: registered new interface driver i2c-diolan-u2c
[  239.767919] i2c-parport-light: adapter type unspecified
[  239.768887] usbcore: registered new interface driver RobotFuzz Open Source InterFace, OSIF
[  239.774491] usbcore: registered new interface driver ati_remote
[  239.776038] usbcore: registered new interface driver mceusb
[  239.777234] usbcore: registered new interface driver redrat3
[  239.778298] Registered IR keymap rc-empty
[  239.779380] rc rc0: rc-core loopback device as /devices/virtual/rc/rc0
[  239.780672] input: rc-core loopback device as /devices/virtual/rc/rc0/input3
[  239.782365] rc rc0: lirc_dev: driver rc-loopback registered at minor = 0
[  239.783734] usbcore: registered new interface driver igorplugusb
[  239.784845] usbcore: registered new interface driver ttusbir
[  239.786362] serial_ir serial_ir.0: port 03f8 already in use
[  239.787392] serial_ir serial_ir.0: use 'setserial /dev/ttySX uart none'
[  239.788594] serial_ir serial_ir.0: or compile the serial port driver as module and
[  239.789932] serial_ir serial_ir.0: make sure this module is loaded first
[  239.791452] serial_ir: probe of serial_ir.0 failed with error -16
[  239.792813] sir_ir: port existence test failed, cannot continue
[  239.794009] Driver for 1-wire Dallas network protocol.
[  239.795117] usbcore: registered new interface driver DS9490R
[  239.796195] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko
[  240.002146] applesmc: supported laptop not found!
[  240.003035] applesmc: driver init failed (ret=-19)!
[  240.638328] intel_powerclamp: CPU does not support MWAIT
[  240.640251] usbcore: registered new interface driver bcm203x
[  240.649066] usbcore: registered new interface driver bfusb
[  240.650497] usbcore: registered new interface driver btusb
[  240.651798] usbcore: registered new interface driver ath3k
[  240.653213] leds_ss4200: no LED devices found
[  240.657888] OPA Virtual Network Driver - v1.0
[  240.666278] usbcore: registered new interface driver usbhid
[  240.668156] usbhid: USB HID core driver
[  240.670239] NET: Registered protocol family 40
[  240.672285] NET: Registered protocol family 4
[  240.674160] comedi: version 0.7.76 - http://www.comedi.org
[  240.679011] vme_user: VME User Space Access Driver
[  240.680616] vme_user: No cards, skipping registration
[  240.682611] no options.
[  240.683532] FPGA DOWNLOAD --->
[  240.684524] FPGA image file name: xlinx_fpga_firmware.bit
[  240.686819] GPIO INIT FAIL!!
[  240.689055] most_core: init()
[  240.690200] most_core: registered new core component net
[  240.692210] usbcore: registered new interface driver hdm_usb
[  240.694441] usbcore: registered new interface driver es2_ap_driver
[  240.696532] greybus: registered new driver hid
[  240.698007] greybus: registered new driver lights
[  240.699382] greybus: registered new driver power_supply
[  240.701016] greybus: registered new driver raw
[  240.702356] greybus: registered new driver vibrator
[  240.704388] dell_smbios: Unable to run on non-Dell system
[  240.705733] fujitsu_laptop: driver 0.6.0 successfully loaded
[  240.707070] topstar_laptop: ACPI extras driver loaded
[  240.708277] intel_oaktrail: Platform not recognized (You could try the module's force-parameter)
[  240.708528] cros_ec_lpcs: unsupported system.
[  240.714290] Board is not the VME system controller
[  240.715151] VME geographical address is set to 0
[  240.716382] CR/CSR Offset: 0
[  240.717119] AMD(R) PCI-E Non-Transparent Bridge Driver 1.0
[  240.718130] Intel(R) PCI-E Non-Transparent Bridge Driver 2.0
[  240.719258] Software Queue-Pair Transport over NTB, version 4
[  240.725891] Simple TC action Loaded
[  240.727221] netem: version 1.3
[  240.727972] gre: GRE over IPv4 demultiplexor driver
[  240.728807] ip_gre: GRE over IPv4 tunneling driver
[  240.730898] IPv4 over IPsec tunneling driver
[  240.732156] Initializing XFRM netlink socket
[  240.733478] NET: Registered protocol family 10
[  240.737055] Segment Routing with IPv6
[  240.737922] mip6: Mobile IPv6
[  240.738485] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[  240.740106] NET: Registered protocol family 17
[  240.741104] NET: Registered protocol family 15
[  240.741883] can: controller area network core (rev 20170425 abi 9)
[  240.743083] NET: Registered protocol family 29
[  240.743856] can: raw protocol (rev 20170425)
[  240.744647] can: broadcast manager protocol (rev 20170425 t)
[  240.745694] can: netlink gateway (rev 20170425) max_hops=1
[  240.747063] NET: Registered protocol family 41
[  240.748020] lec:lane_module_init: lec.c: initialized
[  240.748904] mpoa:atm_mpoa_init: mpc.c: initialized
[  240.749795] l2tp_core: L2TP core driver, V2.0
[  240.750578] NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team
[  240.752248] DECnet: Routing cache hash table of 512 buckets, 32Kbytes
[  240.753700] NET: Registered protocol family 12
[  240.754665] 8021q: 802.1Q VLAN Support v1.8
[  240.755641] sctp: Hash tables configured (bind 64/64)
[  240.756834] tipc: Activated (version 2.0.0)
[  240.757838] NET: Registered protocol family 30
[  240.759344] tipc: Started in single node mode
[  240.760363] Key type dns_resolver registered
[  240.761362] Key type ceph registered
[  240.762237] libceph: loaded (mon/osd proto 15/24)
[  240.763174] openvswitch: Open vSwitch switching datapath
[  240.764510] mpls_gso: MPLS GSO support
[  240.765516] start plist test
[  240.771326] end plist test
[  240.775097] ... APIC ID:      00000000 (0)
[  240.775847] ... APIC VERSION: 01050014
[  240.776531] 0000000000000000000000000000000000000000000000000000000000000000
[  240.776819] 0000000000000000000000000000000000000000000000000000000000000000
[  240.776819] 0000000000000000000000000000000000000000000000000000000000001000
[  240.780427] number of MP IRQ sources: 15.
[  240.781179] number of IO-APIC #0 registers: 24.
[  240.782053] testing the IO APIC.......................
[  240.783000] IO APIC #0......
[  240.783547] .... register #00: 00000000
[  240.784286] .......    : physical APIC id: 00
[  240.785105] .......    : Delivery Type: 0
[  240.785818] .......    : LTS          : 0
[  240.786589] .... register #01: 00170011
[  240.787275] .......     : max redirection entries: 17
[  240.788181] .......     : PRQ implemented: 0
[  240.788963] .......     : IO APIC version: 11
[  240.789734] .... register #02: 00000000
[  240.790507] .......     : arbitration: 00
[  240.791250] .... IRQ redirection table:
[  240.791951] IOAPIC 0:
[  240.792402]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.793772]  pin01, enabled , edge , high, V(23), IRR(0), S(0), physical, D(00), M(0)
[  240.795217]  pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[  240.796638]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.798107]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.799557]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.801055]  pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.802453]  pin07, enabled , edge , high, V(21), IRR(0), S(0), physical, D(00), M(0)
[  240.803925]  pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.805326]  pin09, enabled , level, high, V(20), IRR(0), S(0), physical, D(00), M(0)
[  240.806809]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.808358]  pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.809822]  pin0c, enabled , edge , high, V(22), IRR(0), S(0), physical, D(00), M(0)
[  240.811235]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.812598]  pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.813982]  pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.815347]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.816729]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.818214]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.819671]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.821059]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.822760]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.824123]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.825570]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[  240.827347] IRQ to pin mappings:
[  240.828425] IRQ0 -> 0:2
[  240.828988] IRQ1 -> 0:1
[  240.829469] IRQ3 -> 0:3
[  240.829964] IRQ4 -> 0:4
[  240.830444] IRQ5 -> 0:5
[  240.830870] IRQ6 -> 0:6
[  240.831408] IRQ7 -> 0:7
[  240.831835] IRQ8 -> 0:8
[  240.832280] IRQ9 -> 0:9
[  240.832713] IRQ10 -> 0:10
[  240.833202] IRQ11 -> 0:11
[  240.833661] IRQ12 -> 0:12
[  240.834140] IRQ13 -> 0:13
[  240.834597] IRQ14 -> 0:14
[  240.835093] IRQ15 -> 0:15
[  240.835552] .................................... done.
[  240.836643] sched_clock: Marking stable (240833196419, 0)->(243160453232, -2327256813)
[  240.839185] registered taskstats version 1
[  240.841547] Key type trusted registered
[  240.842562] Key type encrypted registered
[  240.855088] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[  240.857800] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[  240.862252] 8021q: adding VLAN 0 to HW filter on device eth0
[  240.863709] IP-Config: Failed to open gretap0
[  240.865267] IP-Config: Failed to open erspan0
[  240.866571] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[  240.980230] Sending DHCP requests ., OK
[  240.986301] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[  240.988122] IP-Config: Complete:
[  240.989244]      device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[  240.991173]      host=vm-lkp-hsw-4ep1-yocto-x86_64-6, domain=, nis-domain=(none)
[  240.992546]      bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=     nameserver0=10.0.2.3
[  240.996653] Bluetooth: Starting self testing
[  241.000559] Bluetooth: SMP test passed in 2106 usecs
[  241.002082] Bluetooth: Finished self testing
[  241.021317] Freeing unused kernel memory: 1740K
[  241.023289] Write protecting the kernel read-only data: 24576k
[  241.030314] Freeing unused kernel memory: 2024K
[  241.032523] Freeing unused kernel memory: 120K
[  241.034219] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[  241.054110] process 163 (init) attempted a POSIX timer syscall while CONFIG_POSIX_TIMERS is not set
/etc/rcS.d/S00fbsetup: line 3: /sbin/modprobe: not found

Please wait: booting...
[  241.092901] grep (173) used greatest stack depth: 14072 bytes left
[  241.105291] rc (172) used greatest stack depth: 13920 bytes left
Starting udev
[  241.130408] awk (182) used greatest stack depth: 13624 bytes left
[  241.163924] pidof (188) used greatest stack depth: 13616 bytes left
[  241.172181] udevd[189]: starting version 3.1.5
[  241.174062] random: udevd: uninitialized urandom read (16 bytes read)
[  241.175789] random: udevd: uninitialized urandom read (16 bytes read)
[  241.177298] random: udevd: uninitialized urandom read (16 bytes read)
[  241.997981] udevadm (192) used greatest stack depth: 13160 bytes left
[  242.897381] bootlogd (211) used greatest stack depth: 13144 bytes left
[  242.920393] 
[  242.921189] ======================================================
[  242.923454] WARNING: possible circular locking dependency detected
[  242.925453] 4.17.0-rc3-00051-gb6da31b #2 Tainted: G        W        
[  242.927353] ------------------------------------------------------
[  242.929490] S36udev-cache/214 is trying to acquire lock:
[  242.932560]         (ptrval) (&obj_hash[i].lock){..-.}, at: debug_object_activate+0x7d/0x18a
[  242.936478] 
[  242.936478] but task is already holding lock:
[  242.940058]         (ptrval) (&(&port->lock)->rlock){-...}, at: pty_write+0x39/0x83
[  242.944880] 
[  242.944880] which lock already depends on the new lock.
[  242.944880] 
[  242.949034] 
[  242.949034] the existing dependency chain (in reverse order) is:
[  242.953209] 
[  242.953209] -> #3 (&(&port->lock)->rlock){-...}:
[  242.956888]        tty_port_tty_get+0x1b/0x46
[  242.959260]        tty_port_default_wakeup+0xb/0x26
[  242.961381]        serial8250_tx_chars+0x11c/0x198
[  242.963306]        serial8250_handle_irq+0x9e/0xba
[  242.964824]        serial8250_default_handle_irq+0x24/0x37
[  242.966162]        serial8250_interrupt+0x3c/0xb0
[  242.967275]        __handle_irq_event_percpu+0xf6/0x26b
[  242.968301]        handle_irq_event_percpu+0x1d/0x44
[  242.969348]        handle_irq_event+0x33/0x51
[  242.970287]        handle_edge_irq+0xd8/0xf4
[  242.971074]        handle_irq+0xe7/0xeb
[  242.971771]        do_IRQ+0x42/0xb1
[  242.972525]        ret_from_intr+0x0/0x2e
[  242.973277]        _raw_spin_unlock_irqrestore+0x49/0x57
[  242.974292]        uart_write+0xfe/0x13c
[  242.975073]        n_tty_write+0x21e/0x3ac
[  242.975855]        tty_write+0x1bd/0x256
[  242.976668]        __vfs_write+0x21/0x106
[  242.977469]        vfs_write+0xd9/0x17a
[  242.978268]        ksys_write+0x50/0x8c
[  242.979113]        do_syscall_64+0x91/0x1b8
[  242.980012]        entry_SYSCALL_64_after_hwframe+0x49/0xbe
[  242.981039] 
[  242.981039] -> #2 (&port_lock_key){-.-.}:
[  242.984256]        serial8250_console_write+0x8c/0x1f6
[  242.986546]        console_unlock+0x340/0x51f
[  242.988486]        vprintk_emit+0x37b/0x393
[  242.990387]        printk+0x43/0x4b
[  242.991835]        register_console+0x2f1/0x33d
[  242.993999]        univ8250_console_init+0x24/0x27
[  242.996549]        console_init+0x1a3/0x27b
[  242.997866]        start_kernel+0x358/0x470
[  242.999281]        secondary_startup_64+0xa5/0xb0
[  243.001047] 
[  243.001047] -> #1 (console_owner){..-.}:
[  243.002334]        vprintk_emit+0x37b/0x393
[  243.003140]        printk+0x43/0x4b
[  243.003915]        __debug_object_init+0x419/0x439
[  243.004856]        rhashtable_init+0x1bc/0x201
[  243.005896]        rhltable_init+0xf/0x1b
[  243.006747]        test_insert_dup+0x45/0x62d
[  243.007908]        test_rht_init+0xd2e/0x1042
[  243.008920]        do_one_initcall+0xef/0x247
[  243.010041]        kernel_init_freeable+0x2a1/0x33d
[  243.011122]        kernel_init+0xa/0xe6
[  243.011862]        ret_from_fork+0x24/0x30
[  243.012742] 
[  243.012742] -> #0 (&obj_hash[i].lock){..-.}:
[  243.014124]        _raw_spin_lock_irqsave+0x42/0x51
[  243.015303]        debug_object_activate+0x7d/0x18a
[  243.016676]        __queue_work+0x6b/0x3a8
[  243.017780]        queue_work_on+0x47/0x7e
[  243.019006]        pty_write+0x63/0x83
[  243.020357]        n_tty_write+0x21e/0x3ac
[  243.021508]        tty_write+0x1bd/0x256
[  243.022847]        __vfs_write+0x21/0x106
[  243.024033]        vfs_write+0xd9/0x17a
[  243.025291]        redirected_tty_write+0x86/0x9f
[  243.026655]        __vfs_write+0x21/0x106
[  243.027775]        vfs_write+0xd9/0x17a
[  243.028723]        ksys_write+0x50/0x8c
[  243.030124]        do_syscall_64+0x91/0x1b8
[  243.031513]        entry_SYSCALL_64_after_hwframe+0x49/0xbe
[  243.033444] 
[  243.033444] other info that might help us debug this:
[  243.033444] 
[  243.035153] Chain exists of:
[  243.035153]   &obj_hash[i].lock --> &port_lock_key --> &(&port->lock)->rlock
[  243.035153] 
[  243.037858]  Possible unsafe locking scenario:
[  243.037858] 
[  243.039342]        CPU0                    CPU1
[  243.040510]        ----                    ----
[  243.041687]   lock(&(&port->lock)->rlock);
[  243.042908]                                lock(&port_lock_key);
[  243.044708]                                lock(&(&port->lock)->rlock);
[  243.046106]   lock(&obj_hash[i].lock);
[  243.047113] 
[  243.047113]  *** DEADLOCK ***
[  243.047113] 
[  243.048155] 5 locks held by S36udev-cache/214:
[  243.049104]  #0:         (ptrval) (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x20/0x3a
[  243.050596]  #1:         (ptrval) (&tty->atomic_write_lock){+.+.}, at: tty_write_lock+0x18/0x3f
[  243.052290]  #2:         (ptrval) (&o_tty->termios_rwsem/1){++++}, at: n_tty_write+0x66/0x3ac
[  243.054043]  #3:         (ptrval) (&ldata->output_lock){+.+.}, at: n_tty_write+0x118/0x3ac
[  243.055835]  #4:         (ptrval) (&(&port->lock)->rlock){-...}, at: pty_write+0x39/0x83
[  243.057529] 
[  243.057529] stack backtrace:
[  243.058468] CPU: 0 PID: 214 Comm: S36udev-cache Tainted: G        W         4.17.0-rc3-00051-gb6da31b #2
[  243.060416] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[  243.062414] Call Trace:
[  243.062905]  print_circular_bug+0x26b/0x278
[  243.064004]  __lock_acquire+0xebb/0x153c
[  243.064826]  ? lock_acquire+0x154/0x185
[  243.065838]  lock_acquire+0x154/0x185
[  243.066600]  ? debug_object_activate+0x7d/0x18a
[  243.067537]  _raw_spin_lock_irqsave+0x42/0x51
[  243.068385]  ? debug_object_activate+0x7d/0x18a
[  243.069223]  debug_object_activate+0x7d/0x18a
[  243.070177]  ? __kmalloc+0x1ba/0x1f4
[  243.070896]  ? __queue_work+0x6b/0x3a8
[  243.071608]  __queue_work+0x6b/0x3a8
[  243.072341]  queue_work_on+0x47/0x7e
[  243.073081]  pty_write+0x63/0x83
[  243.073717]  n_tty_write+0x21e/0x3ac
[  243.074360]  ? wait_woken+0x71/0x71
[  243.075256]  tty_write+0x1bd/0x256
[  243.075845]  ? n_tty_ioctl+0xf6/0xf6
[  243.076542]  __vfs_write+0x21/0x106
[  243.077196]  ? lock_acquire+0x154/0x185
[  243.077903]  ? redirected_tty_write+0x56/0x9f
[  243.078751]  vfs_write+0xd9/0x17a
[  243.079371]  redirected_tty_write+0x86/0x9f
[  243.080211]  __vfs_write+0x21/0x106
[  243.080958]  ? __se_sys_wait4+0x3d/0x81
[  243.081853]  ? __se_sys_newstat+0x3c/0x47
[  243.082638]  vfs_write+0xd9/0x17a
[  243.083448]  ksys_write+0x50/0x8c
[  243.085166]  do_syscall_64+0x91/0x1b8
[  243.087484]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
[  243.090885] RIP: 0033:0x7f126b876950
[  243.092749] RSP: 002b:00007fff87a13718 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[  243.095106] RAX: ffffffffffffffda RBX: 0000000000000015 RCX: 00007f126b876950
[  243.097896] RDX: 0000000000000015 RSI: 0000000000684b80 RDI: 0000000000000001
[  243.101101] RBP: 0000000000684b80 R08: 000000000000f8f8 R09: 0000000000000021
[  243.104138] R10: 000000000000086f R11: 0000000000000246 R12: 0000000000000001
[  243.107726] R13: 0000000000000001 R14: 0000000000000000 R15: 0000000000683408
[  243.667530] urandom_read: 4 callbacks suppressed
[  243.667555] random: dd: uninitialized urandom read (512 bytes read)
LKP: HOSTNAME vm-lkp-hsw-4ep1-yocto-x86_64-6, MAC 52:54:00:12:34:56, kernel 4.17.0-rc3-00051-gb6da31b 2, serial console /dev/ttyS0
Poky (Yocto Project Reference Distro) 2.1 vm-lkp-hsw-4ep1-yocto-x86_64-6 /dev/ttyS0
[  244.907116] /lkp/lkp/src/monitors/heartbeat: exec: line 23: vmstat: not found
[  244.930157] Kernel tests: Boot OK!
[  244.930157] /lkp/lkp/src/bin/run-lkp
[  244.930157] RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw-4ep1-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/0
[  244.930157] job=/lkp/scheduled/vm-lkp-hsw-4ep1-yocto-x86_64-6/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-b6da31b2c07c46f2dcad1d86caa835227a16d9ff-20180529-8194-l5uu07-0.yaml
[  244.930157] run-job /lkp/scheduled/vm-lkp-hsw-4ep1-yocto-x86_64-6/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-b6da31b2c07c46f2dcad1d86caa835227a16d9ff-20180529-8194-l5uu07-0.yaml
[  244.930157] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-hsw-4ep1-yocto-x86_64-6/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-b6da31b2c07c46f2dcad1d86caa835227a16d9ff-20180529-8194-l5uu07-0.yaml&job_state=running -O /dev/null
[  244.930157] skip microcode check for virtual machine
[  246.013086] BusyBox v1.24.1 (2016-04-15 14:06:13 GMT) multi-call binary.
[  246.013086] 
[  246.013086] Usage: time [-v] PROG ARGS
[  251.929681] Writes:  Total: 7694369  Max/Min: 0/0   Fail: 0 
[  258.984445] sctp: [Deprecated]: trinity-main (pid 561) Use of int in max_burst socket option deprecated.
[  258.984445] Use struct sctp_assoc_value instead
[  259.024412] trinity-main uses obsolete (PF_INET,SOCK_PACKET)
[  259.036361] sock: process `trinity-main' is using obsolete setsockopt SO_BSDCOMPAT
[  261.513173] random: fast init done
[  313.646934] Writes:  Total: 9541776  Max/Min: 0/0   Fail: 0 
[  375.066535] Writes:  Total: 9935171  Max/Min: 0/0   Fail: 0 
[  436.585117] Writes:  Total: 10195934  Max/Min: 0/0   Fail: 0 
[  497.958352] Writes:  Total: 10458417  Max/Min: 0/0   Fail: 0 
[  552.547774] sysrq: SysRq : Emergency Sync
[  552.552429] Emergency Sync complete
[  552.566467] sysrq: SysRq : Resetting

Elapsed time: 510

#!/bin/bash

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

kernel=$1

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

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

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

qemu-img create -f qcow2 disk-vm-lkp-hsw-4ep1-yocto-x86_64-6-0 256G
qemu-img create -f qcow2 disk-vm-lkp-hsw-4ep1-yocto-x86_64-6-1 256G

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-cpu Nehalem
	-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-hsw-4ep1-yocto-x86_64-6-0,media=disk,if=virtio
	-drive file=disk-vm-lkp-hsw-4ep1-yocto-x86_64-6-1,media=disk,if=virtio
	-serial stdio
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-hsw-4ep1-yocto-x86_64-6::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-randconfig-b0-05281952
	branch=linux-devel/devel-hourly-2018052817
	commit=b6da31b2c07c46f2dcad1d86caa835227a16d9ff
	BOOT_IMAGE=/pkg/linux/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/vmlinuz-4.17.0-rc3-00051-gb6da31b
	max_uptime=1500
	RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw-4ep1-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-b0-05281952/gcc-7/b6da31b2c07c46f2dcad1d86caa835227a16d9ff/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[*]}"
