early console in setup code early console in extract_kernel input_data: 0x02916069 input_len: 0x006873bc output: 0x01000000 output_len: 0x011b23ec kernel_total_size: 0x01fb4000 Decompressing Linux... Parsing ELF... done. Booting the kernel. [ 0.000000] Linux version 4.17.0-rc1 (kbuild@cairo) (gcc version 7.3.0 (Debian 7.3.0-16)) #9 Wed Apr 18 09:53:19 CST 2018 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 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-0x000000001a3dffff] usable [ 0.000000] BIOS-e820: [mem 0x000000001a3e0000-0x000000001a3fffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel! [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x1a3e0 max_arch_pfn = 0x100000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.000000] initial memory mapped: [mem 0x00000000-0x033fffff] [ 0.000000] Base memory trampoline at [(ptrval)] 9b000 size 16384 [ 0.000000] BRK [0x02cab000, 0x02cabfff] PGTABLE [ 0.000000] BRK [0x02cac000, 0x02cacfff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1a121000-0x1a3dffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68D0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000001A3E15CE 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000001A3E142A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000001A3E0040 0013EA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000001A3E0000 000040 [ 0.000000] ACPI: APIC 0x000000001A3E151E 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000001A3E1596 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] 419MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 1a3e0000 [ 0.000000] low ram: 0 - 1a3e0000 [ 0.000000] tsc: Fast TSC calibration failed [ 0.000000] tsc: Unable to calibrate against PIT [ 0.000000] tsc: No reference (HPET/PMTIMER) available [ 0.000000] Zone ranges: [ 0.000000] Normal [mem 0x0000000000001000-0x000000001a3dffff] [ 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-0x000000001a3dffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001a3dffff] [ 0.000000] On node 0 totalpages: 107390 [ 0.000000] Normal zone: 840 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 107390 pages, LIFO batch:31 [ 0.000000] Reserved but unavailable: 98 pages [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] e820: [mem 0x1a400000-0xfffbffff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on bare hardware [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns [ 0.000000] random: fast init done [ 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: 106550 [ 0.000000] Kernel command line: ip=::::vm-lkp-os-yocto-ia32-11::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-yocto-ia32-11/boot-1-yocto-tiny-i386-2016-04-22.cgz-60cc43fc888428bb2f18f08997432d426a243338-20180418-70649-1kp03is-0.yaml ARCH=i386 kconfig=i386-randconfig-c0-04180923 branch=linus/master commit=60cc43fc888428bb2f18f08997432d426a243338 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-04180923/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/vmlinuz-4.17.0-rc1 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-os-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-04180923/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/1 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw drbd.minor_cou [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Initializing CPU#0 [ 0.000000] Memory: 392924K/429560K available (10468K kernel code, 1352K rwdata, 5392K rodata, 780K init, 11188K bss, 36636K reserved, 0K cma-reserved) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfffe4000 - 0xfffff000 ( 108 kB) [ 0.000000] cpu_entry : 0xffc00000 - 0xffc28000 ( 160 kB) [ 0.000000] vmalloc : 0x5abe0000 - 0xffbfe000 (2640 MB) [ 0.000000] lowmem : 0x40000000 - 0x5a3e0000 ( 419 MB) [ 0.000000] .init : 0x420ee000 - 0x421b1000 ( 780 kB) [ 0.000000] .data : 0x41a39108 - 0x420d3100 (6759 kB) [ 0.000000] .text : 0x41000000 - 0x41a39108 (10468 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] CPU 0 irqstacks, hard=(ptrval) soft=(ptrval) [ 0.000000] console [ttyS0] enabled [ 0.000000] bootconsole [earlyser0] disabled [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.000000] ... CHAINHASH_SIZE: 32768 [ 0.000000] memory used by lock dependency info: 4383 kB [ 0.000000] per task-struct memory footprint: 1344 bytes [ 0.000000] allocated 458752 bytes of page_ext [ 0.000000] ODEBUG: selftest passed [ 0.000000] ACPI: Core revision 20180313 [ 0.000000] ACPI: setting ELCR to 0200 (from 0c00) [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.000000] hpet clockevent registered [ 0.013333] tsc: Fast TSC calibration failed [ 0.029999] tsc: Unable to calibrate against PIT [ 0.029999] tsc: using HPET reference calibration [ 0.033333] tsc: Detected 3200.061 MHz processor [ 0.064201] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2e2083e049c, max_idle_ns: 440795338296 ns [ 0.074085] Calibrating delay loop (skipped), value calculated using timer frequency.. 6402.79 BogoMIPS (lpj=10666870) [ 0.080842] pid_max: default: 4096 minimum: 301 [ 0.108819] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.120535] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.243786] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.244982] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.246053] CPU: AMD QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 0.250470] Spectre V2 : Spectre mitigation: kernel not compiled with retpoline; no mitigation available! [ 0.390977] Performance Events: PMU not available due to virtualization, using software events only. [ 0.562995] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.564625] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.635770] devtmpfs: initialized [ 0.801223] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.810518] futex hash table entries: 16 (order: -3, 704 bytes) [ 0.837643] xor: measuring software checksum speed [ 0.873757] pIII_sse : 253.200 MB/sec [ 0.910194] prefetch64-sse: 313.200 MB/sec [ 0.911267] xor: using function: prefetch64-sse (313.200 MB/sec) [ 1.015262] NET: Registered protocol family 16 [ 1.153985] EISA bus registered [ 1.160585] cpuidle: using governor ladder [ 1.169690] cpuidle: using governor menu [ 1.262253] ACPI: bus type PCI registered [ 1.291352] PCI: PCI BIOS revision 2.10 entry at 0xfd501, last bus=0 [ 1.293793] PCI: Using configuration type 1 for base access [ 2.649999] cryptd: max_cpu_qlen set to 1000 [ 2.771133] raid6: mmxx1 gen() 28 MB/s [ 2.827098] raid6: mmxx2 gen() 52 MB/s [ 2.883827] raid6: sse1x1 gen() 45 MB/s [ 2.941162] raid6: sse1x2 gen() 52 MB/s [ 2.997347] raid6: sse2x1 gen() 37 MB/s [ 3.063639] raid6: sse2x1 xor() 19 MB/s [ 3.124110] raid6: sse2x2 gen() 39 MB/s [ 3.180290] raid6: sse2x2 xor() 45 MB/s [ 3.237334] raid6: int32x1 gen() 56 MB/s [ 3.294214] raid6: int32x1 xor() 35 MB/s [ 3.351137] raid6: int32x2 gen() 39 MB/s [ 3.407688] raid6: int32x2 xor() 29 MB/s [ 3.463832] raid6: int32x4 gen() 17 MB/s [ 3.524218] raid6: int32x4 xor() 5 MB/s [ 3.583591] raid6: int32x8 gen() 8 MB/s [ 3.642011] raid6: int32x8 xor() 5 MB/s [ 3.643677] raid6: using algorithm int32x1 gen() 56 MB/s [ 3.644789] raid6: .... xor() 35 MB/s, rmw enabled [ 3.647749] raid6: using intx1 recovery algorithm [ 3.671563] ACPI: Added _OSI(Module Device) [ 3.672788] ACPI: Added _OSI(Processor Device) [ 3.673643] ACPI: Added _OSI(3.0 _SCP Extensions) [ 3.674437] ACPI: Added _OSI(Processor Aggregator Device) [ 3.677074] ACPI: Added _OSI(Linux-Dell-Video) [ 3.924367] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 4.232212] ACPI: Interpreter enabled [ 4.239165] ACPI: (supports S0 S3 S5) [ 4.240720] ACPI: Using PIC for interrupt routing [ 4.245623] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 4.273333] ACPI: Enabled 2 GPEs in block 00 to 0F [ 5.759999] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 5.768786] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 5.775285] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 5.784849] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 5.806909] PCI host bridge to bus 0000:00 [ 5.808432] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 5.810459] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 5.811626] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 5.812892] pci_bus 0000:00: root bus resource [mem 0x1a400000-0xfebfffff window] [ 5.815073] pci_bus 0000:00: root bus resource [bus 00-ff] [ 5.821468] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 5.864433] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 5.888231] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 5.910966] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 5.920689] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 5.922238] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 5.923769] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 5.925259] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 5.948563] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 5.954578] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 5.955940] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 5.980761] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 5.990752] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 6.017117] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 6.057260] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 6.084034] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 6.090523] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 6.100487] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 6.137190] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 6.161625] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 6.167059] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 6.247515] pci_bus 0000:00: on NUMA node 0 [ 6.394176] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 6.412636] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 6.430451] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 6.447148] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 6.457375] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 6.563333] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 6.563333] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 6.567516] pci 0000:00:02.0: vgaarb: bridge control possible [ 6.570905] vgaarb: loaded [ 6.711865] SCSI subsystem initialized [ 6.718655] ACPI: bus type USB registered [ 6.734359] usbcore: registered new interface driver usbfs [ 6.745594] usbcore: registered new interface driver hub [ 6.750541] usbcore: registered new device driver usb [ 6.767970] Linux video capture interface: v2.00 [ 6.777363] pps_core: LinuxPPS API ver. 1 registered [ 6.778449] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 6.781150] PTP clock support registered [ 6.784634] EDAC MC: Ver: 3.0.0 [ 6.825750] PCI: Using ACPI for IRQ routing [ 6.827960] PCI: pci_cache_line_size set to 64 bytes [ 6.832751] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 6.834798] e820: reserve RAM buffer [mem 0x1a3e0000-0x1bffffff] [ 6.972924] clocksource: Switched to clocksource tsc-early [ 6.974147] CE: hpet increased min_delta_ns to 5000 nsec [ 6.974147] CE: hpet increased min_delta_ns to 7500 nsec [ 6.974147] CE: hpet increased min_delta_ns to 11250 nsec [ 6.974147] CE: hpet increased min_delta_ns to 16875 nsec [ 6.974147] CE: hpet increased min_delta_ns to 25312 nsec [ 6.974147] CE: hpet increased min_delta_ns to 37968 nsec [ 6.974147] CE: hpet increased min_delta_ns to 56952 nsec [ 6.974147] CE: hpet increased min_delta_ns to 85428 nsec [ 7.058971] VFS: Disk quotas dquot_6.6.0 [ 7.066334] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 7.112937] pnp: PnP ACPI init [ 7.139309] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 7.152359] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 7.168546] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 7.173024] pnp 00:03: [dma 2] [ 7.180030] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 7.201439] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 7.213215] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 7.226518] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 7.274308] pnp: PnP ACPI: found 7 devices [ 7.275758] PnPBIOS: Disabled [ 7.471180] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 7.482106] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 7.483172] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 7.484780] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 7.485776] pci_bus 0000:00: resource 7 [mem 0x1a400000-0xfebfffff window] [ 7.493466] NET: Registered protocol family 2 [ 7.533359] tcp_listen_portaddr_hash hash table entries: 256 (order: 1, 10240 bytes) [ 7.536469] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 7.544873] TCP bind hash table entries: 4096 (order: 5, 147456 bytes) [ 7.554206] TCP: Hash tables configured (established 4096 bind 4096) [ 7.568326] UDP hash table entries: 256 (order: 2, 20480 bytes) [ 7.581999] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes) [ 7.587960] CE: hpet increased min_delta_ns to 128142 nsec [ 7.636505] NET: Registered protocol family 1 [ 7.651554] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 7.652971] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 7.659546] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 7.665950] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 7.672724] PCI: CLS 0 bytes, default 64 [ 7.742748] Unpacking initramfs... [ 46.896635] random: crng init done [ 70.548752] debug: unmapping init [mem 0x5a121000-0x5a3dffff] [ 70.666423] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac) [ 70.672960] apm: overridden by ACPI. [ 70.804511] The force parameter has not been set to 1. The Iris poweroff handler will not be installed. [ 70.806250] NatSemi SCx200 Driver [ 70.815034] 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 [ 70.818593] spin_lock-torture: Creating torture_shuffle task [ 70.826167] spin_lock-torture: Creating torture_stutter task [ 70.828744] spin_lock-torture: torture_shuffle task started [ 70.834595] spin_lock-torture: Creating lock_torture_writer task [ 70.839316] spin_lock-torture: torture_stutter task started [ 70.844408] spin_lock-torture: Creating lock_torture_writer task [ 70.849300] spin_lock-torture: lock_torture_writer task started [ 70.854689] spin_lock-torture: Creating lock_torture_stats task [ 70.859978] spin_lock-torture: lock_torture_writer task started [ 70.869766] spin_lock-torture: lock_torture_stats task started [ 71.659405] tsc: Refined TSC clocksource calibration: 3199.993 MHz [ 71.673360] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2e204376f46, max_idle_ns: 440795203828 ns [ 71.676034] clocksource: Switched to clocksource tsc [ 77.801564] torture_init_begin: Refusing rcu init: spin_lock running. [ 77.802684] torture_init_begin: One torture test at a time! [ 77.846446] Initialise system trusted keyrings [ 77.864694] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [ 77.895566] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 77.916174] ntfs: driver 2.1.32 [Flags: R/W]. [ 77.919529] efs: 1.0a - http://aeschi.ch.eu.org/efs/ [ 77.923222] romfs: ROMFS MTD (C) 2007 Red Hat, Inc. [ 77.928146] QNX4 filesystem 0.2.3 registered. [ 77.929792] fuse init (API version 7.26) [ 77.958317] orangefs_debugfs_init: called with debug mask: :none: :0: [ 77.972249] orangefs_init: module version upstream loaded [ 77.978085] JFS: nTxBlock = 3069, nTxLock = 24557 [ 78.131376] befs: version: 0.9.3 [ 78.139740] ocfs2: Registered cluster interface o2cb [ 78.149297] OCFS2 User DLM kernel interface loaded [ 78.927877] NET: Registered protocol family 38 [ 78.941984] Key type asymmetric registered [ 78.944250] Asymmetric key parser 'x509' registered [ 78.948017] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) [ 78.958018] io scheduler noop registered [ 78.959170] io scheduler deadline registered (default) [ 78.961425] io scheduler cfq registered [ 78.962309] io scheduler mq-deadline registered (default) [ 78.965035] io scheduler bfq registered [ 79.012117] test_hexdump: all 1184 tests passed [ 79.023138] test_firmware: interface ready [ 79.490821] test_hash: __hash_32() has no arch implementation to test. [ 79.492141] test_hash: hash_32() has no arch implementation to test. [ 79.493085] test_hash: hash_64() has no arch implementation to test. [ 79.494722] test_hash: 33152 tests passed. [ 79.532535] test_siphash: self-tests: pass [ 79.626235] Running rhashtable test nelem=8, max_size=0, shrinking=0 [ 79.628665] Test 00: [ 79.642801] Adding 50000 keys [ 81.997430] CE: hpet increased min_delta_ns to 192213 nsec [ 81.997620] CE: hpet increased min_delta_ns to 288319 nsec [ 82.007910] CE: hpet increased min_delta_ns to 432478 nsec [ 82.031631] CE: hpet increased min_delta_ns to 648717 nsec [ 85.572706] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 87.551885] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 87.554253] Deleting 50000 keys [ 89.878032] Duration of test: 10233421840 ns [ 89.882965] Test 01: [ 89.896541] Adding 50000 keys [ 94.877564] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 96.304637] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 96.305998] Deleting 50000 keys [ 98.891905] Duration of test: 8993796090 ns [ 98.896534] Test 02: [ 98.912513] Adding 50000 keys [ 107.745848] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 109.156660] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 109.158919] Deleting 50000 keys [ 111.562640] Duration of test: 12648267874 ns [ 111.566180] Test 03: [ 111.580756] Adding 50000 keys [ 116.921305] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 118.382654] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 118.384714] Deleting 50000 keys [ 121.028717] Duration of test: 9447209905 ns [ 122.205613] test if its possible to exceed max_size 8192: no, ok [ 122.256212] Average test time: 10330673927 [ 122.258500] test inserting duplicates [ 122.260166] ODEBUG: object is on stack, but not annotated [ 122.261095] WARNING: CPU: 0 PID: 1 at lib/debugobjects.c:366 __debug_object_init+0x273/0x320 [ 122.261095] CPU: 0 PID: 1 Comm: swapper Tainted: G T 4.17.0-rc1 #9 [ 122.261095] EIP: __debug_object_init+0x273/0x320 [ 122.261095] EFLAGS: 00200002 CPU: 0 [ 122.261095] EAX: 41e68f0e EBX: 4011bd60 ECX: 00000001 EDX: 9810de96 [ 122.261095] ESI: 41fa8e40 EDI: 5701e6a8 EBP: 4011bcdc ESP: 4011bcb8 [ 122.261095] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068 [ 122.261095] CR0: 80050033 CR2: 00000000 CR3: 021b4000 CR4: 00000690 [ 122.261095] Call Trace: [ 122.261095] ? debug_object_init+0x13/0x16 [ 122.261095] ? __init_work+0x18/0x1a [ 122.261095] ? rhashtable_init+0x186/0x1be [ 122.261095] ? rhltable_init+0x11/0x1e [ 122.261095] ? test_insert_dup+0x47/0x239 [ 122.261095] ? ___siphash_aligned+0x11/0x11 [ 122.261095] ? jhash+0x11d/0x11d [ 122.261095] ? my_hashfn+0x14/0x14 [ 122.261095] ? test_rht_init+0x7b8/0x14a8 [ 122.261095] ? sched_clock_local+0xe/0x13b [ 122.261095] ? ___siphash_aligned+0x11/0x11 [ 122.261095] ? check_chain_key+0x9a/0xef [ 122.261095] ? __lock_acquire+0x487/0x4f8 [ 122.261095] ? lock_release+0x15f/0x178 [ 122.261095] ? add_device_randomness+0x74/0x8c [ 122.261095] ? test_insert_dup+0x239/0x239 [ 122.261095] ? do_one_initcall+0x8c/0x1d6 [ 122.261095] ? do_early_param+0xf1/0xf1 [ 122.261095] ? kernel_init_freeable+0x11b/0x1fc [ 122.261095] ? rest_init+0xbb/0xbb [ 122.261095] ? kernel_init+0x8/0xd0 [ 122.261095] ? ret_from_fork+0x19/0x30 [ 122.261095] Code: 7d 42 83 f8 04 7f 26 8b 15 f0 43 fa 41 8b 92 74 02 00 00 39 d3 0f 82 98 00 00 00 81 c2 00 20 00 00 39 d3 0f 83 8a 00 00 00 eb 6a <0f> 0b 8b 47 08 83 f8 03 74 12 72 07 83 f8 04 74 33 eb 3d c7 47 [ 122.261095] ---[ end trace ddc80bc4cbbff90f ]--- [ 122.323276] [ 122.323276] ---- ht: ---- [ 122.323276] bucket[1] -> [[ val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]] [ 122.323276] ------------- [ 122.328200] [ 122.328200] ---- ht: ---- [ 122.328200] bucket[1] -> [[ val 1 (tid=2), val 1 (tid=0) ]] -> [[ val 21 (tid=1) ]] [ 122.328200] ------------- [ 122.331538] [ 122.331538] ---- ht: ---- [ 122.331538] bucket[1] -> [[ val 21 (tid=1) ]] -> [[ val 1 (tid=0) ]] [ 122.331538] ------------- [ 122.334422] [ 122.334422] ---- ht: ---- [ 122.334422] bucket[1] -> [[ val 21 (tid=1) ]] -> [[ val 1 (tid=2), val 1 (tid=0) ]] [ 122.334422] ------------- [ 122.337751] Testing concurrent rhashtable access from 10 threads [ 131.042055] Writes: Total: 36697 Max/Min: 0/0 Fail: 0 [ 192.481539] Writes: Total: 39236 Max/Min: 0/0 Fail: 0 [ 253.926627] Writes: Total: 42335 Max/Min: 0/0 Fail: 0 [ 315.361568] Writes: Total: 44738 Max/Min: 0/0 Fail: 0 [ 376.801759] Writes: Total: 47615 Max/Min: 0/0 Fail: 0 [ 401.099396] test 3125 add/delete pairs into rhlist [ 402.063172] test 3125 random rhlist add/delete operations [ 402.523113] Started 10 threads, 0 failed, rhltable test returns 0 [ 402.554646] test_printf: all 240 tests passed [ 402.569608] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 27924 [ 402.625894] test_bitmap: all 1524 tests passed [ 402.680426] crc32: CRC_LE_BITS = 1, CRC_BE BITS = 1 [ 402.682144] crc32: self tests passed, processed 225944 bytes in 21951114 nsec [ 402.708563] crc32c: CRC_LE_BITS = 1 [ 402.709465] crc32c: self tests passed, processed 225944 bytes in 11062835 nsec [ 404.102272] crc32_combine: 8373 self tests passed [ 405.512475] crc32c_combine: 8373 self tests passed [ 405.578589] atomic64_test: passed for i386+ platform with CX8 and with SSE [ 405.584930] rbtree testing [ 405.728840] -> test 1 (latency of nnodes insert+delete): 457376 cycles [ 405.906038] -> test 2 (latency of nnodes cached insert+delete): 556931 cycles [ 405.929920] -> test 3 (latency of inorder traversal): 67239 cycles [ 405.932590] -> test 4 (latency to fetch first node) [ 405.934545] non-cached: 1075 cycles [ 405.935542] cached: 84 cycles [ 406.819953] augmented rbtree testing [ 407.135435] -> test 1 (latency of nnodes insert+delete): 1005787 cycles [ 407.441306] -> test 2 (latency of nnodes cached insert+delete): 968637 cycles [ 408.645513] interval tree insert/remove [ 408.988353] -> 1094420 cycles [ 408.990794] interval tree search [ 409.921061] -> 2974579 cycles (2692 results) [ 409.966352] gpio_winbond: chip ID at 2e is ffff [ 409.967972] gpio_winbond: not an our chip [ 409.969780] gpio_winbond: chip ID at 4e is ffff [ 409.971142] gpio_winbond: not an our chip [ 409.998375] no IO addresses supplied [ 410.004191] usbcore: registered new interface driver udlfb [ 410.005641] usbcore: registered new interface driver smscufx [ 410.061855] uvesafb: failed to execute /sbin/v86d [ 410.062747] uvesafb: make sure that the v86d helper is installed and executable [ 410.064650] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2) [ 410.065636] uvesafb: vbe_init() failed with -22 [ 410.067963] uvesafb: probe of uvesafb.0 failed with error -22 [ 410.072916] ipmi message handler version 39.2 [ 410.074563] IPMI System Interface driver. [ 410.080898] ipmi_si: Unable to find any System Interface(s) [ 410.081739] IPMI SSIF Interface driver [ 410.085677] IPMI Watchdog: driver initialized [ 410.102927] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 410.111409] ACPI: Power Button [PWRF] [ 410.124627] Warning: Processor Platform Limit event detected, but not handled. [ 410.125819] Consider compiling CPUfreq support into your kernel. [ 410.173031] isapnp: Scanning for PnP cards... [ 410.616527] isapnp: No Plug & Play device found [ 413.534746] HDLC line discipline maxframe=4096 [ 413.535502] N_HDLC line discipline registered. [ 413.537953] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 413.576176] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 413.623211] serial8250: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 415.205074] MOXA Intellio family driver version 6.0k [ 415.208517] MOXA Smartio/Industio family driver version 2.0.5 [ 415.231622] Non-volatile memory driver v1.3 [ 415.232717] toshiba: not a supported Toshiba laptop [ 415.248798] ppdev: user-space parallel port driver [ 415.258583] platform pc8736x_gpio.0: NatSemi pc8736x GPIO Driver Initializing [ 415.261406] platform pc8736x_gpio.0: no device found [ 415.268814] nsc_gpio initializing [ 415.272848] SyncLink PC Card driver $Revision: 4.34 $, tty major#238 [ 415.285968] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 415.311975] drm_mm: Testing DRM range manger (struct drm_mm), with random_seed=0x66c7d35b max_iterations=8192 max_prime=128 [ 415.314061] drm_mm: igt_sanitycheck - ok! [ 415.319360] igt_debug 0x0000000000000000-0x0000000000000200: 512: free [ 415.320971] igt_debug 0x0000000000000200-0x0000000000000600: 1024: used [ 415.321771] igt_debug 0x0000000000000600-0x0000000000000a00: 1024: free [ 415.322572] igt_debug 0x0000000000000a00-0x0000000000000e00: 1024: used [ 415.323378] igt_debug 0x0000000000000e00-0x0000000000001000: 512: free [ 415.324553] igt_debug total: 4096, used 2048 free 2048 [ 440.795613] Writes: Total: 60828 Max/Min: 0/0 Fail: 0 [ 501.659624] Writes: Total: 76884 Max/Min: 0/0 Fail: 0 [ 531.762044] CE: hpet increased min_delta_ns to 973075 nsec [ 565.895340] Writes: Total: 99062 Max/Min: 0/0 Fail: 0 [ 627.465337] Writes: Total: 106904 Max/Min: 0/0 Fail: 0 [ 689.409183] Writes: Total: 126360 Max/Min: 0/0 Fail: 0 [ 752.022690] Writes: Total: 142059 Max/Min: 0/0 Fail: 0 [ 816.345070] Writes: Total: 151141 Max/Min: 0/0 Fail: 0 [ 876.974534] Writes: Total: 161181 Max/Min: 0/0 Fail: 0 [ 939.185699] Writes: Total: 170761 Max/Min: 0/0 Fail: 0 [ 1000.515894] Writes: Total: 186012 Max/Min: 0/0 Fail: 0 [ 1063.286693] Writes: Total: 190366 Max/Min: 0/0 Fail: 0 [ 1127.322493] Writes: Total: 200928 Max/Min: 0/0 Fail: 0 [ 1191.572807] Writes: Total: 214735 Max/Min: 0/0 Fail: 0 [ 1255.070520] Writes: Total: 220555 Max/Min: 0/0 Fail: 0 [ 1318.247951] Writes: Total: 228800 Max/Min: 0/0 Fail: 0 [ 1380.112450] Writes: Total: 237733 Max/Min: 0/0 Fail: 0 [ 1445.872272] Writes: Total: 250774 Max/Min: 0/0 Fail: 0 [ 1509.312603] Writes: Total: 260887 Max/Min: 0/0 Fail: 0 BUG: kernel hang in test stage Elapsed time: 1520 #!/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 ) HTTP_PREFIX=https://github.com/0day-ci/lkp-qemu/raw/master wget --timestamping "${initrds[@]/#/$HTTP_PREFIX}" { cat "${initrds[@]//*\//}" [[ $INSTALL_MOD_PATH ]] && ( cd "$INSTALL_MOD_PATH" find lib | cpio -o -H newc --quiet | gzip ) echo job-script | cpio -o -H newc --quiet | gzip } > initrd.img kvm=( qemu-system-x86_64 -kernel $kernel -initrd initrd.img -m 420 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -serial stdio -display none -monitor null ) append=( ip=::::vm-lkp-os-yocto-ia32-11::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=i386 kconfig=i386-randconfig-c0-04180923 branch=linus/master commit=60cc43fc888428bb2f18f08997432d426a243338 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-04180923/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/vmlinuz-4.17.0-rc1 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-os-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-04180923/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/1 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[*]}"