early console in setup code
Probing EDD (edd=off to disable)... ok
[    0.000000] Linux version 4.7.0-rc4-00256-g325e2ef (kbuild@xian) (gcc version 6.1.1 20160430 (Debian 6.1.1-1) ) #2 PREEMPT Thu Jun 30 05:25:01 CST 2016
[    0.000000] Command line: root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-ia32-28/bisect_boot-1-yocto-minimal-i386.cgz-x86_64-randconfig-s4-06300257-325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79-20160630-98844-15a2z12-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-06300257 branch=linux-devel/devel-spot-201606300239 commit=325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/vmlinuz-4.7.0-rc4-00256-g325e2ef max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-ia32/yocto-minimal-i386.cgz/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-ia32-28::dhcp drbd.minor_count=8
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    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-0x0000000013fdffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fe0000-0x0000000013ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    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 = 0x13fe0 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  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 0x000f6630-0x000f663f] mapped at [ffff8800000f6630]
[    0.000000]   mpc: f6640-f6718
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] BRK [0x055e1000, 0x055e1fff] PGTABLE
[    0.000000] BRK [0x055e2000, 0x055e2fff] PGTABLE
[    0.000000] BRK [0x055e3000, 0x055e3fff] PGTABLE
[    0.000000] BRK [0x055e4000, 0x055e4fff] PGTABLE
[    0.000000] BRK [0x055e5000, 0x055e5fff] PGTABLE
[    0.000000] BRK [0x055e6000, 0x055e6fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x13a8f000-0x13fdffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6460 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE16EE 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE0C14 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 000BD4 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: SSDT 0x0000000013FE0C88 0009B6 (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x0000000013FE163E 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE16B6 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fd000 (        fee00000)
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:13a8e001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 5467802304 cycles
[    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-0x0000000013fdffff]
[    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-0x0000000013fdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fdffff]
[    0.000000] On node 0 totalpages: 81790
[    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: 1216 pages used for memmap
[    0.000000]   DMA32 zone: 77792 pages, LIFO batch:15
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fd000 (        fee00000)
[    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 ffffffffff5fc000 (fec00000)
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 306af80
[    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 in Zone order, mobility grouping on.  Total pages: 80489
[    0.000000] Kernel command line: root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-ia32-28/bisect_boot-1-yocto-minimal-i386.cgz-x86_64-randconfig-s4-06300257-325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79-20160630-98844-15a2z12-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-06300257 branch=linux-devel/devel-spot-201606300239 commit=325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/vmlinuz-4.7.0-rc4-00256-g325e2ef max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-ia32/yocto-minimal-i386.cgz/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-ia32-28::dhcp drbd.minor_coun
[    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: 242116K/327160K available (24159K kernel code, 20899K rwdata, 7136K rodata, 968K init, 16912K bss, 85044K reserved, 0K cma-reserved)
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] NR_IRQS:4352 nr_irqs:48 16
[    0.000000] console [ttyS0] enabled
[    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|
[    0.000000]                  A-B-B-A deadlock:failed|failed|  ok  |failed|failed|failed|
[    0.000000]              A-B-B-C-C-A deadlock:failed|failed|  ok  |failed|failed|failed|
[    0.000000]              A-B-C-A-B-C deadlock:failed|failed|  ok  |failed|failed|failed|
[    0.000000]          A-B-B-C-C-D-D-A deadlock:failed|failed|  ok  |failed|failed|failed|
[    0.000000]          A-B-C-D-B-D-D-A deadlock:failed|failed|  ok  |failed|failed|failed|
[    0.000000]          A-B-C-D-B-C-D-A deadlock:failed|failed|  ok  |failed|failed|failed|
[    0.000000]                     double unlock:  ok  |  ok  |failed|  ok  |failed|failed|
[    0.000000]                   initialize held:failed|failed|failed|failed|failed|failed|
[    0.000000]                  bad unlock order:  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]   --------------------------------------------------------------------------
[    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:failed|
[    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] 151 out of 253 testcases failed, as expected. |
[    0.000000] ----------------------------------------------------
[    0.000000] allocated 786432 bytes of page_ext
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Detected 2693.508 MHz processor
[    0.254970] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=2693508)
[    0.256330] pid_max: default: 32768 minimum: 301
[    0.257169] ACPI: Core revision 20160422
[    0.258013] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.259110] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.260452] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.261006] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.261587] CPU: Intel Westmere E56xx/L56xx/X56xx (Nehalem-C) (family: 0x6, model: 0x2c, stepping: 0x1)
[    0.266591] Performance Events: unsupported p6 CPU model 44 no PMU driver, software events only.
[    0.267988] enabled ExtINT on CPU#0
[    0.269370] ENABLING IO-APIC IRQs
[    0.269748] init IO_APIC IRQs
[    0.270147]  apic 0 pin 0 not connected
[    0.270540] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.271612] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.272913] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.274073] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.275352] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.276614] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.277803] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.279116] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.279992] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.280747] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.282043] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.283372] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.284700] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.286053] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.287391] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.288541]  apic 0 pin 16 not connected
[    0.289068]  apic 0 pin 17 not connected
[    0.289587]  apic 0 pin 18 not connected
[    0.290113]  apic 0 pin 19 not connected
[    0.290629]  apic 0 pin 20 not connected
[    0.291177]  apic 0 pin 21 not connected
[    0.291694]  apic 0 pin 22 not connected
[    0.292321]  apic 0 pin 23 not connected
[    0.293118] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.294084] Using local APIC timer interrupts.
[    0.294084] calibrating APIC timer ...
[    0.406859] ... lapic delta = 6874933
[    0.407363] ... PM-Timer delta = 393726
[    0.407876] APIC calibration not consistent with PM-Timer: 109ms instead of 100ms
[    0.408876] APIC delta adjusted to PM-Timer: 6250310 (6874933)
[    0.409640] TSC delta adjusted to PM-Timer: 269347694 (296264856)
[    0.410443] ..... delta 6250310
[    0.410873] ..... mult: 268448770
[    0.411313] ..... calibration result: 1000049
[    0.411894] ..... CPU clock speed is 2693.0476 MHz.
[    0.412547] ..... host bus clock speed is 1000.0049 MHz.
[    0.413520] pgdatinit0 (12) used greatest stack depth: 15064 bytes left
[    0.414529] devtmpfs: initialized
[    0.415343] x86/mm: Memory block size: 128MB
[    0.423390] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.424731] xor: measuring software checksum speed
[    0.435325]    prefetch64-sse: 11772.000 MB/sec
[    0.445325]    generic_sse:  9476.000 MB/sec
[    0.445911] xor: using function: prefetch64-sse (11772.000 MB/sec)
[    0.446724] prandom: seed boundary self test passed
[    0.448146] prandom: 100 self tests passed
[    0.448706] pinctrl core: initialized pinctrl subsystem
[    0.449722] regulator-dummy: no parameters
[    0.450884] NET: Registered protocol family 16
[    0.455341] cpuidle: using governor menu
[    0.456857] ACPI: bus type PCI registered
[    0.457542] PCI: Using configuration type 1 for base access
[    0.488047] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.506339] raid6: sse2x1   gen()  4632 MB/s
[    0.523333] raid6: sse2x1   xor()  3324 MB/s
[    0.540334] raid6: sse2x2   gen()  6121 MB/s
[    0.557335] raid6: sse2x2   xor()  4238 MB/s
[    0.574333] raid6: sse2x4   gen()  7851 MB/s
[    0.591330] raid6: sse2x4   xor()  4691 MB/s
[    0.591911] raid6: using algorithm sse2x4 gen() 7851 MB/s
[    0.592630] raid6: .... xor() 4691 MB/s, rmw enabled
[    0.593269] raid6: using ssse3x2 recovery algorithm
[    0.594095] gpio-f7188x: Not a Fintek device at 0x0000002e
[    0.594841] gpio-f7188x: Not a Fintek device at 0x0000004e
[    0.595932] ACPI: Added _OSI(Module Device)
[    0.596505] ACPI: Added _OSI(Processor Device)
[    0.597071] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.597695] ACPI: Added _OSI(Processor Aggregator Device)
[    0.603956] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.604828] 
[    0.607884] ACPI: Interpreter enabled
[    0.608428] ACPI: (supports S0 S3 S4 S5)
[    0.608963] ACPI: Using IOAPIC for interrupt routing
[    0.609682] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.622674] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.623520] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments]
[    0.624387] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.625248] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.626967] PCI host bridge to bus 0000:00
[    0.627534] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.628427] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.629317] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.630279] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window]
[    0.631249] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.632030] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.633603] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.635285] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.641046] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    0.643808] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.644743] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.645619] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.647098] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.648486] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.649732] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.650677] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.652195] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.654994] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.659846] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.669373] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.670878] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.673086] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.675263] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.683809] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    0.685291] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    0.687535] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    0.689953] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    0.698715] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    0.700499] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    0.706705] pci_bus 0000:00: on NUMA node 0
[    0.708385] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.709537] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.710644] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.718090] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.719340] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.721115] ACPI: Enabled 16 GPEs in block 00 to 0F
[    0.722963] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.723907] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.725201] vgaarb: loaded
[    0.725675] vgaarb: bridge control possible 0000:00:02.0
[    0.727594] SCSI subsystem initialized
[    0.728285] libata version 3.00 loaded.
[    0.729006] ACPI: bus type USB registered
[    0.729787] usbcore: registered new interface driver usbfs
[    0.730750] usbcore: registered new interface driver hub
[    0.731709] usbcore: registered new device driver usb
[    0.732797] Linux video capture interface: v2.00
[    0.733639] pps_core: LinuxPPS API ver. 1 registered
[    0.734469] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.736219] wmi: Mapper loaded
[    0.736998] FPGA manager framework
[    0.737692] Advanced Linux Sound Architecture Driver Initialized.
[    0.740045] PCI: Using ACPI for IRQ routing
[    0.740770] PCI: pci_cache_line_size set to 64 bytes
[    0.741798] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.742786] e820: reserve RAM buffer [mem 0x13fe0000-0x13ffffff]
[    0.746095] clocksource: Switched to clocksource kvm-clock
[    0.747407] FS-Cache: Loaded
[    0.748089] CacheFiles: Loaded
[    0.748732] pnp: PnP ACPI init
[    0.749529] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.750821] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.752086] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.753293] pnp 00:03: [dma 2]
[    0.753934] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.755293] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.756617] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.758622] pnp: PnP ACPI: found 6 devices
[    0.765199] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.766848] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.767832] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.768841] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.769980] pci_bus 0000:00: resource 7 [mem 0x14000000-0xfebfffff window]
[    0.771179] NET: Registered protocol family 1
[    0.771901] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.772921] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.773908] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.774997] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.776422] PCI: CLS 0 bytes, default 64
[    0.777330] Unpacking initramfs...
[    1.202894] debug: unmapping init [mem 0xffff880013a8f000-0xffff880013fdffff]
[    1.215135] cryptomgr_test (24) used greatest stack depth: 15048 bytes left
[    1.218220] AVX or AES-NI instructions are not detected.
[    1.219085] CPU feature 'AVX registers' is not supported.
[    1.220122] CPU feature 'AVX registers' is not supported.
[    1.221998] futex hash table entries: 256 (order: 1, 12288 bytes)
[    1.223510] workingset: timestamp_bits=44 max_order=16 bucket_order=0
[    1.225014] ntfs: driver 2.1.32 [Flags: R/O].
[    1.225930] fuse init (API version 7.24)
[    1.226933] NILFS version 2 loaded
[    1.228471] gfs2: GFS2 installed
[    1.229211] Key type big_key registered
[    1.233254] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    1.234481] io scheduler noop registered
[    1.235148] io scheduler cfq registered (default)
[    1.238867] test_hexdump: all 1184 tests passed
[    1.240051] test_firmware: interface ready
[    1.254698] test_hash: __hash_32() has no arch implementation to test.
[    1.255770] test_hash: hash_32() has no arch implementation to test.
[    1.256769] test_hash: hash_64() has no arch implementation to test.
[    1.257786] test_hash: 33152 tests passed.
[    1.258490] Running rhashtable test nelem=8, max_size=0, shrinking=0
[    1.259490] Test 00:
[    1.262183]   Adding 50000 keys
[    1.285166]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.298826]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.300164]   Deleting 50000 keys
[    1.311646]   Duration of test: 48943792 ns
[    1.312563] Test 01:
[    1.315212]   Adding 50000 keys
[    1.338164]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.351876]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.353217]   Deleting 50000 keys
[    1.364701]   Duration of test: 48940718 ns
[    1.365617] Test 02:
[    1.368312]   Adding 50000 keys
[    1.391141]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.404658]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.406008]   Deleting 50000 keys
[    1.417429]   Duration of test: 48666651 ns
[    1.418323] Test 03:
[    1.420942]   Adding 50000 keys
[    1.443809]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.457435]   Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0
[    1.458735]   Deleting 50000 keys
[    1.469939]   Duration of test: 48437410 ns
[    1.470827] Average test time: 48747142
[    1.471469] Testing concurrent rhashtable access from 10 threads
[    1.602549]   thread[8]: rhashtable_insert_fast failed
[    1.603417]   thread[2]: rhashtable_insert_fast failed
[    1.603422]   thread[3]: rhashtable_insert_fast failed
[    1.603426]   thread[0]: rhashtable_insert_fast failed
[    1.603437] Test failed: thread 0 returned: -12
[    1.603469] rhashtable_thra (124) used greatest stack depth: 14568 bytes left
[    1.603482]   thread[9]: rhashtable_insert_fast failed
[    1.603486]   thread[1]: rhashtable_insert_fast failed
[    1.603490] Test failed: thread 1 returned: -12
[    1.603513] Test failed: thread 2 returned: -12
[    1.603533] Test failed: thread 3 returned: -12
[    1.603553]   thread[7]: rhashtable_insert_fast failed
[    1.603556]   thread[4]: rhashtable_insert_fast failed
[    1.603560] Test failed: thread 4 returned: -12
[    1.603579]   thread[6]: rhashtable_insert_fast failed
[    1.603582]   thread[5]: rhashtable_insert_fast failed
[    1.603586] Test failed: thread 5 returned: -12
[    1.603591] Test failed: thread 6 returned: -12
[    1.603595] Test failed: thread 7 returned: -12
[    1.631305] Test failed: thread 8 returned: -12
[    1.632027] BUG: unable to handle kernel paging request at ffff88000ea0befc
[    1.633135] IP: [<ffffffff8111599a>] do_raw_spin_lock+0x2a/0x120
[    1.634101] PGD 55e1067 PUD 55e2067 PMD 1398b067 PTE 800000000ea0b060
[    1.635199] Oops: 0000 [#1] PREEMPT DEBUG_PAGEALLOC
[    1.635935] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc4-00256-g325e2ef #2
[    1.637047] task: ffff880013552600 task.stack: ffff880013558000
[    1.637979] RIP: 0010:[<ffffffff8111599a>]  [<ffffffff8111599a>] do_raw_spin_lock+0x2a/0x120
[    1.639304] RSP: 0000:ffff88001355bd18  EFLAGS: 00010082
[    1.640118] RAX: 0000000000000000 RBX: ffff88000ea0bef8 RCX: 0000000000000000
[    1.641206] RDX: 0000000000000000 RSI: 00000000000005a8 RDI: ffff88000ea0bef8
[    1.642294] RBP: ffff88001355bd30 R08: 0000000000001000 R09: 0000000000000002
[    1.643376] R10: 0000000000000067 R11: 00003ffffffff000 R12: ffff88000ea0bef8
[    1.644453] R13: ffff88000ea0bef0 R14: ffff880013552600 R15: ffff880013552600
[    1.645539] FS:  0000000000000000(0000) GS:ffffffff8304c000(0000) knlGS:0000000000000000
[    1.646755] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    1.647647] CR2: ffff88000ea0befc CR3: 000000000300c000 CR4: 00000000000006b0
[    1.648718] Stack:
[    1.649055]  ffff88000ea0bef8 ffff88000ea0bef8 ffff88000ea0bef0 ffff88001355bd48
[    1.650269]  ffffffff82793ca1 7fffffffffffffff ffff88001355bdb0 ffffffff8278ecf3
[    1.651467]  ffff88001355bd78 0000000000000001 ffff880013552600 ffffffff811007b0
[    1.652679] Call Trace:
[    1.653048]  [<ffffffff82793ca1>] _raw_spin_lock_irq+0x21/0x30
[    1.653944]  [<ffffffff8278ecf3>] wait_for_completion+0x133/0x1c0
[    1.654887]  [<ffffffff811007b0>] ? sched_set_stop_task+0x60/0x60
[    1.655807]  [<ffffffff810f801b>] kthread_stop+0x5b/0xa0
[    1.656623]  [<ffffffff8449d644>] test_rht_init+0xa87/0xb40
[    1.657476]  [<ffffffff8449cbbd>] ? test_kstrtox_init+0xb9b/0xb9b
[    1.658454]  [<ffffffff8446b454>] do_one_initcall+0xd1/0x164
[    1.659328]  [<ffffffff8446b71b>] kernel_init_freeable+0x234/0x2ff
[    1.660288]  [<ffffffff827843da>] kernel_init+0xa/0x110
[    1.661047]  [<ffffffff8279449d>] ret_from_fork+0x1d/0x40
[    1.661869]  [<ffffffff827843d0>] ? rest_init+0x80/0x80
[    1.662667] Code: 00 55 48 89 e5 48 83 ec 18 48 85 ff 48 89 5d e8 4c 89 65 f0 48 89 fb 4c 89 6d f8 75 0e 31 f6 48 c7 c7 c0 8b 0d 83 e8 a6 dd 69 00 <81> 7b 04 ad 4e ad de 74 18 e8 a8 37 63 00 85 c0 74 0f 48 c7 c6 
[    1.666891] RIP  [<ffffffff8111599a>] do_raw_spin_lock+0x2a/0x120
[    1.667830]  RSP <ffff88001355bd18>
[    1.668391] CR2: ffff88000ea0befc
[    1.668904] ---[ end trace fdce1905cee5a7a0 ]---
[    1.669633] Kernel panic - not syncing: Fatal exception
[    1.670390] Kernel Offset: disabled

Elapsed time: 10
qemu-system-x86_64 -enable-kvm -cpu Westmere -kernel /pkg/linux/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/vmlinuz-4.7.0-rc4-00256-g325e2ef -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-ia32-28/bisect_boot-1-yocto-minimal-i386.cgz-x86_64-randconfig-s4-06300257-325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79-20160630-98844-15a2z12-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-06300257 branch=linux-devel/devel-spot-201606300239 commit=325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/vmlinuz-4.7.0-rc4-00256-g325e2ef max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-ia32/yocto-minimal-i386.cgz/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-ia32-28::dhcp drbd.minor_count=8'  -initrd /fs/sdg1/initrd-vm-kbuild-yocto-ia32-28 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdg1/disk0-vm-kbuild-yocto-ia32-28,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-ia32-28 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-ia32-28 -daemonize -display none -monitor null 
