early console in setup code
Probing EDD (edd=off to disable)... ok
early console in extract_kernel
input_data: 0x01ff3080
input_len: 0x006101a6
output: 0x01000000
output_len: 0x00c5e094
kernel_total_size: 0x01a07000

Decompressing Linux... Parsing ELF... No relocation needed... done.
Booting the kernel.
[    0.000000] Linux version 4.19.0-rc5-00321-g0e9446c (kbuild@lkp-hsx03) (gcc version 6.4.0 20171026 (Debian 6.4.0-9)) #1082 Sun Nov 4 09:07:43 CST 2018
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[    0.000000] 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] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000001] kvm-clock: cpu 0, msr 1bb8001, primary cpu clock
[    0.000001] kvm-clock: using sched offset of 907970027 cycles
[    0.000615] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.002363] tsc: Detected 2693.508 MHz processor
[    0.004548] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.005343] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.005958] last_pfn = 0x1ffdc max_arch_pfn = 0x1000000
[    0.006529] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC  
[    0.007320] Scanning 1 areas for low memory corruption
[    0.007883] initial memory mapped: [mem 0x00000000-0x02ffffff]
[    0.008511] Base memory trampoline at [(ptrval)] 9b000 size 16384
[    0.009234] BRK [0x02605000, 0x02605fff] PGTABLE
[    0.009755] BRK [0x02606000, 0x02606fff] PGTABLE
[    0.010257] RAMDISK: [mem 0x1fbee000-0x1ffcffff]
[    0.010766] ACPI: Early table checksum verification disabled
[    0.011432] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.012058] ACPI: RSDT 0x000000001FFE1628 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.012978] ACPI: FACP 0x000000001FFE147C 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.013908] ACPI: DSDT 0x000000001FFE0040 00143C (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.014875] ACPI: FACS 0x000000001FFE0000 000040
[    0.015372] ACPI: APIC 0x000000001FFE1570 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.016300] ACPI: HPET 0x000000001FFE15F0 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.017230] 511MB LOWMEM available.
[    0.017610]   mapped low ram: 0 - 1ffdc000
[    0.018052]   low ram: 0 - 1ffdc000
[    0.021012] Zone ranges:
[    0.021307]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.021972]   Normal   [mem 0x0000000001000000-0x000000001ffdbfff]
[    0.022641] Movable zone start for each node
[    0.023097] Early memory node ranges
[    0.023477]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.024153]   node   0: [mem 0x0000000000100000-0x000000001ffdbfff]
[    0.024910] Reserved but unavailable: 134 pages
[    0.024913] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffdbfff]
[    0.026149] On node 0 totalpages: 130938
[    0.026584]   DMA zone: 32 pages used for memmap
[    0.027092]   DMA zone: 0 pages reserved
[    0.027510]   DMA zone: 3998 pages, LIFO batch:0
[    0.028080]   Normal zone: 992 pages used for memmap
[    0.028623]   Normal zone: 126940 pages, LIFO batch:31
[    0.031598] ACPI: PM-Timer IO Port: 0x608
[    0.032031] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.032612] KVM setup async PF for cpu 0
[    0.033042] kvm-stealtime: cpu 0, msr 1a68500
[    0.033539] [mem 0x20000000-0xfeffbfff] available for PCI devices
[    0.034218] Booting paravirtualized kernel on KVM
[    0.034776] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.035962] random: get_random_bytes called from start_kernel+0x6b/0x3d4 with crng_init=0
[    0.036887] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.037485] pcpu-alloc: [0] 0 
[    0.037839] Built 1 zonelists, mobility grouping on.  Total pages: 129914
[    0.038602] Kernel command line: ip=::::vm-ivb41-yocto-i386-3::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-ivb41-yocto-i386-3/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-0e9446c35a80931044b6d8d2d74a9cabd248539f-20181104-48025-1ot4ggz-3.yaml ARCH=i386 kconfig=i386-randconfig-s1-201844 branch=linux-devel/devel-spot-201811040216 commit=0e9446c35a80931044b6d8d2d74a9cabd248539f BOOT_IMAGE=/pkg/linux/i386-randconfig-s1-201844/gcc-6/0e9446c35a80931044b6d8d2d74a9cabd248539f/vmlinuz-4.19.0-rc5-00321-g0e9446c max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-s1-201844/gcc-6/0e9446c35a80931044b6d8d2d74a9cabd248539f/3 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 cons
[    0.050718] sysrq: sysrq always enabled.
[    0.052054] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.053229] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.054363] Initializing CPU#0
[    0.060526] Memory: 492608K/523752K available (6989K kernel code, 817K rwdata, 3508K rodata, 524K init, 10516K bss, 31144K reserved, 0K cma-reserved)
[    0.062752] virtual kernel memory layout:
[    0.062752]     fixmap  : 0xfffe4000 - 0xfffff000   ( 108 kB)
[    0.062752]   cpu_entry : 0xffa00000 - 0xffa28000   ( 160 kB)
[    0.062752]     vmalloc : 0xa07dc000 - 0xff7fe000   (1520 MB)
[    0.062752]     lowmem  : 0x80000000 - 0x9ffdc000   ( 511 MB)
[    0.062752]       .init : 0x81b25000 - 0x81ba8000   ( 524 kB)
[    0.062752]       .data : 0x816d350e - 0x81b10600   (4340 kB)
[    0.062752]       .text : 0x81000000 - 0x816d350e   (6989 kB)
[    0.068368] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.069284] Kernel/User page tables isolation: enabled
[    0.070436] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.071241] CPU 0 irqstacks, hard=(ptrval) soft=(ptrval)
[    0.072026] console [ttyS0] enabled
[    0.072026] console [ttyS0] enabled
[    0.072864] bootconsole [earlyser0] disabled
[    0.072864] bootconsole [earlyser0] disabled
[    0.073853] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.074763] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.075235] ... MAX_LOCK_DEPTH:          48
[    0.075721] ... MAX_LOCKDEP_KEYS:        8191
[    0.076218] ... CLASSHASH_SIZE:          4096
[    0.076720] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.077217] ... MAX_LOCKDEP_CHAINS:      65536
[    0.077725] ... CHAINHASH_SIZE:          32768
[    0.078225]  memory used by lock dependency info: 4383 kB
[    0.078841]  per task-struct memory footprint: 1344 bytes
[    0.079467] ACPI: Core revision 20180810
[    0.080003] ACPI: setting ELCR to 0200 (from 0c00)
[    0.080732] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.081828] hpet clockevent registered
[    0.082348] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns
[    0.083526] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=26935080)
[    0.084478] pid_max: default: 32768 minimum: 301
[    0.085059] Security Framework initialized
[    0.085528] Yama: becoming mindful.
[    0.085953] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.086717] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.087804] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.088413] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.089091] CPU: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1)
[    0.090051] Spectre V2 : Vulnerable: Minimal generic ASM retpoline
[    0.090759] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.091697] Speculative Store Bypass: Vulnerable
[    0.093896] random: get_random_u32 called from copy_process+0x1e8/0x1454 with crng_init=0
[    0.095027] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    0.096350] devtmpfs: initialized
[    0.097654] random: get_random_u32 called from bucket_table_alloc+0xe2/0x113 with crng_init=0
[    0.099480] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.100629] futex hash table entries: 256 (order: 1, 11264 bytes)
[    0.101413] prandom: seed boundary self test passed
[    0.103966] prandom: 100 self tests passed
[    0.104448] pinctrl core: initialized pinctrl subsystem
[    0.105516] NET: Registered protocol family 16
[    0.107147] cpuidle: using governor menu
[    0.108361] ACPI: bus type PCI registered
[    0.114514] PCI: PCI BIOS area is rw and x. Use pci=nobios if you want it NX.
[    0.115360] PCI: PCI BIOS revision 2.10 entry at 0xfd501, last bus=0
[    0.116080] PCI: Using configuration type 1 for base access
[    0.124889] cryptd: max_cpu_qlen set to 1000
[    0.125469] gpio-f7188x: Not a Fintek device at 0x0000002e
[    0.126124] gpio-f7188x: Not a Fintek device at 0x0000004e
[    0.126904] ACPI: Added _OSI(Module Device)
[    0.127401] ACPI: Added _OSI(Processor Device)
[    0.127907] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.128453] ACPI: Added _OSI(Processor Aggregator Device)
[    0.129068] ACPI: Added _OSI(Linux-Dell-Video)
[    0.129581] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.132731] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.136914] ACPI: Interpreter enabled
[    0.137393] ACPI: (supports S0 S3 S5)
[    0.137826] ACPI: Using PIC for interrupt routing
[    0.138396] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.139653] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.147643] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.148386] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    0.149023] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.149792] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.151192] PCI host bridge to bus 0000:00
[    0.151679] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.152448] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.153216] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.153536] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window]
[    0.154455] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.155132] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.156504] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.157897] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.164202] pci 0000:00:01.1: reg 0x20: [io  0xc0c0-0xc0cf]
[    0.167505] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.168320] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.170295] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.171107] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.172229] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.173926] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.174751] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.175971] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.179036] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.186448] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.199448] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.201194] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.206189] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.209850] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.225439] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.228685] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    0.233525] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    0.237453] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    0.253525] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    0.257083] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[    0.260222] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
[    0.265782] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[    0.273525] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[    0.276831] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000
[    0.278365] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f]
[    0.290612] pci_bus 0000:00: on NUMA node 0
[    0.292615] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.293784] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.294692] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.295592] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.296350] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.297744] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    0.298756] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.299772] pci 0000:00:02.0: vgaarb: bridge control possible
[    0.300488] vgaarb: loaded
[    0.301051] ACPI: bus type USB registered
[    0.301599] usbcore: registered new interface driver usbfs
[    0.302267] usbcore: registered new interface driver hub
[    0.303572] usbcore: registered new device driver usb
[    0.304650] pps_core: LinuxPPS API ver. 1 registered
[    0.305525] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.307008] PTP clock support registered
[    0.307745] PCI: Using ACPI for IRQ routing
[    0.308263] PCI: pci_cache_line_size set to 64 bytes
[    0.309017] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.309722] e820: reserve RAM buffer [mem 0x1ffdc000-0x1fffffff]
[    0.310641] Bluetooth: Core ver 2.22
[    0.311138] NET: Registered protocol family 31
[    0.311662] Bluetooth: HCI device and connection manager initialized
[    0.312465] Bluetooth: HCI socket layer initialized
[    0.313064] Bluetooth: L2CAP socket layer initialized
[    0.313591] NET: Registered protocol family 8
[    0.314123] NET: Registered protocol family 20
[    0.314727] workqueue: round-robin CPU selection forced, expect performance impact
[    0.315763] nfc: nfc_init: NFC Core ver 0.1
[    0.316573] NET: Registered protocol family 39
[    0.318487] clocksource: Switched to clocksource kvm-clock
[    0.319632] FS-Cache: Loaded
[    0.320017] pnp: PnP ACPI init
[    0.320513] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.321379] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.322301] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.323084] pnp 00:03: [dma 2]
[    0.323525] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.323525] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.323525] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.323525] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.323525] pnp: PnP ACPI: found 7 devices
[    0.358638] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.359819] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.360545] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.361251] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.362031] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window]
[    0.362893] NET: Registered protocol family 2
[    0.363819] tcp_listen_portaddr_hash hash table entries: 256 (order: 1, 10240 bytes)
[    0.365541] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.366774] TCP bind hash table entries: 4096 (order: 5, 147456 bytes)
[    0.368111] TCP: Hash tables configured (established 4096 bind 4096)
[    0.369259] UDP hash table entries: 256 (order: 2, 20480 bytes)
[    0.370319] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes)
[    0.371257] NET: Registered protocol family 1
[    0.371794] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.372479] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.373176] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.374021] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.375066] PCI: CLS 0 bytes, default 64
[    0.375654] Unpacking initramfs...
[    0.727159] Freeing initrd memory: 3976K
[    0.728413] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns
[    0.730040] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[    0.730780] apm: overridden by ACPI.
[    0.731205] Scanning for low memory corruption every 60 seconds
[    0.732302] The force parameter has not been set to 1. The Iris poweroff handler will not be installed.
[    0.733393] NatSemi SCx200 Driver
[    0.733941] 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
[    0.735934] spin_lock-torture: Creating torture_shuffle task
[    0.736645] spin_lock-torture: Creating torture_stutter task
[    0.737328] spin_lock-torture: torture_shuffle task started
[    0.737993] spin_lock-torture: Creating lock_torture_writer task
[    0.738711] spin_lock-torture: torture_stutter task started
[    0.739374] spin_lock-torture: Creating lock_torture_writer task
[    0.740090] spin_lock-torture: lock_torture_writer task started
[    0.740805] spin_lock-torture: Creating lock_torture_stats task
[    0.741513] spin_lock-torture: lock_torture_writer task started
[    0.742269] spin_lock-torture: lock_torture_stats task started
[    1.324147] _warn_unseeded_randomness: 9 callbacks suppressed
[    1.324153] random: get_random_u32 called from get_random_order+0x2a/0x4e with crng_init=0
[    1.603645] random: fast init done
[    5.449055] random: get_random_u32 called from copy_process+0x1e8/0x1454 with crng_init=1
[    5.450745] random: get_random_u32 called from stress_one_work+0x13/0x7d with crng_init=1
[    7.461549] torture_init_begin: Refusing rcu init: spin_lock running.
[    7.462419] torture_init_begin: One torture test at a time!
[    7.463064] torture_init_begin: Refusing rcu init: spin_lock running.
[    7.463909] torture_init_begin: One torture test at a time!
[    7.465372] random: get_random_u32 called from copy_process+0x1e8/0x1454 with crng_init=1
[    7.474148] workingset: timestamp_bits=31 max_order=17 bucket_order=0
[    7.475052] zbud: loaded
[    7.475795] fuse init (API version 7.27)
[    7.476671] random: get_random_u32 called from bucket_table_alloc+0xe2/0x113 with crng_init=1
[    7.478109] random: get_random_u32 called from copy_process+0x1e8/0x1454 with crng_init=1
[    7.486202] NET: Registered protocol family 38
[    7.487062] Key type asymmetric registered
[    7.487563] Asymmetric key parser 'x509' registered
[    7.744066] String selftests succeeded
[    7.744766] 
[    7.744766] Start testing find_bit() with random-filled bitmap
[    7.748054] find_next_bit:                 1450995 ns, 164445 iterations
[    7.751113] find_next_zero_bit:            1733179 ns, 163236 iterations
[    7.753516] find_last_bit:                 1084229 ns, 164446 iterations
[    7.764348] find_first_bit:                9498230 ns,  16627 iterations
[    7.766140] find_next_and_bit:		2318922 cycles, 74228 iterations
[    7.767011] 
[    7.767011] Start testing find_bit() with sparse bitmap
[    7.768279] find_next_bit:                   36545 ns,    655 iterations
[    7.772935] find_next_zero_bit:            3467927 ns, 327026 iterations
[    7.774402] find_last_bit:                   28855 ns,    655 iterations
[    7.779353] find_first_bit:                3754803 ns,    655 iterations
[    7.780666] find_next_and_bit:		54696 cycles, 1 iterations
[    7.781915] test_firmware: interface ready
[    7.782700] INFO: trying to register non-static key.
[    7.783426] the code is fine but needs lockdep annotation.
[    7.784282] turning off the locking correctness validator.
[    7.784300] CPU: 0 PID: 1 Comm: swapper Not tainted 4.19.0-rc5-00321-g0e9446c #1082
[    7.784300] Call Trace:
[    7.784300]  dump_stack+0x16/0x18
[    7.784300]  register_lock_class+0xb0/0x2c9
[    7.784300]  ? check_chain_key+0x86/0xe1
[    7.784300]  __lock_acquire+0x83/0x535
[    7.784300]  lock_acquire+0x55/0x71
[    7.784300]  ? ida_alloc_range+0x6e/0x271
[    7.784300]  _raw_spin_lock_irqsave+0x2e/0x3e
[    7.784300]  ? ida_alloc_range+0x6e/0x271
[    7.784300]  ? ida_check_destroy_1+0x6e/0x6e
[    7.784300]  ida_alloc_range+0x6e/0x271
[    7.784300]  ? ida_check_destroy_1+0x6e/0x6e
[    7.784300]  ida_alloc+0x12/0x14
[    7.784300]  ida_checks+0x64/0x409
[    7.784300]  ? ida_check_destroy_1+0x6e/0x6e
[    7.784300]  do_one_initcall+0x68/0x13c
[    7.784300]  ? parse_args+0xb3/0x1e4
[    7.784300]  kernel_init_freeable+0xea/0x174
[    7.784300]  ? rest_init+0xbb/0xbb
[    7.784300]  kernel_init+0x8/0xd5
[    7.784300]  ret_from_fork+0x2e/0x38
[    7.836203] IDA: 147321 of 147321 tests passed
[    7.844144] Running rhashtable test nelem=8, max_size=0, shrinking=0
[    7.844873] Test 00:
[    7.845204]   Adding 50000 keys
[    7.867003]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    7.879473]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    7.880945]   Deleting 50000 keys
[    7.894870]   Duration of test: 49291795 ns
[    7.895561] Test 01:
[    7.895935]   Adding 50000 keys
[    7.921117]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    7.930886]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    7.931857]   Deleting 50000 keys
[    7.945511]   Duration of test: 49131953 ns
[    7.946111] Test 02:
[    7.946459]   Adding 50000 keys
[    7.981622]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    7.994316]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    7.995612]   Deleting 50000 keys
[    8.010064]   Duration of test: 63114566 ns
[    8.010902] Test 03:
[    8.011439]   Adding 50000 keys
[    8.039327]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    8.049556]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    8.051717]   Deleting 50000 keys
[    8.066370]   Duration of test: 54316782 ns
[    8.072054] test if its possible to exceed max_size 8192: no, ok
[    8.072824] Average test time: 53963774
[    8.073359] test inserting duplicates
[    8.074002] 
[    8.074002] ---- ht: ----
[    8.074002] bucket[1] -> [[ val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]]
[    8.074002] -------------
[    8.075543] 
[    8.075543] ---- ht: ----
[    8.075543] bucket[1] -> [[ val 1 (tid=2),  val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]]
[    8.075543] -------------
[    8.077243] 
[    8.077243] ---- ht: ----
[    8.077243] bucket[1] -> [[ val 21 (tid=1) ]] -> [[ val 1 (tid=0) ]]
[    8.077243] -------------
[    8.078745] 
[    8.078745] ---- ht: ----
[    8.078745] bucket[1] -> [[ val 21 (tid=1) ]] -> [[ val 1 (tid=2),  val 1 (tid=0) ]]
[    8.078745] -------------
[    8.080424] Testing concurrent rhashtable access from 10 threads
[    9.293946] test 3125 add/delete pairs into rhlist
[    9.317554] test 3125 random rhlist add/delete operations
[    9.344294] Started 10 threads, 0 failed, rhltable test returns 0
[    9.345095] test passed
[    9.345428] test_printf: crng possibly not yet initialized. plain 'p' buffer contains "(ptrval)"
[    9.345526] test_printf: all 240 tests passed
[    9.347158] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 915
[    9.349541] test_bitmap: all 1524 tests passed
[    9.350059] test_uuid: all 18 tests passed
[   11.880031] WARNING: CPU: 0 PID: 1 at include/linux/xarray.h:54 xa_mk_value+0x7/0x10
[   11.881093] CPU: 0 PID: 1 Comm: swapper Not tainted 4.19.0-rc5-00321-g0e9446c #1082
[   11.881944] EIP: xa_mk_value+0x7/0x10
[   11.882374] Code: 04 96 5b 5e 5d c3 55 89 e5 e8 8a 5b ff ff 5d c3 55 89 e5 5d c3 55 31 c9 89 e5 6a 00 e8 59 46 53 00 c9 c3 55 85 c0 89 e5 79 02 <0f> 0b 01 c0 83 c8 01 5d c3 55 89 e5 53 89 c3 89 d0 25 ff ff ff 7f
[   11.883565] EAX: ffffffff EBX: ffffffff ECX: 00000000 EDX: ffffffff
[   11.883565] ESI: ffffffff EDI: 00000080 EBP: 9ec2bf04 ESP: 9ec2bf04
[   11.883565] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068 EFLAGS: 00210286
[   11.883565] CR0: 80050033 CR2: 00000000 CR3: 01bac000 CR4: 000406b0
[   11.883565] Call Trace:
[   11.883565]  __check_store_range+0xe/0x171
[   11.883565]  check_store_range+0x5d/0x87
[   11.883565]  ? check_reserve+0x321/0x321
[   11.883565]  xarray_checks+0x58/0xab
[   11.883565]  do_one_initcall+0x68/0x13c
[   11.883565]  ? parse_args+0xb3/0x1e4
[   11.883565]  kernel_init_freeable+0xea/0x174
[   11.883565]  ? rest_init+0xbb/0xbb
[   11.883565]  kernel_init+0x8/0xd5
[   11.883565]  ret_from_fork+0x2e/0x38
[   11.883565] _warn_unseeded_randomness: 5 callbacks suppressed
[   11.883565] random: get_random_bytes called from init_oops_id+0x23/0x3f with crng_init=1
[   11.883565] ---[ end trace 4caf99c850e11324 ]---
[   60.713665] BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 44s!
[   60.714627] Showing busy workqueues and worker pools:
[   60.715208] workqueue events_power_efficient: flags=0x80
[   60.715813]   pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256
[   60.716503]     pending: neigh_periodic_work

Elapsed time: 70

#!/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-tiny-i386-2016-04-22.cgz
	/lkp/lkp/lkp-i386.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz
	/osimage/pkg/debian-x86_64-2016-08-31.cgz/trinity-static-i386-x86_64-6ddabfd2_2017-11-10.cgz
)

HTTP_PREFIX=https://download.01.org/0day-ci/lkp-qemu
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-ivb41-yocto-i386-3-0 256G
qemu-img create -f qcow2 disk-vm-ivb41-yocto-i386-3-1 256G

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

append=(
	ip=::::vm-ivb41-yocto-i386-3::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=i386
	kconfig=i386-randconfig-s1-201844
	branch=linux-devel/devel-spot-201811040216
	commit=0e9446c35a80931044b6d8d2d74a9cabd248539f
	BOOT_IMAGE=/pkg/linux/i386-randconfig-s1-201844/gcc-6/0e9446c35a80931044b6d8d2d74a9cabd248539f/vmlinuz-4.19.0-rc5-00321-g0e9446c
	max_uptime=1500
	RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-s1-201844/gcc-6/0e9446c35a80931044b6d8d2d74a9cabd248539f/3
	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
	--no-log
)

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