early console in setup code early console in extract_kernel input_data: 0x02753080 input_len: 0x00cdd49a output: 0x01000000 output_len: 0x01cc4624 kernel_total_size: 0x02835000 Decompressing Linux... Parsing ELF... No relocation needed... done. Booting the kernel. [ 0.000000] Linux version 4.15.0-rc1 (kbuild@xian) (gcc version 5.4.1 20171010 (Debian 5.5.0-3)) #360 Wed Nov 29 18:13:29 CST 2017 [ 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-0x0000000013fdbfff] usable [ 0.000000] BIOS-e820: [mem 0x0000000013fdc000-0x0000000013ffffff] 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] Notice: NX (Execute Disable) protection missing in CPU! [ 0.000000] random: fast init done [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x13fdc max_arch_pfn = 0x1000000 [ 0.000000] MTRR: Disabled [ 0.000000] x86/PAT: MTRRs disabled, skipping PAT initialization too. [ 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 [ffc00a90] [ 0.000000] mpc: f6aa0-f6b80 [ 0.000000] initial memory mapped: [mem 0x00000000-0x03bfffff] [ 0.000000] Base memory trampoline at [c009b000] 9b000 size 16384 [ 0.000000] BRK [0x0362a000, 0x0362afff] PGTABLE [ 0.000000] BRK [0x0362b000, 0x0362bfff] PGTABLE [ 0.000000] RAMDISK: [mem 0x13bf2000-0x13fcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000013FE15CE 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000013FE142A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000013FE0040 0013EA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x0000000013FE0000 000040 [ 0.000000] ACPI: APIC 0x0000000013FE151E 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000013FE1596 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffc000 ( fee00000) [ 0.000000] 0MB HIGHMEM available. [ 0.000000] 319MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 13fdc000 [ 0.000000] low ram: 0 - 13fdc000 [ 0.000000] cma: dma_contiguous_reserve(limit 13fdc000) [ 0.000000] kvm-clock: cpu 0, msr 0:13fda001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 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] Normal [mem 0x0000000001000000-0x0000000013fdbfff] [ 0.000000] HighMem 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-0x0000000013fdbfff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fdbfff] [ 0.000000] On node 0 totalpages: 81786 [ 0.000000] DMA zone: 32 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] Normal zone: 608 pages used for memmap [ 0.000000] Normal zone: 77788 pages, LIFO batch:15 [ 0.000000] Reserved but unavailable: 98 pages [ 0.000000] Using APIC driver default [ 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 ffffb000 (fec00000) [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 27f1f00 [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] 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: 81146 [ 0.000000] Kernel command line: ip=::::vm-lkp-wsx03-openwrt-i386-23::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-openwrt-i386-23/trinity-300s-openwrt-i386-2016-03-16.cgz-4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323-20171129-81955-j5mius-0.yaml ARCH=i386 kconfig=i386-randconfig-x0-11291749 branch=linus/master commit=4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323 BOOT_IMAGE=/pkg/linux/i386-randconfig-x0-11291749/gcc-5/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/vmlinuz-4.15.0-rc1 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-openwrt-i386/openwrt-i386-2016-03-16.cgz/i386-randconfig-x0-11291749/gcc-5/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=nor [ 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] allocated 331628 bytes of page_ext [ 0.000000] Initializing HighMem for node 0 (00000000:00000000) [ 0.000000] Memory: 280688K/327144K available (18138K kernel code, 2576K rwdata, 6200K rodata, 708K init, 11148K bss, 46456K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfff8c000 - 0xfffff000 ( 460 kB) [ 0.000000] pkmap : 0xffc00000 - 0xffe00000 (2048 kB) [ 0.000000] vmalloc : 0xd47dc000 - 0xffbfe000 ( 692 MB) [ 0.000000] lowmem : 0xc0000000 - 0xd3fdc000 ( 319 MB) [ 0.000000] .init : 0xc2a77000 - 0xc2b28000 ( 708 kB) [ 0.000000] .data : 0xc21b6b18 - 0xc2a51080 (8809 kB) [ 0.000000] .text : 0xc1000000 - 0xc21b6b18 (18138 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.001000] NR_IRQS: 2304, nr_irqs: 48, preallocated irqs: 16 [ 0.001000] CPU 0 irqstacks, hard=d340e000 soft=d3410000 [ 0.001000] Console: colour VGA+ 80x25 [ 0.001000] console [tty0] enabled [ 0.001000] console [ttyS0] enabled [ 0.001000] bootconsole [earlyser0] disabled [ 0.001000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.001000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.001000] ... MAX_LOCK_DEPTH: 48 [ 0.001000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.001000] ... CLASSHASH_SIZE: 4096 [ 0.001000] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.001000] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.001000] ... CHAINHASH_SIZE: 32768 [ 0.001000] memory used by lock dependency info: 4639 kB [ 0.001000] per task-struct memory footprint: 2112 bytes [ 0.001000] ACPI: Core revision 20170831 [ 0.001000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001000] hpet clockevent registered [ 0.002068] APIC: Switch to symmetric I/O mode setup [ 0.006064] Enabling APIC mode: Flat. Using 1 I/O APICs [ 0.011920] enabled ExtINT on CPU#0 [ 0.096101] ENABLING IO-APIC IRQs [ 0.098139] init IO_APIC IRQs [ 0.100068] apic 0 pin 0 not connected [ 0.102159] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.105160] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.106000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.106000] apic 0 pin 16 not connected [ 0.106000] apic 0 pin 17 not connected [ 0.106000] apic 0 pin 18 not connected [ 0.106000] apic 0 pin 19 not connected [ 0.106000] apic 0 pin 20 not connected [ 0.106000] apic 0 pin 21 not connected [ 0.106000] apic 0 pin 22 not connected [ 0.106000] apic 0 pin 23 not connected [ 0.106000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.106000] tsc: Detected 2260.996 MHz processor [ 0.107062] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260996) [ 0.108089] pid_max: default: 32768 minimum: 301 [ 0.119070] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.121169] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.131458] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.132191] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.133066] CPU: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 0.154454] Performance Events: PMU not available due to virtualization, using software events only. [ 0.232063] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.233432] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.236397] Using local APIC timer interrupts. [ 0.236397] calibrating APIC timer ... [ 0.239000] ... lapic delta = 6252318 [ 0.239000] ... PM-Timer delta = 357822 [ 0.239000] ... PM-Timer result ok [ 0.239000] ..... delta 6252318 [ 0.239000] ..... mult: 268535013 [ 0.239000] ..... calibration result: 1000370 [ 0.239000] ..... CPU clock speed is 2260.0958 MHz. [ 0.239000] ..... host bus clock speed is 1000.0370 MHz. [ 0.241754] devtmpfs: initialized [ 0.279515] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.280162] futex hash table entries: 256 (order: 1, 13312 bytes) [ 0.282411] xor: measuring software checksum speed [ 0.293054] pIII_sse : 1104.000 MB/sec [ 0.304033] prefetch64-sse: 1288.000 MB/sec [ 0.305060] xor: using function: prefetch64-sse (1288.000 MB/sec) [ 0.308521] regulator-dummy: no parameters [ 0.313930] NET: Registered protocol family 16 [ 0.334517] audit: initializing netlink subsys (disabled) [ 0.340000] cpuidle: using governor ladder [ 0.340267] audit: type=2000 audit(1511952696.470:1): state=initialized audit_enabled=0 res=1 [ 0.341041] cpuidle: using governor menu [ 0.359453] ACPI: bus type PCI registered [ 0.371575] PCI: PCI BIOS revision 2.10 entry at 0xfd501, last bus=0 [ 0.373057] PCI: Using configuration type 1 for base access [ 0.662102] raid6: mmxx1 gen() 296 MB/s [ 0.680057] raid6: mmxx2 gen() 339 MB/s [ 0.699231] raid6: sse1x1 gen() 238 MB/s [ 0.718132] raid6: sse1x2 gen() 292 MB/s [ 0.736122] raid6: sse2x1 gen() 511 MB/s [ 0.754060] raid6: sse2x1 xor() 419 MB/s [ 0.772102] raid6: sse2x2 gen() 621 MB/s [ 0.790067] raid6: sse2x2 xor() 474 MB/s [ 0.791132] raid6: using algorithm sse2x2 gen() 621 MB/s [ 0.793036] raid6: .... xor() 474 MB/s, rmw enabled [ 0.794037] raid6: using intx1 recovery algorithm [ 0.799258] ACPI: Added _OSI(Module Device) [ 0.800129] ACPI: Added _OSI(Processor Device) [ 0.801040] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.802061] ACPI: Added _OSI(Processor Aggregator Device) [ 0.847000] ACPI: Interpreter enabled [ 0.848130] ACPI: (supports S0 S4 S5) [ 0.849091] ACPI: Using IOAPIC for interrupt routing [ 0.851255] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.857000] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.983185] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.985096] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.986255] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.987159] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.989802] PCI host bridge to bus 0000:00 [ 0.990065] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.991069] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.992067] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.993064] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window] [ 0.994087] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.996035] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 1.008693] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 1.021459] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 1.036000] pci 0000:00:01.1: reg 0x20: [io 0xc0c0-0xc0cf] [ 1.042379] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 1.043095] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 1.044068] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 1.045094] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 1.052173] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 1.061628] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 1.063037] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 1.072426] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 1.077000] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 1.082000] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 1.094000] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 1.102268] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 1.107000] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 1.112000] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 1.126000] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 1.134306] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 1.140000] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 1.144000] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 1.154000] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] [ 1.176207] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 1.182000] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf] [ 1.184000] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] [ 1.192000] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] [ 1.210344] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000 [ 1.215000] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f] [ 1.240402] pci_bus 0000:00: on NUMA node 0 [ 1.252746] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.256516] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.260420] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.264287] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.266777] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 1.279593] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 1.280000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 1.280092] pci 0000:00:02.0: vgaarb: bridge control possible [ 1.281038] vgaarb: loaded [ 1.287871] SCSI subsystem initialized [ 1.289326] libata version 3.00 loaded. [ 1.290767] ACPI: bus type USB registered [ 1.291422] usbcore: registered new interface driver usbfs [ 1.294151] usbcore: registered new interface driver hub [ 1.295527] usbcore: registered new device driver usb [ 1.298321] media: Linux media interface: v0.10 [ 1.300194] Linux video capture interface: v2.00 [ 1.302653] pps_core: LinuxPPS API ver. 1 registered [ 1.303061] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.304228] PTP clock support registered [ 1.306258] EDAC MC: Ver: 3.0.0 [ 1.312161] EDAC DEBUG: edac_mc_sysfs_init: device mc created [ 1.314750] dell_smbios: Unable to run on non-Dell system [ 1.317711] FPGA manager framework [ 1.319107] PCI: Using ACPI for IRQ routing [ 1.320061] PCI: pci_cache_line_size set to 32 bytes [ 1.324287] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 1.325152] e820: reserve RAM buffer [mem 0x13fdc000-0x13ffffff] [ 1.329872] NET: Registered protocol family 8 [ 1.331056] NET: Registered protocol family 20 [ 1.350894] clocksource: Switched to clocksource kvm-clock [ 1.368848] VFS: Disk quotas dquot_6.6.0 [ 1.379822] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 1.397915] FS-Cache: Loaded [ 1.407470] CacheFiles: Loaded [ 1.416866] pnp: PnP ACPI init [ 1.426977] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 1.443508] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 1.471024] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 1.507829] pnp 00:03: [dma 2] [ 1.518100] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 1.553912] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 1.597480] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 1.622968] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 1.684350] pnp: PnP ACPI: found 7 devices [ 1.874492] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.930054] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.956494] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.981998] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 2.021437] pci_bus 0000:00: resource 7 [mem 0x14000000-0xfebfffff window] [ 2.056295] NET: Registered protocol family 2 [ 2.086729] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 2.112062] TCP bind hash table entries: 4096 (order: 5, 180224 bytes) [ 2.150634] TCP: Hash tables configured (established 4096 bind 4096) [ 2.185241] UDP hash table entries: 256 (order: 2, 24576 bytes) [ 2.215453] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes) [ 2.258678] NET: Registered protocol family 1 [ 2.282683] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 2.307712] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 2.343220] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 2.358946] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 2.380070] PCI: CLS 0 bytes, default 32 [ 2.392023] Trying to unpack rootfs image as initramfs... [ 8.291061] Freeing initrd memory: 3960K [ 8.339990] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac) [ 8.357889] apm: overridden by ACPI. [ 8.372815] PCLMULQDQ-NI instructions are not detected. [ 8.387488] The force parameter has not been set to 1. The Iris poweroff handler will not be installed. [ 15.015491] Initialise system trusted keyrings [ 15.037379] Key type blacklist registered [ 15.053437] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [ 15.204615] zbud: loaded [ 15.229295] DLM installed [ 15.249754] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 15.307051] qnx6: QNX6 filesystem 1.0.0 registered. [ 15.320402] fuse init (API version 7.26) [ 15.338261] orangefs_debugfs_init: called with debug mask: :none: :0: [ 15.364765] orangefs_init: module version upstream loaded [ 15.410846] JFS: nTxBlock = 2223, nTxLock = 17790 [ 15.447431] NILFS version 2 loaded [ 15.481283] ocfs2: Registered cluster interface user [ 15.513374] OCFS2 User DLM kernel interface loaded [ 15.639340] NET: Registered protocol family 38 [ 15.664289] Key type asymmetric registered [ 15.688369] Asymmetric key parser 'x509' registered [ 15.702179] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 15.722273] io scheduler noop registered (default) [ 15.737429] io scheduler cfq registered [ 15.759174] io scheduler mq-deadline registered [ 15.782894] io scheduler kyber registered [ 20.024725] String selftests succeeded [ 20.044036] test_string_helpers: Running tests... [ 20.083825] test_hexdump: all 1184 tests passed [ 20.297062] test_hash: __hash_32() has no arch implementation to test. [ 20.327974] test_hash: hash_32() has no arch implementation to test. [ 20.357758] test_hash: hash_64() has no arch implementation to test. [ 20.391786] test_hash: 33152 tests passed. [ 20.415743] test_siphash: self-tests: pass [ 20.456988] Running rhashtable test nelem=8, max_size=0, shrinking=0 [ 20.488623] Test 00: [ 20.503876] Adding 50000 keys [ 23.569993] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 24.134229] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 24.174860] Deleting 50000 keys [ 24.832636] Duration of test: 4310524039 ns [ 24.844696] Test 01: [ 24.852787] Adding 50000 keys [ 26.136621] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 26.664988] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 26.715315] Deleting 50000 keys [ 27.801930] Duration of test: 2940016871 ns [ 27.813784] Test 02: [ 27.822184] Adding 50000 keys [ 29.056979] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 29.509726] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 29.530167] Deleting 50000 keys [ 30.085066] Duration of test: 2253196798 ns [ 30.095985] Test 03: [ 30.102825] Adding 50000 keys [ 31.585403] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 31.983858] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 32.004184] Deleting 50000 keys [ 32.686077] Duration of test: 2574862752 ns [ 32.949370] test if its possible to exceed max_size 8192: no, ok [ 32.965838] Average test time: 3019650115 [ 32.977217] Testing concurrent rhashtable access from 10 threads [ 94.666183] test 3125 add/delete pairs into rhlist [ 95.069229] test 3125 random rhlist add/delete operations [ 95.330726] Started 10 threads, 0 failed, rhltable test returns 0 [ 95.347718] test passed [ 95.359028] test_printf: all 260 tests passed [ 96.656859] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 3332 [ 96.770961] test_bitmap: all 460506 tests passed [ 96.783469] test_uuid: all 18 tests passed [ 124.230111] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1] [ 124.230111] CPU: 0 PID: 1 Comm: swapper Not tainted 4.15.0-rc1 #360 [ 124.230111] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 124.230111] task: d3430000 task.stack: d342c000 [ 124.230111] EIP: test_parman_move+0xe/0xb0 [ 124.230111] EFLAGS: 00200212 CPU: 0 [ 124.230111] EAX: d0c00000 EBX: 00000004 ECX: 00000930 EDX: 0000090c [ 124.230111] ESI: d0740324 EDI: d359f680 EBP: d342dec4 ESP: d342debc [ 124.230111] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 [ 124.230111] CR0: 80050033 CR2: 00000000 CR3: 02b2b000 CR4: 000006b0 [ 124.230111] Call Trace: [ 124.230111] parman_lsort_item_add+0x9d/0x190 [ 124.230111] parman_item_add+0x9/0x10 [ 124.230111] test_parman_init+0x358/0x3b5 [ 124.230111] ? test_uuid_init+0x1a9/0x1a9 [ 124.230111] do_one_initcall+0x7c/0x10e [ 124.230111] ? kernel_init_freeable+0xc3/0x15b [ 124.230111] kernel_init_freeable+0xe3/0x15b [ 124.230111] ? rest_init+0xc0/0xc0 [ 124.230111] kernel_init+0xb/0xf0 [ 124.230111] ? schedule_tail_wrapper+0x9/0xc [ 124.230111] ret_from_fork+0x19/0x24 [ 124.230111] Code: e8 48 5f c2 00 5d 85 c0 0f 94 c0 0f b6 c0 c3 90 90 90 90 90 90 90 90 90 90 90 90 90 90 55 89 e5 57 53 8b 5d 08 8b 40 04 c1 e3 02 <8d> 3c 90 89 fa 8d 04 88 89 d9 e8 a3 be c2 00 83 fb 08 89 da 73 [ 124.230111] Kernel panic - not syncing: softlockup: hung tasks [ 124.230111] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.15.0-rc1 #360 [ 124.230111] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 124.230111] Call Trace: [ 124.230111] dump_stack+0x16/0x19 [ 124.230111] panic+0x86/0x1bd [ 124.230111] watchdog_timer_fn+0x1e7/0x1f0 [ 124.230111] ? watchdog+0x20/0x20 [ 124.230111] hrtimer_run_queues+0xf3/0x1d0 [ 124.230111] run_local_timers+0x8/0x40 [ 124.230111] update_process_times+0x22/0x50 [ 124.230111] tick_nohz_handler+0x66/0x100 [ 124.230111] smp_apic_timer_interrupt+0x40/0x80 [ 124.230111] ? irq_exit+0x2f/0xb0 [ 124.230111] apic_timer_interrupt+0x35/0x3c [ 124.230111] EIP: test_parman_move+0xe/0xb0 [ 124.230111] EFLAGS: 00200212 CPU: 0 [ 124.230111] EAX: d0c00000 EBX: 00000004 ECX: 00000930 EDX: 0000090c [ 124.230111] ESI: d0740324 EDI: d359f680 EBP: d342dec4 ESP: d342debc [ 124.230111] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 [ 124.230111] parman_lsort_item_add+0x9d/0x190 [ 124.230111] parman_item_add+0x9/0x10 [ 124.230111] test_parman_init+0x358/0x3b5 [ 124.230111] ? test_uuid_init+0x1a9/0x1a9 [ 124.230111] do_one_initcall+0x7c/0x10e [ 124.230111] ? kernel_init_freeable+0xc3/0x15b [ 124.230111] kernel_init_freeable+0xe3/0x15b [ 124.230111] ? rest_init+0xc0/0xc0 [ 124.230111] kernel_init+0xb/0xf0 [ 124.230111] ? schedule_tail_wrapper+0x9/0xc [ 124.230111] ret_from_fork+0x19/0x24 [ 124.230111] Kernel Offset: disabled Elapsed time: 40 #!/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/openwrt/openwrt-i386-2016-03-16.cgz /lkp/lkp/lkp-i386.cgz /osimage/pkg/debian-x86_64-2016-08-31.cgz/trinity-static-i386-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-wsx03-openwrt-i386-23-0 256G qemu-img create -f qcow2 disk-vm-lkp-wsx03-openwrt-i386-23-1 256G kvm=( qemu-system-i386 -enable-kvm -kernel $kernel -initrd initrd.img -m 320 -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-wsx03-openwrt-i386-23-0,media=disk,if=virtio -drive file=disk-vm-lkp-wsx03-openwrt-i386-23-1,media=disk,if=virtio -serial stdio -display none -monitor null ) append=( ip=::::vm-lkp-wsx03-openwrt-i386-23::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=i386 kconfig=i386-randconfig-x0-11291749 branch=linus/master commit=4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323 BOOT_IMAGE=/pkg/linux/i386-randconfig-x0-11291749/gcc-5/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/vmlinuz-4.15.0-rc1 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-openwrt-i386/openwrt-i386-2016-03-16.cgz/i386-randconfig-x0-11291749/gcc-5/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/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 ) "${kvm[@]}" -append "${append[*]}"