early console in setup code [ 0.000000] Linux version 4.14.0-rc8 (kbuild@lkp-wsm-ep2) (gcc version 6.2.0 20160901 (Debian 6.2.0-3)) #88 SMP Tue Nov 7 06:44:05 CST 2017 [ 0.000000] Command line: ip=::::vm-lkp-os-openwrt-ia32-11::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-openwrt-ia32-11/boot-1-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-3579-gel5jo-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-ws0-11070630 branch=linus/master commit=39dae59d66acd86d1de24294bd2f343fd5e7a625 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-ws0-11070630/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-os-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-ws0-11070630/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_c [ 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: 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] 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 0x000f6aa0-0x000f6aaf] mapped at [ffffffffff200aa0] [ 0.000000] mpc: f6ab0-f6b80 [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] BRK [0x15a08000, 0x15a08fff] PGTABLE [ 0.000000] BRK [0x15a09000, 0x15a09fff] PGTABLE [ 0.000000] BRK [0x15a0a000, 0x15a0afff] PGTABLE [ 0.000000] BRK [0x15a0b000, 0x15a0bfff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1a11b000-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] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001a3dffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1a117000-0x1a11afff] [ 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] 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] smpboot: Allowing 1 CPUs, 0 hotplug CPUs [ 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: 7645519600211568 ns [ 0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] percpu: Embedded 483 pages/cpu @ffff880019e00000 s1947648 r0 d30720 u2097152 [ 0.000000] pcpu-alloc: s1947648 r0 d30720 u2097152 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 105899 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: ip=::::vm-lkp-os-openwrt-ia32-11::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-openwrt-ia32-11/boot-1-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-3579-gel5jo-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-ws0-11070630 branch=linus/master commit=39dae59d66acd86d1de24294bd2f343fd5e7a625 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-ws0-11070630/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-os-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-ws0-11070630/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. [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Memory: 365196K/429560K available (7458K kernel code, 1956K rwdata, 3828K rodata, 2892K init, 34012K bss, 64364K reserved, 0K cma-reserved) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU event tracing is enabled. [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.000000] Tasks RCU enabled. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16 [ 0.000000] Offload RCU callbacks from CPUs: . [ 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] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.000000] hpet clockevent registered [ 0.016000] tsc: Fast TSC calibration failed [ 0.040000] tsc: Unable to calibrate against PIT [ 0.040000] tsc: using HPET reference calibration [ 0.040000] tsc: Detected 3200.012 MHz processor [ 0.048000] Calibrating delay loop (skipped), value calculated using timer frequency.. 6400.02 BogoMIPS (lpj=12800048) [ 0.053195] pid_max: default: 4096 minimum: 301 [ 0.061956] ACPI: Core revision 20170728 [ 1.197652] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 1.233561] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 1.245106] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 1.253013] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 1.256691] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 1.456885] mce: CPU supports 10 MCE banks [ 1.473055] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 1.474478] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 5.646768] Freeing SMP alternatives memory: 20K [ 5.904831] smpboot: Max logical packages: 1 [ 5.912681] enabled ExtINT on CPU#0 [ 5.921621] ENABLING IO-APIC IRQs [ 5.925835] init IO_APIC IRQs [ 5.927256] apic 0 pin 0 not connected [ 5.939010] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 5.946147] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 5.948000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 5.948000] apic 0 pin 16 not connected [ 5.948000] apic 0 pin 17 not connected [ 5.948000] apic 0 pin 18 not connected [ 5.948000] apic 0 pin 19 not connected [ 5.948000] apic 0 pin 20 not connected [ 5.948000] apic 0 pin 21 not connected [ 5.948000] apic 0 pin 22 not connected [ 5.948000] apic 0 pin 23 not connected [ 5.948000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 6.045082] Using local APIC timer interrupts. [ 6.045082] calibrating APIC timer ... [ 6.056000] ... lapic delta = 6606753 [ 6.056000] ... PM-Timer delta = 378371 [ 6.056000] APIC calibration not consistent with PM-Timer: 105ms instead of 100ms [ 6.056000] APIC delta adjusted to PM-Timer: 6250250 (6606753) [ 6.056000] TSC delta adjusted to PM-Timer: 319977652 (338228556) [ 6.056000] ..... delta 6250250 [ 6.056000] ..... mult: 268446193 [ 6.056000] ..... calibration result: 4000160 [ 6.056000] ..... CPU clock speed is 3199.3106 MHz. [ 6.056000] ..... host bus clock speed is 1000.0160 MHz. [ 6.064000] smpboot: CPU0: AMD QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 6.254161] Performance Events: PMU not available due to virtualization, using software events only. [ 6.337157] Hierarchical SRCU implementation. [ 6.485578] Huh? What family is it: 0x6?! [ 6.604461] smp: Bringing up secondary CPUs ... [ 6.613716] smp: Brought up 1 node, 1 CPU [ 6.616684] ---------------- [ 6.621255] | NMI testsuite: [ 6.629034] -------------------- [ 6.630190] remote IPI: ok | [ 6.636664] local IPI: ok | [ 6.656670] -------------------- [ 6.660702] Good, all 2 testcases passed! | [ 6.661738] --------------------------------- [ 6.664999] smpboot: Total of 1 processors activated (6400.02 BogoMIPS) [ 7.050083] devtmpfs: initialized [ 7.168552] x86/mm: Memory block size: 128MB [ 7.390270] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 7.405815] futex hash table entries: 16 (order: -1, 2048 bytes) [ 7.453992] prandom: seed boundary self test passed [ 7.485422] prandom: 100 self tests passed [ 7.612678] regulator-dummy: no parameters [ 7.674523] regulator-dummy: no parameters [ 7.722817] regulator-dummy: Failed to create debugfs directory [ 7.876644] NET: Registered protocol family 16 [ 8.213016] cpuidle: using governor menu [ 8.244000] ACPI: bus type PCI registered [ 8.268653] PCI: Using configuration type 1 for base access [ 10.634124] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 10.678244] gpio-f7188x: Not a Fintek device at 0x0000002e [ 10.685674] gpio-f7188x: Not a Fintek device at 0x0000004e [ 10.733018] ACPI: Added _OSI(Module Device) [ 10.736649] ACPI: Added _OSI(Processor Device) [ 10.737837] ACPI: Added _OSI(3.0 _SCP Extensions) [ 10.738846] ACPI: Added _OSI(Processor Aggregator Device) [ 11.442263] ACPI: Interpreter enabled [ 11.449698] ACPI: (supports S0 S5) [ 11.452798] ACPI: Using IOAPIC for interrupt routing [ 11.468000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 11.552000] ACPI: Enabled 2 GPEs in block 00 to 0F [ 14.829295] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 14.839156] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 14.846065] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 14.898939] PCI host bridge to bus 0000:00 [ 14.902315] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 14.904889] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 14.912938] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 14.921268] pci_bus 0000:00: root bus resource [mem 0x1a400000-0xfebfffff window] [ 14.924692] pci_bus 0000:00: root bus resource [bus 00-ff] [ 14.934513] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 15.097598] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 15.250834] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 15.320997] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 15.352970] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 15.357255] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 15.360818] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 15.369834] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 15.512590] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 15.541716] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 15.545077] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 15.744573] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 15.768000] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 15.800706] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 15.856580] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 16.046364] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 16.060945] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 16.072874] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 16.136763] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 16.272516] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 16.288761] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 16.633028] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 16.688000] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 16.766505] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 16.837772] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 16.873958] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 17.214445] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 17.216000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 17.221094] pci 0000:00:02.0: vgaarb: bridge control possible [ 17.225140] vgaarb: loaded [ 17.475461] Linux video capture interface: v2.00 [ 17.486052] EDAC MC: Ver: 3.0.0 [ 17.669242] Unable to find dmi tokens [ 17.778111] FPGA manager framework [ 17.796623] PCI: Using ACPI for IRQ routing [ 17.798453] PCI: pci_cache_line_size set to 64 bytes [ 17.809975] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 17.817797] e820: reserve RAM buffer [mem 0x1a3e0000-0x1bffffff] [ 17.988565] clocksource: Switched to clocksource hpet [ 18.466958] Warning: could not register annotated branches stats [ 18.572750] hrtimer: interrupt took 7103890 ns [ 26.308055] pnp: PnP ACPI init [ 26.410809] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 26.465545] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 26.519546] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 26.549488] pnp 00:03: [dma 2] [ 26.570965] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 26.630770] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 26.686475] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 26.742549] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 26.950130] pnp: PnP ACPI: found 7 devices [ 27.104752] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 27.131370] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 27.145403] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 27.146770] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 27.153421] pci_bus 0000:00: resource 7 [mem 0x1a400000-0xfebfffff window] [ 27.249737] NET: Registered protocol family 1 [ 27.261699] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 27.263474] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 27.270274] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 27.278475] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 27.290110] PCI: CLS 0 bytes, default 64 [ 27.497917] Unpacking initramfs... [ 244.595829] Freeing initrd memory: 2836K [ 244.627059] Machine check injector initialized [ 245.046157] PCLMULQDQ-NI instructions are not detected. [ 245.066805] AVX or AES-NI instructions are not detected. [ 245.069909] CPU feature 'AVX registers' is not supported. [ 245.078770] CPU feature 'AVX registers' is not supported. [ 245.081986] CPU feature 'AVX registers' is not supported. [ 245.083040] AVX2 instructions are not detected. [ 245.091279] spin_lock-torture:--- Start of test [debug]: nwriters_stress=2 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 245.097324] spin_lock-torture: Creating torture_shuffle task [ 245.105391] spin_lock-torture: Creating torture_stutter task [ 245.110796] spin_lock-torture: torture_shuffle task started [ 245.114855] spin_lock-torture: Creating lock_torture_writer task [ 245.121255] spin_lock-torture: torture_stutter task started [ 245.123876] spin_lock-torture: Creating lock_torture_writer task [ 245.127288] spin_lock-torture: lock_torture_writer task started [ 245.138762] spin_lock-torture: Creating lock_torture_stats task [ 245.146057] spin_lock-torture: lock_torture_writer task started [ 245.158901] spin_lock-torture: lock_torture_stats task started [ 245.670651] tsc: Refined TSC clocksource calibration: 3200.003 MHz [ 245.682370] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2e204cd0862, max_idle_ns: 440795244678 ns [ 246.807992] clocksource: Switched to clocksource tsc [ 252.607590] torture_init_begin: Refusing rcu init: spin_lock running. [ 252.610455] torture_init_begin: One torture test at a time! [ 252.729986] workingset: timestamp_bits=56 max_order=17 bucket_order=0 [ 252.776168] romfs: ROMFS MTD (C) 2007 Red Hat, Inc. [ 252.794250] fuse init (API version 7.26) [ 254.267816] test_hexdump: all 1184 tests passed [ 254.303013] test_firmware: interface ready [ 254.309234] Running rhashtable test nelem=8, max_size=0, shrinking=0 [ 254.310645] Test 00: [ 254.424532] Adding 50000 keys [ 264.629336] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 269.871326] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 269.877534] Deleting 50000 keys [ 274.006876] Duration of test: 19578725778 ns [ 274.010579] Test 01: [ 274.102984] Adding 50000 keys [ 285.170349] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 290.142080] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 290.144001] Deleting 50000 keys [ 294.790719] Duration of test: 20685302787 ns [ 294.793363] Test 02: [ 294.873600] Adding 50000 keys [ 303.284096] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 306.147406] Writes: Total: 2 Max/Min: 0/0 Fail: 0 [ 308.013401] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 308.015882] Deleting 50000 keys [ 312.568093] Duration of test: 17689574870 ns [ 312.570839] Test 03: [ 312.675022] Adding 50000 keys [ 322.663235] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 330.578885] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 330.583179] Deleting 50000 keys [ 339.302251] Duration of test: 26623682510 ns [ 339.310019] Average test time: 21144321486 [ 339.311680] Testing concurrent rhashtable access from 10 threads [ 367.586868] Writes: Total: 2 Max/Min: 0/0 Fail: 0 [ 406.501055] BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 33s! [ 406.503708] Showing busy workqueues and worker pools: [ 406.510695] workqueue events: flags=0x0 [ 406.517288] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=3/256 [ 406.519526] in-flight: 3:rht_deferred_worker [ 406.520454] pending: vmstat_shepherd, rht_deferred_worker [ 406.540957] workqueue mm_percpu_wq: flags=0x8 [ 406.541847] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 [ 406.543449] pending: vmstat_update [ 406.550096] pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=33s workers=2 idle: 15 Elapsed time: 460 initrds=( /osimage/openwrt/openwrt-i386-2016-03-16.cgz /lkp/scheduled/vm-lkp-os-openwrt-ia32-11/boot-1-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-3579-gel5jo-0.cgz /lkp/lkp/lkp-i386.cgz ) cat "${initrds[@]}" > /fs/sdb2/initrd-vm-lkp-os-openwrt-ia32-11 kvm=( qemu-system-x86_64 -kernel /pkg/linux/x86_64-randconfig-ws0-11070630/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 -initrd /fs/sdb2/initrd-vm-lkp-os-openwrt-ia32-11 -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-11 -serial file:/dev/shm/kboot/vm-lkp-os-openwrt-ia32-11/serial -serial file:/dev/shm/kboot/vm-lkp-os-openwrt-ia32-11/kmsg -daemonize -display none -monitor null ) append=( ip=::::vm-lkp-os-openwrt-ia32-11::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-os-openwrt-ia32-11/boot-1-openwrt-i386-2016-03-16.cgz-39dae59d66acd86d1de24294bd2f343fd5e7a625-20171107-3579-gel5jo-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-ws0-11070630 branch=linus/master commit=39dae59d66acd86d1de24294bd2f343fd5e7a625 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-ws0-11070630/gcc-6/39dae59d66acd86d1de24294bd2f343fd5e7a625/vmlinuz-4.14.0-rc8 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-os-openwrt-ia32/openwrt-i386-2016-03-16.cgz/x86_64-randconfig-ws0-11070630/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[*]}"