early console in setup code [ 0.000000] Linux version 4.14.0-rc8 (kbuild@lkp-ivb-ep02) (gcc version 6.2.0 20160901 (Debian 6.2.0-3)) #65 Mon Nov 6 18:05:47 CST 2017 [ 0.000000] Command line: ip=::::vm-lkp-os-openwrt-ia32-13::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-openwrt-ia32-13/trinity-300s-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-45537-1vyo6na-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-u0-11060308 branch=linus/master commit=39dae59d66acd86d1de24294bd2f343fd5e7a625 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-os-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/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=normal rw [ 0.000000] KERNEL supported cpus: [ 0.000000] CPU: vendor_id 'AuthenticAMD' unknown, using generic init. [ 0.000000] CPU: Your system may be unstable. [ 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] 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] tsc: Fast TSC calibration failed [ 0.000000] tsc: Unable to calibrate against PIT [ 0.000000] tsc: No reference (HPET/PMTIMER) available [ 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 = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] BRK [0x0c4ce000, 0x0c4cefff] PGTABLE [ 0.000000] BRK [0x0c4cf000, 0x0c4cffff] PGTABLE [ 0.000000] BRK [0x0c4d0000, 0x0c4d0fff] PGTABLE [ 0.000000] BRK [0x0c4d1000, 0x0c4d1fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x19664000-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] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000) [ 0.000000] Zone ranges: [ 0.000000] DMA32 [mem 0x0000000000001000-0x000000001a3dffff] [ 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-0x000000001a3dffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001a3dffff] [ 0.000000] On node 0 totalpages: 107390 [ 0.000000] DMA32 zone: 1470 pages used for memmap [ 0.000000] DMA32 zone: 21 pages reserved [ 0.000000] DMA32 zone: 107390 pages, LIFO batch:31 [ 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 32, 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 ffffffffff5fc000 (fec00000) [ 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: 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: 105899 [ 0.000000] Kernel command line: ip=::::vm-lkp-os-openwrt-ia32-13::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-openwrt-ia32-13/trinity-300s-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-45537-1vyo6na-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-u0-11060308 branch=linus/master commit=39dae59d66acd86d1de24294bd2f343fd5e7a625 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-os-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/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] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 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: 270568K/429560K available (18233K kernel code, 14957K rwdata, 10728K rodata, 1876K init, 18956K bss, 158992K reserved, 0K cma-reserved) [ 0.000000] ftrace: allocating 31570 entries in 124 pages [ 0.000000] [ 0.000000] ********************************************************** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ** ** [ 0.000000] ** trace_printk() being used. Allocating extra memory. ** [ 0.000000] ** ** [ 0.000000] ** This means that this is a DEBUG kernel and it is ** [ 0.000000] ** unsafe for production use. ** [ 0.000000] ** ** [ 0.000000] ** If you see this message and you are not debugging ** [ 0.000000] ** the kernel, report this immediately to your vendor! ** [ 0.000000] ** ** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ********************************************************** [ 0.000000] NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16 [ 0.000000] console [ttyS0] enabled [ 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: 7871 kB [ 0.000000] per task-struct memory footprint: 2688 bytes [ 0.000000] ------------------------ [ 0.000000] | Locking API testsuite: [ 0.000000] ---------------------------------------------------------------------------- [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] A-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.000000] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.000000] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] recursive read-lock: | ok | |failed| [ 0.000000] recursive read-lock #2: | ok | |failed| [ 0.000000] mixed read-write-lock: |failed| |failed| [ 0.000000] mixed write-read-lock: |failed| |failed| [ 0.000000] mixed read-lock/lock-write ABBA: |failed| |failed| [ 0.000000] mixed read-lock/lock-read ABBA: | ok | |failed| [ 0.000000] mixed write-lock/lock-write ABBA: |failed| |failed| [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] hard-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.000000] soft-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.000000] hard-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.000000] soft-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.000000] sirq-safe-A => hirqs-on/12:failed|failed| ok | [ 0.000000] sirq-safe-A => hirqs-on/21:failed|failed| ok | [ 0.000000] hard-safe-A + irqs-on/12:failed|failed| ok | [ 0.000000] soft-safe-A + irqs-on/12:failed|failed| ok | [ 0.000000] hard-safe-A + irqs-on/21:failed|failed| ok | [ 0.000000] soft-safe-A + irqs-on/21:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/123:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/123:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/132:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/132:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/213:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/213:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/231:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/231:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/312:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/312:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/321:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/321:failed|failed| ok | [ 0.000000] hard-irq read-recursion/123: ok | [ 0.000000] soft-irq read-recursion/123: ok | [ 0.000000] hard-irq read-recursion/132: ok | [ 0.000000] soft-irq read-recursion/132: ok | [ 0.000000] hard-irq read-recursion/213: ok | [ 0.000000] soft-irq read-recursion/213: ok | [ 0.000000] hard-irq read-recursion/231: ok | [ 0.000000] soft-irq read-recursion/231: ok | [ 0.000000] hard-irq read-recursion/312: ok | [ 0.000000] soft-irq read-recursion/312: ok | [ 0.000000] hard-irq read-recursion/321: ok | [ 0.000000] soft-irq read-recursion/321: ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] | Wound/wait tests | [ 0.000000] --------------------- [ 0.000000] ww api failures: ok | ok | ok | [ 0.000000] ww contexts mixing:failed| ok | [ 0.000000] finishing ww context: ok | ok | ok | ok | [ 0.000000] locking mismatches: ok | ok | ok | [ 0.000000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.000000] spinlock nest unlocked: ok | [ 0.000000] ----------------------------------------------------- [ 0.000000] |block | try |context| [ 0.000000] ----------------------------------------------------- [ 0.000000] context:failed| ok | ok | [ 0.000000] try:failed| ok |failed| [ 0.000000] block:failed| ok |failed| [ 0.000000] spinlock:failed| ok |failed| [ 0.000000] -------------------------------------------------------- [ 0.000000] 153 out of 262 testcases failed, as expected. | [ 0.000000] ---------------------------------------------------- [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.000000] hpet clockevent registered [ 0.060000] tsc: Fast TSC calibration failed [ 0.110000] tsc: Unable to calibrate against PIT [ 0.120000] tsc: using HPET reference calibration [ 0.130000] tsc: Detected 3198.073 MHz processor [ 0.150000] Calibrating delay loop (skipped), value calculated using timer frequency.. 6396.14 BogoMIPS (lpj=31980730) [ 0.182325] pid_max: default: 4096 minimum: 301 [ 0.237016] ACPI: Core revision 20170728 [ 78.171715] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 78.762939] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 78.767925] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 80.756287] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 80.762615] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 80.766182] CPU: AuthenticAMD QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 83.525382] Performance Events: no PMU driver, software events only. [ 84.722867] enabled ExtINT on CPU#0 [ 84.882006] ENABLING IO-APIC IRQs [ 85.012765] init IO_APIC IRQs [ 85.066445] apic 0 pin 0 not connected [ 85.282523] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 85.340000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 85.340000] apic 0 pin 16 not connected [ 85.340000] apic 0 pin 17 not connected [ 85.340000] apic 0 pin 18 not connected [ 85.340000] apic 0 pin 19 not connected [ 85.340000] apic 0 pin 20 not connected [ 85.340000] apic 0 pin 21 not connected [ 85.340000] apic 0 pin 22 not connected [ 85.340000] apic 0 pin 23 not connected [ 85.340000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 85.532796] Using local APIC timer interrupts. [ 85.532796] calibrating APIC timer ... [ 85.540000] ... lapic delta = 6247208 [ 85.540000] ... PM-Timer delta = 357786 [ 85.540000] ... PM-Timer result ok [ 85.540000] ..... delta 6247208 [ 85.540000] ..... mult: 268315540 [ 85.540000] ..... calibration result: 9995532 [ 85.540000] ..... CPU clock speed is 3198.4006 MHz. [ 85.540000] ..... host bus clock speed is 999.5532 MHz. [ 86.297909] devtmpfs: initialized [ 89.030000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 89.077070] futex hash table entries: 16 (order: -2, 1536 bytes) [ 89.341579] xor: measuring software checksum speed [ 89.461692] prefetch64-sse: 168.400 MB/sec [ 89.601327] generic_sse: 154.000 MB/sec [ 89.612986] xor: using function: prefetch64-sse (168.400 MB/sec) [ 90.492041] regulator-dummy: no parameters [ 90.827455] regulator-dummy: no parameters [ 91.017463] regulator-dummy: Failed to create debugfs directory [ 91.573695] NET: Registered protocol family 16 [ 94.182258] cpuidle: using governor menu [ 94.611794] ACPI: bus type PCI registered [ 95.372114] PCI: Using configuration type 1 for base access [ 120.720000] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1] [ 120.720000] Modules linked in: [ 120.720000] CPU: 0 PID: 1 Comm: swapper Not tainted 4.14.0-rc8 #65 [ 120.720000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 120.720000] task: ffff88000037c040 task.stack: ffff880000380000 [ 120.720000] RIP: 0010:check_memory_region+0x129/0x12c [ 120.720000] RSP: 0000:ffff8800003875c0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10 [ 120.720000] RAX: fffffbfff113f3ab RBX: ffffffff889f9d57 RCX: ffffffff86243ea3 [ 120.720000] RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffffffff889f9d50 [ 120.720000] RBP: fffffbfff113f3ab R08: fffffbfff113f3ab R09: fffffbfff113f3aa [ 120.720000] R10: fffffbfff113f3aa R11: 0000000000000000 R12: ffff880000387ad0 [ 120.720000] R13: ffff880000387778 R14: ffff880000387738 R15: 0000000000000000 [ 120.720000] FS: 0000000000000000(0000) GS:ffffffff8804f000(0000) knlGS:0000000000000000 [ 120.720000] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 120.720000] CR2: 0000000000000000 CR3: 0000000009e20000 CR4: 00000000000006b0 [ 120.720000] Call Trace: [ 120.720000] deref_stack_reg+0x87/0x12f [ 120.720000] ? __read_once_size_nocheck+0xc/0xc [ 120.720000] ? deref_stack_reg+0x12f/0x12f [ 120.720000] ? __orc_find+0xb3/0xf7 [ 120.720000] ? kstrdup_const+0x7f/0x85 [ 120.720000] ? kstrdup_const+0x72/0x85 [ 120.720000] ? kstrdup_const+0x73/0x85 [ 120.720000] unwind_next_frame+0xf1e/0x10cf [ 120.720000] ? deref_stack_reg+0x12f/0x12f [ 120.720000] ? __kernel_text_address+0x38/0x7e [ 120.720000] __save_stack_trace+0x11c/0x12d [ 120.720000] ? __kernfs_new_node+0x31/0x275 [ 120.720000] save_stack+0x33/0xa6 [ 120.720000] ? save_stack+0x33/0xa6 [ 120.720000] ? kasan_kmalloc+0x86/0x94 [ 120.720000] ? slab_post_alloc_hook+0x31/0x43 [ 120.720000] ? __kmalloc_track_caller+0x145/0x182 [ 120.720000] ? kstrdup+0x64/0xb3 [ 120.720000] ? kstrdup_const+0x73/0x85 [ 120.720000] ? ftrace_likely_update+0x99/0xbe [ 120.720000] ? cyc2ns_read_begin+0x81/0x8f [ 120.720000] ? fs_reclaim_release+0x35/0x52 [ 120.720000] ? cycles_2_ns+0x67/0xaf [ 120.720000] ? cyc2ns_read_begin+0x8f/0x8f [ 120.720000] ? ftrace_likely_update+0x99/0xbe [ 120.720000] ? sched_clock_local+0xd7/0xf7 [ 120.720000] ? ftrace_likely_update+0x99/0xbe [ 120.720000] ? quarantine_reduce+0x60/0x1f7 [ 120.720000] ? kasan_unpoison_shadow+0xf/0x2e [ 120.720000] kasan_kmalloc+0x86/0x94 [ 120.720000] slab_post_alloc_hook+0x31/0x43 [ 120.720000] __kmalloc_track_caller+0x145/0x182 [ 120.720000] ? kstrdup_const+0x73/0x85 [ 120.720000] kstrdup+0x64/0xb3 [ 120.720000] kstrdup_const+0x73/0x85 [ 120.720000] __kernfs_new_node+0x31/0x275 [ 120.720000] kernfs_new_node+0x3e/0x96 [ 120.720000] kernfs_create_dir_ns+0x3c/0x11b [ 120.720000] sysfs_create_dir_ns+0x125/0x1e1 [ 120.720000] kobject_add_internal+0x2eb/0x888 [ 120.720000] kobject_add_varg+0x7f/0x8f [ 120.720000] kobject_init_and_add+0xd0/0xf2 [ 120.720000] ? kobject_create_and_add+0xc1/0xc1 [ 120.720000] ? trace_kmalloc+0x3e/0xc7 [ 120.720000] ? locate_module_kobject+0xb0/0x25a [ 120.720000] locate_module_kobject+0x150/0x25a [ 120.720000] param_sysfs_init+0x600/0x866 [ 120.720000] ? locate_module_kobject+0x25a/0x25a [ 120.720000] ? do_one_initcall+0x2c3/0x30f [ 120.720000] ? do_raw_spin_unlock+0x1af/0x1be [ 120.720000] ? locate_module_kobject+0x25a/0x25a [ 120.720000] do_one_initcall+0x14d/0x30f [ 120.720000] ? initcall_blacklisted+0x1a7/0x1a7 [ 120.720000] ? kernel_init_freeable+0x1c3/0x3f6 [ 120.720000] kernel_init_freeable+0x24a/0x3f6 [ 120.720000] ? rest_init+0x10d/0x10d [ 120.720000] kernel_init+0x11/0x244 [ 120.720000] ? rest_init+0x10d/0x10d [ 120.720000] ret_from_fork+0x25/0x30 [ 120.720000] Code: 41 80 38 00 75 05 49 ff c0 eb f0 4d 85 c0 74 1b 4d 39 c2 75 0c 49 0f be 02 83 e3 07 48 39 c3 7c 0a 5b 5d 44 89 da e9 a8 0d 00 00 <5b> 5d c3 48 8b 0c 24 89 f6 31 d2 e9 c7 fe ff ff 48 8b 0c 24 89 [ 120.720000] Kernel panic - not syncing: softlockup: hung tasks [ 120.720000] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.14.0-rc8 #65 [ 120.720000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 120.720000] Call Trace: [ 120.720000] [ 120.720000] panic+0x1f4/0x4ab [ 120.720000] ? __warn+0x1e1/0x1e1 [ 120.720000] ? watchdog+0x1a/0x1a [ 120.720000] watchdog_timer_fn+0x3c0/0x3ef [ 120.720000] __hrtimer_run_queues+0x351/0x59e [ 120.720000] ? __hrtimer_get_remaining+0x8d/0x8d [ 120.720000] ? read_seqcount_retry+0x2e/0x33 [ 120.720000] ? ktime_get_update_offsets_now+0x178/0x18a [ 120.720000] hrtimer_run_queues+0x113/0x126 [ 120.720000] run_local_timers+0x11/0xe4 [ 120.720000] update_process_times+0x27/0x83 [ 120.720000] tick_periodic+0xfd/0x109 [ 120.720000] tick_handle_periodic+0x27/0x145 [ 120.720000] ? tick_periodic+0x109/0x109 [ 120.720000] smp_apic_timer_interrupt+0x199/0x2c1 [ 120.720000] apic_timer_interrupt+0x89/0x90 [ 120.720000] [ 120.720000] RIP: 0010:check_memory_region+0x129/0x12c [ 120.720000] RSP: 0000:ffff8800003875c0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10 [ 120.720000] RAX: fffffbfff113f3ab RBX: ffffffff889f9d57 RCX: ffffffff86243ea3 [ 120.720000] RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffffffff889f9d50 [ 120.720000] RBP: fffffbfff113f3ab R08: fffffbfff113f3ab R09: fffffbfff113f3aa [ 120.720000] R10: fffffbfff113f3aa R11: 0000000000000000 R12: ffff880000387ad0 [ 120.720000] R13: ffff880000387778 R14: ffff880000387738 R15: 0000000000000000 [ 120.720000] ? deref_stack_reg+0x87/0x12f [ 120.720000] deref_stack_reg+0x87/0x12f [ 120.720000] ? __read_once_size_nocheck+0xc/0xc [ 120.720000] ? deref_stack_reg+0x12f/0x12f [ 120.720000] ? __orc_find+0xb3/0xf7 [ 120.720000] ? kstrdup_const+0x7f/0x85 [ 120.720000] ? kstrdup_const+0x72/0x85 [ 120.720000] ? kstrdup_const+0x73/0x85 [ 120.720000] unwind_next_frame+0xf1e/0x10cf [ 120.720000] ? deref_stack_reg+0x12f/0x12f [ 120.720000] ? __kernel_text_address+0x38/0x7e [ 120.720000] __save_stack_trace+0x11c/0x12d [ 120.720000] ? __kernfs_new_node+0x31/0x275 [ 120.720000] save_stack+0x33/0xa6 [ 120.720000] ? save_stack+0x33/0xa6 [ 120.720000] ? kasan_kmalloc+0x86/0x94 [ 120.720000] ? slab_post_alloc_hook+0x31/0x43 [ 120.720000] ? __kmalloc_track_caller+0x145/0x182 [ 120.720000] ? kstrdup+0x64/0xb3 [ 120.720000] ? kstrdup_const+0x73/0x85 [ 120.720000] ? ftrace_likely_update+0x99/0xbe [ 120.720000] ? cyc2ns_read_begin+0x81/0x8f [ 120.720000] ? fs_reclaim_release+0x35/0x52 [ 120.720000] ? cycles_2_ns+0x67/0xaf [ 120.720000] ? cyc2ns_read_begin+0x8f/0x8f [ 120.720000] ? ftrace_likely_update+0x99/0xbe [ 120.720000] ? sched_clock_local+0xd7/0xf7 [ 120.720000] ? ftrace_likely_update+0x99/0xbe [ 120.720000] ? quarantine_reduce+0x60/0x1f7 [ 120.720000] ? kasan_unpoison_shadow+0xf/0x2e [ 120.720000] kasan_kmalloc+0x86/0x94 [ 120.720000] slab_post_alloc_hook+0x31/0x43 [ 120.720000] __kmalloc_track_caller+0x145/0x182 [ 120.720000] ? kstrdup_const+0x73/0x85 [ 120.720000] kstrdup+0x64/0xb3 [ 120.720000] kstrdup_const+0x73/0x85 [ 120.720000] __kernfs_new_node+0x31/0x275 [ 120.720000] kernfs_new_node+0x3e/0x96 [ 120.720000] kernfs_create_dir_ns+0x3c/0x11b [ 120.720000] sysfs_create_dir_ns+0x125/0x1e1 [ 120.720000] kobject_add_internal+0x2eb/0x888 [ 120.720000] kobject_add_varg+0x7f/0x8f [ 120.720000] kobject_init_and_add+0xd0/0xf2 [ 120.720000] ? kobject_create_and_add+0xc1/0xc1 [ 120.720000] ? trace_kmalloc+0x3e/0xc7 [ 120.720000] ? locate_module_kobject+0xb0/0x25a [ 120.720000] locate_module_kobject+0x150/0x25a [ 120.720000] param_sysfs_init+0x600/0x866 [ 120.720000] ? locate_module_kobject+0x25a/0x25a [ 120.720000] ? do_one_initcall+0x2c3/0x30f [ 120.720000] ? do_raw_spin_unlock+0x1af/0x1be [ 120.720000] ? locate_module_kobject+0x25a/0x25a [ 120.720000] do_one_initcall+0x14d/0x30f [ 120.720000] ? initcall_blacklisted+0x1a7/0x1a7 [ 120.720000] ? kernel_init_freeable+0x1c3/0x3f6 [ 120.720000] kernel_init_freeable+0x24a/0x3f6 [ 120.720000] ? rest_init+0x10d/0x10d [ 120.720000] kernel_init+0x11/0x244 [ 120.720000] ? rest_init+0x10d/0x10d [ 120.720000] ret_from_fork+0x25/0x30 Elapsed time: 340 initrds=( /osimage/openwrt/openwrt-i386-2016-03-16.cgz /lkp/scheduled/vm-lkp-os-openwrt-ia32-13/trinity-300s-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-45537-1vyo6na-0.cgz /lkp/lkp/lkp-i386.cgz /osimage/pkg/static/trinity-i386.cgz /pkg/linux/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/modules.cgz ) cat "${initrds[@]}" > /fs/sdb2/initrd-vm-lkp-os-openwrt-ia32-13 kvm=( qemu-system-x86_64 -kernel /pkg/linux/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 -initrd /fs/sdb2/initrd-vm-lkp-os-openwrt-ia32-13 -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 -pidfile /dev/shm/kboot/pid-vm-lkp-os-openwrt-ia32-13 -serial file:/dev/shm/kboot/vm-lkp-os-openwrt-ia32-13/serial -serial file:/dev/shm/kboot/vm-lkp-os-openwrt-ia32-13/kmsg -daemonize -display none -monitor null ) append=( ip=::::vm-lkp-os-openwrt-ia32-13::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-openwrt-ia32-13/trinity-300s-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-45537-1vyo6na-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-u0-11060308 branch=linus/master commit=39dae59d66acd86d1de24294bd2f343fd5e7a625 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-os-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-u0-11060308/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/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=normal rw drbd.minor_count=8 ) "${kvm[@]}" -append "${append[*]}"