early console in setup code early console in extract_kernel input_data: 0x00000000027e4276 input_len: 0x0000000000bc53e3 output: 0x0000000001000000 output_len: 0x0000000001d522f4 kernel_total_size: 0x00000000023d8000 booted via startup_32() Physical KASLR using RDTSC... Virtual KASLR using RDTSC... Decompressing Linux... Parsing ELF... Performing relocations... done. Booting the kernel. [ 0.000000] Linux version 4.15.0-rc1 (kbuild@athens) (gcc version 7.2.1 20171025 (Debian 7.2.0-12)) #110 Tue Nov 28 05:50:17 CST 2017 [ 0.000000] Command line: ip=::::vm-lkp-wsx03-yocto-x86_64-12::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-yocto-x86_64-12/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323-20171128-31303-1lowyob-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-a0-11280544 branch=linus/master commit=4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-a0-11280544/gcc-7/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/vmlinuz-4.15.0-rc1 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-a0-11280544/gcc-7/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/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=tt [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001ffddfff] usable [ 0.000000] BIOS-e820: [mem 0x000000001ffde000-0x000000001fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] random: fast init done [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x1ffde max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 0080000000 mask FF80000000 uncachable [ 0.000000] 1 disabled [ 0.000000] 2 disabled [ 0.000000] 3 disabled [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] 7 disabled [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] BRK [0x08bb3000, 0x08bb3fff] PGTABLE [ 0.000000] BRK [0x08bb4000, 0x08bb4fff] PGTABLE [ 0.000000] BRK [0x08bb5000, 0x08bb5fff] PGTABLE [ 0.000000] BRK [0x08bb6000, 0x08bb6fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1e00c000-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:1ffdc001, 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] DMA32 [mem 0x0000000001000000-0x000000001ffddfff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x000000001ffddfff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffddfff] [ 0.000000] On node 0 totalpages: 130940 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1984 pages used for memmap [ 0.000000] DMA32 zone: 126942 pages, LIFO batch:31 [ 0.000000] Reserved but unavailable: 98 pages [ 0.000000] kasan: KernelAddressSanitizer initialized [ 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 7def440 [ 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: 19112604462750000 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: 128871 [ 0.000000] Kernel command line: ip=::::vm-lkp-wsx03-yocto-x86_64-12::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-yocto-x86_64-12/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323-20171128-31303-1lowyob-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-a0-11280544 branch=linus/master commit=4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-a0-11280544/gcc-7/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/vmlinuz-4.15.0-rc1 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-a0-11280544/gcc-7/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/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 con [ 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: 375276K/523760K available (11515K kernel code, 4029K rwdata, 5396K rodata, 1136K init, 9856K bss, 148484K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.010000] NR_IRQS: 4352, nr_irqs: 48, preallocated irqs: 16 [ 0.010000] Console: colour VGA+ 80x25 [ 0.010000] console [tty0] enabled [ 0.010000] console [ttyS0] enabled [ 0.010000] bootconsole [earlyser0] disabled [ 0.010000] ------------------------ [ 0.010000] | Locking API testsuite: [ 0.010000] ---------------------------------------------------------------------------- [ 0.010000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] A-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.010000] double unlock:failed|failed|failed|failed|failed|failed|failed| [ 0.010000] initialize held:failed|failed|failed|failed|failed|failed|failed| [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] recursive read-lock: | ok | |failed| [ 0.010000] recursive read-lock #2: | ok | |failed| [ 0.010000] mixed read-write-lock: |failed| |failed| [ 0.010000] mixed write-read-lock: |failed| |failed| [ 0.010000] mixed read-lock/lock-write ABBA: |failed| |failed| [ 0.010000] mixed read-lock/lock-read ABBA: | ok | |failed| [ 0.010000] mixed write-lock/lock-write ABBA: |failed| |failed| [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] hard-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.010000] soft-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.010000] hard-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.010000] soft-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.010000] sirq-safe-A => hirqs-on/12:failed|failed| ok | [ 0.010000] sirq-safe-A => hirqs-on/21:failed|failed| ok | [ 0.010000] hard-safe-A + irqs-on/12:failed|failed| ok | [ 0.010000] soft-safe-A + irqs-on/12:failed|failed| ok | [ 0.010000] hard-safe-A + irqs-on/21:failed|failed| ok | [ 0.010000] soft-safe-A + irqs-on/21:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.010000] hard-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.010000] soft-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.010000] hard-irq lock-inversion/123:failed|failed| ok | [ 0.010000] soft-irq lock-inversion/123:failed|failed| ok | [ 0.010000] hard-irq lock-inversion/132:failed|failed| ok | [ 0.010000] soft-irq lock-inversion/132:failed|failed| ok | [ 0.010000] hard-irq lock-inversion/213:failed|failed| ok | [ 0.010000] soft-irq lock-inversion/213:failed|failed| ok | [ 0.010000] hard-irq lock-inversion/231:failed|failed| ok | [ 0.010000] soft-irq lock-inversion/231:failed|failed| ok | [ 0.010000] hard-irq lock-inversion/312:failed|failed| ok | [ 0.010000] soft-irq lock-inversion/312:failed|failed| ok | [ 0.010000] hard-irq lock-inversion/321:failed|failed| ok | [ 0.010000] soft-irq lock-inversion/321:failed|failed| ok | [ 0.010000] hard-irq read-recursion/123: ok | [ 0.010000] soft-irq read-recursion/123: ok | [ 0.010000] hard-irq read-recursion/132: ok | [ 0.010000] soft-irq read-recursion/132: ok | [ 0.010000] hard-irq read-recursion/213: ok | [ 0.010000] soft-irq read-recursion/213: ok | [ 0.010000] hard-irq read-recursion/231: ok | [ 0.010000] soft-irq read-recursion/231: ok | [ 0.010000] hard-irq read-recursion/312: ok | [ 0.010000] soft-irq read-recursion/312: ok | [ 0.010000] hard-irq read-recursion/321: ok | [ 0.010000] soft-irq read-recursion/321: ok | [ 0.010000] -------------------------------------------------------------------------- [ 0.010000] | Wound/wait tests | [ 0.010000] --------------------- [ 0.010000] ww api failures: ok | ok |failed| [ 0.010000] ww contexts mixing:failed|failed| [ 0.010000] finishing ww context:failed|failed|failed|failed| [ 0.010000] locking mismatches:failed|failed|failed| [ 0.010000] EDEADLK handling: ok | ok |failed|failed|failed|failed|failed|failed|failed|failed| [ 0.010000] spinlock nest unlocked:failed| [ 0.010000] ----------------------------------------------------- [ 0.010000] |block | try |context| [ 0.010000] ----------------------------------------------------- [ 0.010000] context:failed| ok | ok | [ 0.010000] try:failed| ok |failed| [ 0.010000] block:failed| ok |failed| [ 0.010000] spinlock:failed| ok |failed| [ 0.010000] -------------------------------------------------------- [ 0.010000] 185 out of 262 testcases failed, as expected. | [ 0.010000] ---------------------------------------------------- [ 0.010000] ACPI: Core revision 20170831 [ 0.010000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.010000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.010000] hpet clockevent registered [ 0.010102] APIC: Switch to symmetric I/O mode setup [ 0.021038] enabled ExtINT on CPU#0 [ 0.047479] ENABLING IO-APIC IRQs [ 0.051235] init IO_APIC IRQs [ 0.060096] apic 0 pin 0 not connected [ 0.080868] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.091496] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.100000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.100000] apic 0 pin 16 not connected [ 0.100000] apic 0 pin 17 not connected [ 0.100000] apic 0 pin 18 not connected [ 0.100000] apic 0 pin 19 not connected [ 0.100000] apic 0 pin 20 not connected [ 0.100000] apic 0 pin 21 not connected [ 0.100000] apic 0 pin 22 not connected [ 0.100000] apic 0 pin 23 not connected [ 0.100000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.100000] tsc: Detected 2260.996 MHz processor [ 0.100055] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=22609960) [ 0.110067] pid_max: default: 32768 minimum: 301 [ 0.126191] Security Framework initialized [ 0.132299] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.140235] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.184270] mce: CPU supports 10 MCE banks [ 0.191199] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.200096] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.210034] CPU: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1) [ 0.242565] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only. [ 0.262113] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.270128] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.282472] TSC deadline timer enabled [ 0.290067] devtmpfs: initialized [ 0.410609] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.420396] futex hash table entries: 256 (order: 0, 6144 bytes) [ 0.435284] pinctrl core: initialized pinctrl subsystem [ 0.459132] regulator-dummy: no parameters [ 0.490187] NET: Registered protocol family 16 [ 0.588494] cpuidle: using governor ladder [ 0.608787] ACPI: bus type PCI registered [ 0.610099] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.637007] PCI: Using configuration type 1 for base access [ 1.485967] ACPI: Added _OSI(Module Device) [ 1.490386] ACPI: Added _OSI(Processor Device) [ 1.500119] ACPI: Added _OSI(3.0 _SCP Extensions) [ 1.510099] ACPI: Added _OSI(Processor Aggregator Device) [ 2.011815] workqueue: round-robin CPU selection forced, expect performance impact [ 2.471431] ACPI: Interpreter enabled [ 2.484107] ACPI: (supports S0 S3 S5) [ 2.490093] ACPI: Using IOAPIC for interrupt routing [ 2.504366] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 2.605750] ACPI: Enabled 2 GPEs in block 00 to 0F [ 5.706344] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 5.711258] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments] [ 5.723723] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 6.033420] acpiphp: Slot [3] registered [ 6.051107] acpiphp: Slot [4] registered [ 6.070874] acpiphp: Slot [5] registered [ 6.091364] acpiphp: Slot [6] registered [ 6.111100] acpiphp: Slot [7] registered [ 6.131198] acpiphp: Slot [8] registered [ 6.151128] acpiphp: Slot [9] registered [ 6.171100] acpiphp: Slot [10] registered [ 6.191328] acpiphp: Slot [11] registered [ 6.211137] acpiphp: Slot [12] registered [ 6.231523] acpiphp: Slot [13] registered [ 6.251125] acpiphp: Slot [14] registered [ 6.271164] acpiphp: Slot [15] registered [ 6.291234] acpiphp: Slot [16] registered [ 6.311171] acpiphp: Slot [17] registered [ 6.331103] acpiphp: Slot [18] registered [ 6.351223] acpiphp: Slot [19] registered [ 6.371171] acpiphp: Slot [20] registered [ 6.391131] acpiphp: Slot [21] registered [ 6.411065] acpiphp: Slot [22] registered [ 6.431129] acpiphp: Slot [23] registered [ 6.451099] acpiphp: Slot [24] registered [ 6.470801] acpiphp: Slot [25] registered [ 6.490911] acpiphp: Slot [26] registered [ 6.511109] acpiphp: Slot [27] registered [ 6.532895] acpiphp: Slot [28] registered [ 6.551137] acpiphp: Slot [29] registered [ 6.570674] acpiphp: Slot [30] registered [ 6.590647] acpiphp: Slot [31] registered [ 6.604159] PCI host bridge to bus 0000:00 [ 6.610456] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 6.620554] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 6.630590] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 6.640646] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window] [ 6.650542] pci_bus 0000:00: root bus resource [bus 00-ff] [ 6.661718] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 6.762227] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 6.871450] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 6.935236] pci 0000:00:01.1: reg 0x20: [io 0xc080-0xc08f] [ 6.960418] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 6.970161] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 6.980263] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 6.990226] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 7.082209] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 7.099755] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 7.100483] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 7.198296] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 7.220000] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 7.260000] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 7.340000] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 7.430935] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 7.460000] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 7.470000] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 7.520000] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 7.611566] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 7.640000] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 7.660000] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 7.720000] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] [ 7.837202] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000 [ 7.850000] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f] [ 7.987295] pci_bus 0000:00: on NUMA node 0 [ 8.250840] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 8.331819] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 8.410000] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 8.480719] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 8.524823] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 8.632007] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 8.640000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 8.640222] pci 0000:00:02.0: vgaarb: bridge control possible [ 8.650094] vgaarb: loaded [ 8.732475] FPGA manager framework [ 8.747557] PCI: Using ACPI for IRQ routing [ 8.750126] PCI: pci_cache_line_size set to 64 bytes [ 8.764256] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 8.770519] e820: reserve RAM buffer [mem 0x1ffde000-0x1fffffff] [ 8.828103] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 8.830130] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 8.845587] clocksource: Switched to clocksource kvm-clock [ 8.872986] pnp: PnP ACPI init [ 8.903451] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 8.942981] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 9.001014] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 9.031787] pnp 00:03: [dma 2] [ 9.049451] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 9.096006] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 9.153877] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 9.199466] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 9.372985] pnp: PnP ACPI: found 7 devices [ 9.970359] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 10.009721] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 10.026367] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 10.043764] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 10.062968] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window] [ 10.104854] NET: Registered protocol family 1 [ 10.119388] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 10.135987] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 10.153041] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 10.171232] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 10.195548] PCI: CLS 0 bytes, default 64 [ 10.227345] Unpacking initramfs... [ 105.044564] Freeing initrd memory: 32528K [ 105.130007] PCLMULQDQ-NI instructions are not detected. [ 105.143370] AVX or AES-NI instructions are not detected. [ 105.156886] CPU feature 'AVX registers' is not supported. [ 105.170182] CPU feature 'AVX registers' is not supported. [ 105.187064] spin_lock-torture:--- Start of test: 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 [ 105.224863] spin_lock-torture: Creating torture_shuffle task [ 105.240724] spin_lock-torture: Creating torture_stutter task [ 105.254749] spin_lock-torture: torture_shuffle task started [ 105.270234] spin_lock-torture: Creating lock_torture_writer task [ 105.284924] spin_lock-torture: torture_stutter task started [ 105.300370] spin_lock-torture: Creating lock_torture_writer task [ 105.315025] spin_lock-torture: lock_torture_writer task started [ 105.331677] spin_lock-torture: Creating lock_torture_stats task [ 105.346032] spin_lock-torture: lock_torture_writer task started [ 105.370150] torture_init_begin: Refusing rcu init: spin_lock running. [ 105.386045] spin_lock-torture: lock_torture_stats task started [ 105.399921] torture_init_begin: One torture test at a time! [ 105.470497] Initialise system trusted keyrings [ 105.488458] workingset: timestamp_bits=46 max_order=17 bucket_order=0 [ 106.460161] SGI XFS with security attributes, no debug enabled [ 106.717835] befs: version: 0.9.3 [ 107.020111] Key type asymmetric registered [ 107.032959] Asymmetric key parser 'x509' registered [ 107.058214] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 107.078095] io scheduler noop registered [ 107.107701] io scheduler cfq registered (default) [ 107.120795] io scheduler mq-deadline registered [ 132.280220] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper:1] [ 132.280220] Modules linked in: [ 132.280220] CPU: 0 PID: 1 Comm: swapper Not tainted 4.15.0-rc1 #110 [ 132.280220] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 132.280220] task: ffff880018e98040 task.stack: ffff880018ea0000 [ 132.280220] RIP: 0010:__asan_load8+0x64/0x7e [ 132.280220] RSP: 0000:ffff880018ea7dd0 EFLAGS: 00000207 ORIG_RAX: ffffffffffffff11 [ 132.280220] RAX: 1ffff10001c8dd00 RBX: 000000000000007d RCX: ffffffff84f2df6d [ 132.280220] RDX: b1b2b3b4b5b6b7b8 RSI: dffffc0000000000 RDI: ffff88000e46eba0 [ 132.280220] RBP: ffff88000e46e848 R08: 0000000000000007 R09: ffff88000e46e848 [ 132.280220] R10: 0000000000000008 R11: 0000000000000001 R12: 000000000000007d [ 132.280220] R13: ffff88000e46eba0 R14: 000000000000006b R15: 0000000000000056 [ 132.280220] FS: 0000000000000000(0000) GS:ffffffff84846000(0000) knlGS:0000000000000000 [ 132.280220] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 132.280220] CR2: 0000000000000000 CR3: 0000000007a17000 CR4: 00000000000006f0 [ 132.280220] Call Trace: [ 132.280220] string_selftest_init+0x369/0x4ab [ 132.280220] ? prandom_init+0xcf/0xcf [ 132.280220] ? do_early_param+0xde/0xde [ 132.280220] do_one_initcall+0xfd/0x202 [ 132.280220] ? initcall_blacklisted+0x178/0x178 [ 132.280220] ? ___might_sleep+0xa8/0x1f3 [ 132.280220] ? __schedule_bug+0x9e/0x9e [ 132.280220] ? __memset+0x12/0x30 [ 132.280220] ? __might_sleep+0x2f/0xbf [ 132.280220] kernel_init_freeable+0x1a5/0x273 [ 132.280220] ? rest_init+0xa9/0xa9 [ 132.280220] kernel_init+0xc/0x146 [ 132.280220] ? rest_init+0xa9/0xa9 [ 132.280220] ret_from_fork+0x1f/0x30 [ 132.280220] Code: c1 e9 03 41 80 3c 31 00 75 15 48 c1 e8 03 31 d2 8a 04 30 84 c0 74 08 31 d2 44 38 c0 0f 9e c2 83 e2 01 eb 10 48 c1 e8 03 8a 04 30 <84> c0 74 15 3c 07 0f 9e c2 84 d2 74 0c 31 d2 be 08 00 00 00 e9 [ 132.280220] Kernel panic - not syncing: softlockup: hung tasks [ 132.280220] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.15.0-rc1 #110 [ 132.280220] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 132.280220] Call Trace: [ 132.280220] [ 132.280220] panic+0x165/0x324 [ 132.280220] ? refcount_error_report+0x12e/0x12e [ 132.280220] ? vprintk_func+0x93/0x9b [ 132.280220] ? watchdog_timer_fn+0x1d2/0x212 [ 132.280220] watchdog_timer_fn+0x1e3/0x212 [ 132.280220] hrtimer_run_queues+0xe9/0x1cc [ 132.280220] run_local_timers+0x5c/0xc7 [ 132.280220] ? add_timer_on+0x1a9/0x1a9 [ 132.280220] ? __irqentry_text_end+0x6/0x6 [ 132.280220] ? ktime_get+0x4f/0x5a [ 132.280220] update_process_times+0x20/0x52 [ 132.280220] tick_handle_periodic+0x1c/0x69 [ 132.280220] smp_apic_timer_interrupt+0x52/0x60 [ 132.280220] apic_timer_interrupt+0xa7/0xb0 [ 132.280220] [ 132.280220] RIP: 0010:__asan_load8+0x64/0x7e [ 132.280220] RSP: 0000:ffff880018ea7dd0 EFLAGS: 00000207 ORIG_RAX: ffffffffffffff11 [ 132.280220] RAX: 1ffff10001c8dd00 RBX: 000000000000007d RCX: ffffffff84f2df6d [ 132.280220] RDX: b1b2b3b4b5b6b7b8 RSI: dffffc0000000000 RDI: ffff88000e46eba0 [ 132.280220] RBP: ffff88000e46e848 R08: 0000000000000007 R09: ffff88000e46e848 [ 132.280220] R10: 0000000000000008 R11: 0000000000000001 R12: 000000000000007d [ 132.280220] R13: ffff88000e46eba0 R14: 000000000000006b R15: 0000000000000056 [ 132.280220] ? string_selftest_init+0x369/0x4ab [ 132.280220] string_selftest_init+0x369/0x4ab [ 132.280220] ? prandom_init+0xcf/0xcf [ 132.280220] ? do_early_param+0xde/0xde [ 132.280220] do_one_initcall+0xfd/0x202 [ 132.280220] ? initcall_blacklisted+0x178/0x178 [ 132.280220] ? ___might_sleep+0xa8/0x1f3 [ 132.280220] ? __schedule_bug+0x9e/0x9e [ 132.280220] ? __memset+0x12/0x30 [ 132.280220] ? __might_sleep+0x2f/0xbf [ 132.280220] kernel_init_freeable+0x1a5/0x273 [ 132.280220] ? rest_init+0xa9/0xa9 [ 132.280220] kernel_init+0xc/0x146 [ 132.280220] ? rest_init+0xa9/0xa9 [ 132.280220] ret_from_fork+0x1f/0x30 [ 132.280220] Kernel Offset: 0x2600000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff) Elapsed time: 140 #!/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-wsx03-yocto-x86_64-12-0 256G kvm=( qemu-system-x86_64 -enable-kvm -cpu SandyBridge -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-wsx03-yocto-x86_64-12-0,media=disk,if=virtio -serial stdio -display none -monitor null ) append=( ip=::::vm-lkp-wsx03-yocto-x86_64-12::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=x86_64 kconfig=x86_64-randconfig-a0-11280544 branch=linus/master commit=4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-a0-11280544/gcc-7/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/vmlinuz-4.15.0-rc1 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-wsx03-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-a0-11280544/gcc-7/4fbd8d194f06c8a3fd2af1ce560ddb31f7ec8323/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 ) "${kvm[@]}" -append "${append[*]}"