early console in setup code
Probing EDD (edd=off to disable)... ok
early console in extract_kernel
input_data: 0x0000000002b073b1
input_len: 0x00000000008f8c2c
output: 0x0000000001000000
output_len: 0x00000000023cb684
kernel_total_size: 0x000000000242c000
trampoline_32bit: 0x000000000009d000
booted via startup_32()
Physical KASLR using RDTSC...
Virtual KASLR using RDTSC...

Decompressing Linux... Parsing ELF... Performing relocations... done.
Booting the kernel.
[    0.000000] Linux version 5.2.0-rc5-00484-ge0b179b (kbuild@lkp-kbuild04) (gcc version 7.3.0 (Debian 7.3.0-1)) #1 SMP Mon Jun 24 04:53:53 CST 2019
[    0.000000] Command line: ip=::::vm-snb-yocto-ia32-1107::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1107/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-e0-20190625-50441-1x22zkc-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=e0b179bc1ae221107c87e94dd54f0454eedf437e BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/vmlinuz-5.2.0-rc5-00484-ge0b179b max_uptime=1500 RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/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 rcuperf.shutdown=0
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] 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-0x000000007ffdbfff] usable
[    0.000000] BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] printk: debug: ignoring loglevel setting.
[    0.000000] printk: bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000003] kvm-clock: cpu 0, msr 4a001001, primary cpu clock
[    0.000004] kvm-clock: using sched offset of 4506479212 cycles
[    0.011020] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.041716] tsc: Detected 2260.998 MHz processor
[    0.076128] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.089314] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.098681] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.108304] MTRR default type: write-back
[    0.115987] MTRR fixed ranges enabled:
[    0.122472]   00000-9FFFF write-back
[    0.128588]   A0000-BFFFF uncachable
[    0.135399]   C0000-FFFFF write-protect
[    0.143601] MTRR variable ranges enabled:
[    0.151055]   0 base 0080000000 mask FF80000000 uncachable
[    0.162191]   1 disabled
[    0.196122]   2 disabled
[    0.201279]   3 disabled
[    0.205983]   4 disabled
[    0.210254]   5 disabled
[    0.214371]   6 disabled
[    0.219492]   7 disabled
[    0.225029] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Memory KASLR using RDTSC...
[    0.244642] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.255530] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.266411] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.341042] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f]
[    0.352481]   mpc: f6a80-f6b74
[    0.360330] BRK [0x4a201000, 0x4a201fff] PGTABLE
[    0.369338] BRK [0x4a202000, 0x4a202fff] PGTABLE
[    0.378250] BRK [0x4a203000, 0x4a203fff] PGTABLE
[    0.387878] BRK [0x4a204000, 0x4a204fff] PGTABLE
[    0.396952] BRK [0x4a205000, 0x4a205fff] PGTABLE
[    0.408132] BRK [0x4a206000, 0x4a206fff] PGTABLE
[    0.417561] RAMDISK: [mem 0x74872000-0x7ffcffff]
[    0.426700] ACPI: Early table checksum verification disabled
[    0.438690] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.450290] ACPI: RSDT 0x000000007FFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.467170] ACPI: FACP 0x000000007FFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.483701] ACPI: DSDT 0x000000007FFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.500101] ACPI: FACS 0x000000007FFE0000 000040
[    0.509034] ACPI: APIC 0x000000007FFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.525374] ACPI: HPET 0x000000007FFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.541602] ACPI: Local APIC address 0xfee00000
[    0.550394] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.565523] No NUMA configuration found
[    0.572939] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff]
[    0.585712] NODE_DATA(0) allocated [mem 0x74847000-0x74871fff]
[    0.599876] cma: Reserved 200 MiB at 0x0000000068000000
[    0.610117] Zone ranges:
[    0.614874]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.626508]   DMA32    [mem 0x0000000001000000-0x000000007ffdbfff]
[    0.638280]   Normal   empty
[    0.643811]   Device   empty
[    0.649228] Movable zone start for each node
[    0.656976] Early memory node ranges
[    0.664134]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.676284]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.690291] Zeroed struct page in unavailable ranges: 98 pages
[    0.690319] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff]
[    0.715242] On node 0 totalpages: 524154
[    0.722664]   DMA zone: 64 pages used for memmap
[    0.731539]   DMA zone: 21 pages reserved
[    0.739155]   DMA zone: 3998 pages, LIFO batch:0
[    0.748972]   DMA32 zone: 8128 pages used for memmap
[    0.758404]   DMA32 zone: 520156 pages, LIFO batch:63
[    0.788415] ACPI: PM-Timer IO Port: 0x608
[    0.796321] ACPI: Local APIC address 0xfee00000
[    0.804941] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.815210] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.827370] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.838057] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.851049] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.863501] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.877137] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.889615] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.903224] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.916449] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.930613] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.943834] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.957382] ACPI: IRQ0 used by override.
[    0.964641] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.978246] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.991990] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    1.005465] ACPI: IRQ5 used by override.
[    1.012949] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    1.026505] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    1.040268] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    1.054529] ACPI: IRQ9 used by override.
[    1.062432] ACPI: IRQ10 used by override.
[    1.070986] ACPI: IRQ11 used by override.
[    1.079029] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    1.093345] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    1.107444] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    1.122999] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    1.138197] Using ACPI (MADT) for SMP configuration information
[    1.150613] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    1.161592] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    1.171901] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    1.183108] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    1.194913] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    1.208356] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    1.221184] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    1.234930] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    1.247987] Booting paravirtualized kernel on KVM
[    1.260820] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    3.338025] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    3.375263] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
[    3.386338] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
[    3.397811] pcpu-alloc: [0] 0 1 
[    3.403294] KVM setup async PF for cpu 0
[    3.410807] kvm-stealtime: cpu 0, msr 65e2d040
[    3.419326] Built 1 zonelists, mobility grouping on.  Total pages: 515941
[    3.432169] Policy zone: DMA32
[    3.437997] Kernel command line: ip=::::vm-snb-yocto-ia32-1107::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1107/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-e0-20190625-50441-1x22zkc-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=e0b179bc1ae221107c87e94dd54f0454eedf437e BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/vmlinuz-5.2.0-rc5-00484-ge0b179b max_uptime=1500 RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/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 rcuperf.shutdown=0
[    3.599475] sysrq: sysrq always enabled.
[    3.709297] Memory: 261668K/2096616K available (14339K kernel code, 2346K rwdata, 4220K rodata, 2508K init, 5272K bss, 258208K reserved, 204800K cma-reserved)
[    3.737002] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    3.749675] Kernel/User page tables isolation: enabled
[    3.759131] ftrace: allocating 42695 entries in 167 pages
[    3.945635] rcu: Hierarchical RCU implementation.
[    3.954681] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    3.967381] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    3.978918] 	Tasks RCU enabled.
[    3.984847] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    3.999145] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    4.051504] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    4.067288] random: get_random_bytes called from start_kernel+0x2fa/0x4de with crng_init=0
[    4.197263] Console: colour VGA+ 80x25
[    0.000000] Linux version 5.2.0-rc5-00484-ge0b179b (kbuild@lkp-kbuild04) (gcc version 7.3.0 (Debian 7.3.0-1)) #1 SMP Mon Jun 24 04:53:53 CST 2019
[    0.000000] Command line: ip=::::vm-snb-yocto-ia32-1107::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1107/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-e0-20190625-50441-1x22zkc-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=e0b179bc1ae221107c87e94dd54f0454eedf437e BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/vmlinuz-5.2.0-rc5-00484-ge0b179b max_uptime=1500 RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/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 rcuperf.shutdown=0
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] 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-0x000000007ffdbfff] usable
[    0.000000] BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] printk: debug: ignoring loglevel setting.
[    0.000000] printk: bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000003] kvm-clock: cpu 0, msr 4a001001, primary cpu clock
[    0.000004] kvm-clock: using sched offset of 4506479212 cycles
[    0.011020] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.041716] tsc: Detected 2260.998 MHz processor
[    0.076128] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.089314] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.098681] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.108304] MTRR default type: write-back
[    0.115987] MTRR fixed ranges enabled:
[    0.122472]   00000-9FFFF write-back
[    0.128588]   A0000-BFFFF uncachable
[    0.135399]   C0000-FFFFF write-protect
[    0.143601] MTRR variable ranges enabled:
[    0.151055]   0 base 0080000000 mask FF80000000 uncachable
[    0.162191]   1 disabled
[    0.196122]   2 disabled
[    0.201279]   3 disabled
[    0.205983]   4 disabled
[    0.210254]   5 disabled
[    0.214371]   6 disabled
[    0.219492]   7 disabled
[    0.225029] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.244642] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.255530] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.266411] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.341042] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f]
[    0.352481]   mpc: f6a80-f6b74
[    0.360330] BRK [0x4a201000, 0x4a201fff] PGTABLE
[    0.369338] BRK [0x4a202000, 0x4a202fff] PGTABLE
[    0.378250] BRK [0x4a203000, 0x4a203fff] PGTABLE
[    0.387878] BRK [0x4a204000, 0x4a204fff] PGTABLE
[    0.396952] BRK [0x4a205000, 0x4a205fff] PGTABLE
[    0.408132] BRK [0x4a206000, 0x4a206fff] PGTABLE
[    0.417561] RAMDISK: [mem 0x74872000-0x7ffcffff]
[    0.426700] ACPI: Early table checksum verification disabled
[    0.438690] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.450290] ACPI: RSDT 0x000000007FFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.467170] ACPI: FACP 0x000000007FFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.483701] ACPI: DSDT 0x000000007FFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.500101] ACPI: FACS 0x000000007FFE0000 000040
[    0.509034] ACPI: APIC 0x000000007FFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.525374] ACPI: HPET 0x000000007FFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.541602] ACPI: Local APIC address 0xfee00000
[    0.550394] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.565523] No NUMA configuration found
[    0.572939] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff]
[    0.585712] NODE_DATA(0) allocated [mem 0x74847000-0x74871fff]
[    0.599876] cma: Reserved 200 MiB at 0x0000000068000000
[    0.610117] Zone ranges:
[    0.614874]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.626508]   DMA32    [mem 0x0000000001000000-0x000000007ffdbfff]
[    0.638280]   Normal   empty
[    0.643811]   Device   empty
[    0.649228] Movable zone start for each node
[    0.656976] Early memory node ranges
[    0.664134]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.676284]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.690291] Zeroed struct page in unavailable ranges: 98 pages
[    0.690319] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff]
[    0.715242] On node 0 totalpages: 524154
[    0.722664]   DMA zone: 64 pages used for memmap
[    0.731539]   DMA zone: 21 pages reserved
[    0.739155]   DMA zone: 3998 pages, LIFO batch:0
[    0.748972]   DMA32 zone: 8128 pages used for memmap
[    0.758404]   DMA32 zone: 520156 pages, LIFO batch:63
[    0.788415] ACPI: PM-Timer IO Port: 0x608
[    0.796321] ACPI: Local APIC address 0xfee00000
[    0.804941] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.815210] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.827370] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.838057] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.851049] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.863501] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.877137] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.889615] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.903224] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.916449] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.930613] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.943834] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.957382] ACPI: IRQ0 used by override.
[    0.964641] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.978246] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.991990] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    1.005465] ACPI: IRQ5 used by override.
[    1.012949] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    1.026505] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    1.040268] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    1.054529] ACPI: IRQ9 used by override.
[    1.062432] ACPI: IRQ10 used by override.
[    1.070986] ACPI: IRQ11 used by override.
[    1.079029] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    1.093345] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    1.107444] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    1.122999] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    1.138197] Using ACPI (MADT) for SMP configuration information
[    1.150613] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    1.161592] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    1.171901] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    1.183108] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    1.194913] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    1.208356] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    1.221184] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    1.234930] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    1.247987] Booting paravirtualized kernel on KVM
[    1.260820] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    3.338025] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    3.375263] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
[    3.386338] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
[    3.397811] pcpu-alloc: [0] 0 1 
[    3.403294] KVM setup async PF for cpu 0
[    3.410807] kvm-stealtime: cpu 0, msr 65e2d040
[    3.419326] Built 1 zonelists, mobility grouping on.  Total pages: 515941
[    3.432169] Policy zone: DMA32
[    3.437997] Kernel command line: ip=::::vm-snb-yocto-ia32-1107::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1107/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-e0-20190625-50441-1x22zkc-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=e0b179bc1ae221107c87e94dd54f0454eedf437e BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/vmlinuz-5.2.0-rc5-00484-ge0b179b max_uptime=1500 RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/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 rcuperf.shutdown=0
[    3.599475] sysrq: sysrq always enabled.
[    3.709297] Memory: 261668K/2096616K available (14339K kernel code, 2346K rwdata, 4220K rodata, 2508K init, 5272K bss, 258208K reserved, 204800K cma-reserved)
[    3.737002] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    3.749675] Kernel/User page tables isolation: enabled
[    3.759131] ftrace: allocating 42695 entries in 167 pages
[    3.945635] rcu: Hierarchical RCU implementation.
[    3.954681] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    3.967381] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    3.978918] 	Tasks RCU enabled.
[    3.984847] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    3.999145] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    4.051504] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    4.067288] random: get_random_bytes called from start_kernel+0x2fa/0x4de with crng_init=0
[    4.197263] Console: colour VGA+ 80x25
[    7.597673] printk: console [tty0] enabled
[    7.615973] printk: console [ttyS0] enabled
[    7.615973] printk: console [ttyS0] enabled
[    7.637945] printk: bootconsole [earlyser0] disabled
[    7.637945] printk: bootconsole [earlyser0] disabled
[    7.666427] ACPI: Core revision 20190509
[    7.682220] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    7.713295] hpet clockevent registered
[    7.732147] APIC: Switch to symmetric I/O mode setup
[    7.761547] x2apic enabled
[    7.796261] Switched APIC routing to physical x2apic.
[    7.818428] enabled ExtINT on CPU#0
[    7.861887] ENABLING IO-APIC IRQs
[    7.879706] init IO_APIC IRQs
[    7.896394]  apic 0 pin 0 not connected
[    7.916356] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:0)
[    7.954633] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[    7.994713] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:0)
[    8.034572] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:0)
[    8.072553] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:0)
[    8.107161] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:0)
[    8.143653] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:0)
[    8.181386] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:0)
[    8.216578] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:0)
[    8.247168] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:0)
[    8.278373] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:0)
[    8.310558] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:0)
[    8.353300] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:0)
[    8.393261] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:0)
[    8.431660] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:0)
[    8.480228]  apic 0 pin 16 not connected
[    8.504021]  apic 0 pin 17 not connected
[    8.525240]  apic 0 pin 18 not connected
[    8.539602]  apic 0 pin 19 not connected
[    8.556673]  apic 0 pin 20 not connected
[    8.572201]  apic 0 pin 21 not connected
[    8.586821]  apic 0 pin 22 not connected
[    8.602727]  apic 0 pin 23 not connected
[    8.626027] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    8.649712] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[    8.699611] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998)
[    8.700592] pid_max: default: 32768 minimum: 301
[    8.702816] LSM: Security Framework initializing
[    8.703744] Yama: becoming mindful.
[    8.705674] SELinux:  Initializing.
[    8.706871] *** VALIDATE SELinux ***
[    8.712294] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    8.714065] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    8.715744] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    8.716737] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    8.720193] *** VALIDATE proc ***
[    8.721190] *** VALIDATE cgroup1 ***
[    8.721614] *** VALIDATE cgroup2 ***
Poking KASLR using RDTSC...
[    8.733136] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    8.734613] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    8.735648] Spectre V2 : Mitigation: Full generic retpoline
[    8.736610] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    8.737612] Speculative Store Bypass: Vulnerable
[    8.738642] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    8.741581] Freeing SMP alternatives memory: 40K
[    8.760581] TSC deadline timer enabled
[    8.760581] smpboot: CPU0: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1)
[    8.762030] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    8.763230] rcu: Hierarchical SRCU implementation.
[    8.774253] NMI watchdog: Perf NMI watchdog permanently disabled
[    8.775521] smp: Bringing up secondary CPUs ...
[    8.777074] x86: Booting SMP configuration:
[    8.777646] .... node  #0, CPUs:      #1
[    4.589902] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[    4.589902] masked ExtINT on CPU#1
[    8.815001] KVM setup async PF for cpu 1
[    8.815581] kvm-stealtime: cpu 1, msr 65f2d040
[    8.843354] smp: Brought up 1 node, 2 CPUs
[    8.843669] smpboot: Max logical packages: 2
[    8.844644] smpboot: Total of 2 processors activated (9043.99 BogoMIPS)
[    8.903715] node 0 initialised, 342985 pages in 56ms
[    8.920908] devtmpfs: initialized
[    8.922390] x86/mm: Memory block size: 128MB
[    8.933649] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    8.934700] futex hash table entries: 512 (order: 3, 32768 bytes)
[    8.943782] pinctrl core: initialized pinctrl subsystem
[    8.945621] PM: RTC time: 16:18:13, date: 2019-06-25
[    8.948809] NET: Registered protocol family 16
[    8.953203] audit: initializing netlink subsys (disabled)
[    8.954645] audit: type=2000 audit(1561450688.525:1): state=initialized audit_enabled=0 res=1
[    8.976997] cpuidle: using governor menu
[    8.980941] ACPI: bus type PCI registered
[    8.981621] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    8.984331] PCI: Using configuration type 1 for base access
[    9.012877] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    9.075096] ACPI: Added _OSI(Module Device)
[    9.075652] ACPI: Added _OSI(Processor Device)
[    9.076642] ACPI: Added _OSI(3.0 _SCP Extensions)
[    9.077708] ACPI: Added _OSI(Processor Aggregator Device)
[    9.078709] ACPI: Added _OSI(Linux-Dell-Video)
[    9.079644] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    9.080645] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    9.089936] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    9.106897] ACPI: Interpreter enabled
[    9.121912] ACPI: (supports S0 S3 S4 S5)
[    9.134646] ACPI: Using IOAPIC for interrupt routing
[    9.150905] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    9.184389] ACPI: Enabled 2 GPEs in block 00 to 0F
[    9.232581] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    9.248765] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
[    9.269777] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    9.300581] acpiphp: Slot [3] registered
[    9.308905] acpiphp: Slot [4] registered
[    9.321008] acpiphp: Slot [5] registered
[    9.331945] acpiphp: Slot [6] registered
[    9.343910] acpiphp: Slot [7] registered
[    9.354927] acpiphp: Slot [8] registered
[    9.365942] acpiphp: Slot [9] registered
[    9.377903] acpiphp: Slot [10] registered
[    9.389973] acpiphp: Slot [11] registered
[    9.402002] acpiphp: Slot [12] registered
[    9.414031] acpiphp: Slot [13] registered
[    9.424878] acpiphp: Slot [14] registered
[    9.436940] acpiphp: Slot [15] registered
[    9.439898] acpiphp: Slot [16] registered
[    9.448881] acpiphp: Slot [17] registered
[    9.458996] acpiphp: Slot [18] registered
[    9.464027] acpiphp: Slot [19] registered
[    9.477035] acpiphp: Slot [20] registered
[    9.488971] acpiphp: Slot [21] registered
[    9.500965] acpiphp: Slot [22] registered
[    9.512962] acpiphp: Slot [23] registered
[    9.525003] acpiphp: Slot [24] registered
[    9.536871] acpiphp: Slot [25] registered
[    9.549944] acpiphp: Slot [26] registered
[    9.561964] acpiphp: Slot [27] registered
[    9.573939] acpiphp: Slot [28] registered
[    9.585874] acpiphp: Slot [29] registered
[    9.595971] acpiphp: Slot [30] registered
[    9.607898] acpiphp: Slot [31] registered
[    9.619806] PCI host bridge to bus 0000:00
[    9.632673] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    9.650675] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    9.667750] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    9.711800] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
[    9.733704] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[    9.754712] pci_bus 0000:00: root bus resource [bus 00-ff]
[    9.770095] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    9.787581] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    9.806900] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    9.867675] pci 0000:00:01.1: reg 0x20: [io  0xc0c0-0xc0cf]
[    9.896945] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    9.916650] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    9.936654] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    9.958650] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    9.982289] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[   10.004962] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[   10.024868] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[   10.045160] pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x160 took 40039 usecs
[   10.062581] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[   10.096672] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[   10.141682] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[   10.219710] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[   10.240483] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[   10.272680] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[   10.304714] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[   10.383676] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[   10.405976] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[   10.436677] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[   10.482973] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[   10.542741] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[   10.584487] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[   10.588645] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
[   10.592671] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[   10.601701] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[   10.611486] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000
[   10.613646] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f]
[   10.636868] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[   10.639068] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[   10.640846] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[   10.642829] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[   10.644132] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[   10.648202] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[   10.648581] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[   10.680672] pci 0000:00:02.0: vgaarb: bridge control possible
[   10.693644] vgaarb: loaded
[   10.703875] SCSI subsystem initialized
[   10.720941] ACPI: bus type USB registered
[   10.731905] usbcore: registered new interface driver usbfs
[   10.745743] usbcore: registered new interface driver hub
[   10.756944] usbcore: registered new device driver usb
[   10.771004] pps_core: LinuxPPS API ver. 1 registered
[   10.783670] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[   10.804810] PTP clock support registered
[   10.816936] EDAC MC: Ver: 3.0.0
[   10.832909] PCI: Using ACPI for IRQ routing
[   10.845651] PCI: pci_cache_line_size set to 64 bytes
[   10.858201] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[   10.871647] e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff]
[   10.883615] NetLabel: Initializing
[   10.893622] NetLabel:  domain hash size = 128
[   10.905642] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[   10.920844] NetLabel:  unlabeled traffic allowed by default
[   10.941258] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[   10.962647] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[   10.983615] clocksource: Switched to clocksource kvm-clock
[   11.333235] VFS: Disk quotas dquot_6.6.0
[   11.346917] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[   11.369270] *** VALIDATE hugetlbfs ***
[   11.382689] pnp: PnP ACPI init
[   11.394970] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[   11.416133] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[   11.436940] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[   11.456930] pnp 00:03: [dma 2]
[   11.468527] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[   11.490434] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[   11.511958] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[   11.533378] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[   11.556355] pnp: PnP ACPI: found 7 devices
[   11.634285] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[   11.664356] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[   11.684134] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[   11.704711] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[   11.727654] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
[   11.750819] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[   11.774960] NET: Registered protocol family 2
[   11.792202] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes)
[   11.820694] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[   11.847355] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[   11.869107] TCP: Hash tables configured (established 16384 bind 16384)
[   11.891851] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[   11.912848] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[   11.935568] NET: Registered protocol family 1
[   11.966357] RPC: Registered named UNIX socket transport module.
[   11.987283] RPC: Registered udp transport module.
[   12.004074] RPC: Registered tcp transport module.
[   12.021216] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   12.042412] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[   12.062531] pci 0000:00:00.0: quirk_passive_release+0x0/0xa0 took 19744 usecs
[   12.087169] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[   12.107517] pci 0000:00:00.0: quirk_natoma+0x0/0x30 took 19843 usecs
[   12.128965] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[   12.150030] pci 0000:00:01.0: quirk_isa_dma_hangs+0x0/0x30 took 20565 usecs
[   12.175141] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[   12.204343] pci 0000:00:02.0: pci_fixup_video+0x0/0x110 took 29176 usecs
[   12.238918] PCI: CLS 0 bytes, default 64
[   12.263386] Trying to unpack rootfs image as initramfs...
[   56.926273] Freeing initrd memory: 187768K
[   56.973521] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[   57.071305] Initialise system trusted keyrings
[   57.086636] Key type blacklist registered
[   57.102743] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[   57.149369] zbud: loaded
[   57.213703] 9p: Installing v9fs 9p2000 file system support
[   57.338277] NET: Registered protocol family 38
[   57.353094] Key type asymmetric registered
[   57.366989] Asymmetric key parser 'x509' registered
[   57.382872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[   57.409121] io scheduler mq-deadline registered
[   57.425846] io scheduler kyber registered
[   57.441300] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[   57.466347] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[   57.487546] intel_idle: Please enable MWAIT in BIOS SETUP
[   57.503231] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   57.535733] ACPI: Power Button [PWRF]
[   57.549272] ERST DBG: ERST support is disabled.
[   57.920425] PCI Interrupt Link [LNKD] enabled at IRQ 11
[   58.256908] PCI Interrupt Link [LNKA] enabled at IRQ 10
[   58.286657] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[   58.398362] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   58.531519] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   58.568373] Non-volatile memory driver v1.3
[   58.594378] Linux agpgart interface v0.103
[   58.624474] virtio_blk virtio0: [vda] 536870912 512-byte logical blocks (275 GB/256 GiB)
[   58.675165] virtio_blk virtio1: [vdb] 536870912 512-byte logical blocks (275 GB/256 GiB)
[   58.721413] rdac: device handler registered
[   58.751431] hp_sw: device handler registered
[   58.766078] emc: device handler registered
[   58.779902] alua: device handler registered
[   58.794165] MACsec IEEE 802.1AE
[   58.806381] libphy: Fixed MDIO Bus: probed
[   58.821250] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[   58.843925] e1000: Copyright (c) 1999-2006 Intel Corporation.
[   59.152334] PCI Interrupt Link [LNKC] enabled at IRQ 11
[   60.674146] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[   60.693495] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[   60.716246] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[   60.734039] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[   60.752214] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k
[   60.773897] igb: Copyright (c) 2007-2014 Intel Corporation.
[   60.790945] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k
[   60.817203] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[   60.834991] i40e: Intel(R) Ethernet Connection XL710 Network Driver - version 2.8.20-k
[   60.859073] i40e: Copyright (c) 2013 - 2014 Intel Corporation.
[   60.876703] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI
[   60.895660] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[   60.912089] usbcore: registered new interface driver catc
[   60.926250] usbcore: registered new interface driver kaweth
[   60.940729] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver
[   60.961870] usbcore: registered new interface driver pegasus
[   60.976944] usbcore: registered new interface driver rtl8150
[   60.992630] usbcore: registered new interface driver asix
[   61.007857] usbcore: registered new interface driver cdc_ether
[   61.044411] usbcore: registered new interface driver cdc_eem
[   61.060454] usbcore: registered new interface driver dm9601
[   61.075214] usbcore: registered new interface driver smsc75xx
[   61.092999] usbcore: registered new interface driver smsc95xx
[   61.107299] usbcore: registered new interface driver gl620a
[   61.124245] usbcore: registered new interface driver net1080
[   61.142133] usbcore: registered new interface driver plusb
[   61.159892] usbcore: registered new interface driver rndis_host
[   61.177481] usbcore: registered new interface driver cdc_subset
[   61.194300] usbcore: registered new interface driver zaurus
[   61.210049] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[   61.231738] usbcore: registered new interface driver int51x1
[   61.247746] usbcore: registered new interface driver ipheth
[   61.263895] usbcore: registered new interface driver sierra_net
[   61.281507] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   61.300406] ehci-pci: EHCI PCI platform driver
[   61.313916] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   61.331733] ohci-pci: OHCI PCI platform driver
[   61.346126] uhci_hcd: USB Universal Host Controller Interface driver
[   61.364237] usbcore: registered new interface driver usbserial_generic
[   61.383521] usbserial: USB Serial support registered for generic
[   61.401701] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   61.434820] serio: i8042 KBD port at 0x60,0x64 irq 1
[   61.450702] serio: i8042 AUX port at 0x60,0x64 irq 12
[   61.467356] mousedev: PS/2 mouse device common for all mice
[   61.486441] rtc_cmos 00:00: RTC can wake from S4
[   61.495710] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   61.542513] rtc_cmos 00:00: registered as rtc0
[   61.559103] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[   61.592942] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[   61.594323] i6300ESB timer 0000:00:06.0: initialized. heartbeat=30 sec (nowayout=0)
[   61.651884] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[   61.670380] iTCO_vendor_support: vendor-support=0
[   61.689193] intel_pstate: CPU model not supported
[   61.707186] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[   61.719888] hidraw: raw HID events driver (C) Jiri Kosina
[   61.756831] usbcore: registered new interface driver usbhid
[   61.774095] usbhid: USB HID core driver
[   61.789396] drop_monitor: Initializing network drop monitor service
[   61.809164] Initializing XFRM netlink socket
[   61.825070] NET: Registered protocol family 10
[   61.844016] Segment Routing with IPv6
[   61.856045] NET: Registered protocol family 17
[   61.876293] 9pnet: Installing 9P2000 support
[   61.891003] mpls_gso: MPLS GSO support
[   61.911135] mce: Using 10 MCE banks
[   61.926259] IPI shorthand broadcast: enabled
[   61.942334] ... APIC ID:      00000000 (0)
[   61.942718] ... APIC VERSION: 00050014
[   61.942718] 0000000000000000000000000000000000000000000000000000000000000000
[   61.942718] 0000000000000000000000000000000000000000000000000000000000000000
[   61.942718] 0000000000000000000000000000000000000000000000000000000000001000
[   62.043470] number of MP IRQ sources: 15.
[   62.056117] number of IO-APIC #0 registers: 24.
[   62.069270] testing the IO APIC.......................
[   62.084985] IO APIC #0......
[   62.094963] .... register #00: 00000000
[   62.107052] .......    : physical APIC id: 00
[   62.120085] .......    : Delivery Type: 0
[   62.132651] .......    : LTS          : 0
[   62.145648] .... register #01: 00170011
[   62.157837] .......     : max redirection entries: 17
[   62.170925] .......     : PRQ implemented: 0
[   62.184019] .......     : IO APIC version: 11
[   62.197365] .... register #02: 00000000
[   62.213693] .......     : arbitration: 00
[   62.238216] .... IRQ redirection table:
[   62.259740] IOAPIC 0:
[   62.283088]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.321848]  pin01, enabled , edge , high, V(25), IRR(0), S(0), physical, D(01), M(0)
[   62.347128]  pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[   62.372405]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.398456]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.424058]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.450049]  pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.475483]  pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.501310]  pin08, enabled , edge , high, V(25), IRR(0), S(0), physical, D(00), M(0)
[   62.527081]  pin09, enabled , level, high, V(21), IRR(0), S(0), physical, D(01), M(0)
[   62.552551]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.578152]  pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.603522]  pin0c, enabled , edge , high, V(24), IRR(0), S(0), physical, D(00), M(0)
[   62.628932]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.654365]  pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.679900]  pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.705051]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.730355]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.787064]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.812961]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.838978]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.864875]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.890409]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.916081]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   62.941287] IRQ to pin mappings:
[   62.953153] IRQ0 -> 0:2
[   62.963001] IRQ1 -> 0:1
[   62.972654] IRQ3 -> 0:3
[   62.982513] IRQ4 -> 0:4
[   62.992943] IRQ5 -> 0:5
[   63.003018] IRQ6 -> 0:6
[   63.012967] IRQ7 -> 0:7
[   63.022666] IRQ8 -> 0:8
[   63.032898] IRQ9 -> 0:9
[   63.042497] IRQ10 -> 0:10
[   63.052506] IRQ11 -> 0:11
[   63.062655] IRQ12 -> 0:12
[   63.072728] IRQ13 -> 0:13
[   63.082866] IRQ14 -> 0:14
[   63.092917] IRQ15 -> 0:15
[   63.103057] .................................... done.
[   63.124282] sched_clock: Marking stable (58534698442, 4588902921)->(65227582178, -2103980815)
[   63.211318] registered taskstats version 1
[   63.224545] Loading compiled-in X.509 certificates
[   63.297353] Loaded X.509 cert 'Build time autogenerated kernel key: d9b101fb5246aa0bd9c9e35b5c2d843d4c1e49d5'
[   63.330355] zswap: loaded using pool lzo/zbud
[   63.418854] Key type big_key registered
[   63.460723] Key type encrypted registered
[   63.474692] ima: No TPM chip found, activating TPM-bypass!
[   63.491960] ima: Allocated hash algorithm: sha1
[   63.506900] No architecture policies found
[   63.520903] evm: Initialising EVM extended attributes:
[   63.537327] evm: security.selinux
[   63.549192] evm: security.apparmor
[   63.561248] evm: security.ima
[   63.572165] evm: security.capability
[   63.584448] evm: HMAC attrs: 0x1
[   63.612390] PM:   Magic number: 7:846:338
[   63.630502] tty tty59: hash matches
[   63.644470] rtc_cmos 00:00: setting system clock to 2019-06-25T16:19:09 UTC (1561479549)
[   63.719864] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   63.764225] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   63.771078] Sending DHCP requests ., OK
[   63.811123] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[   63.841688] IP-Config: Complete:
[   63.853394]      device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[   63.883111]      host=vm-snb-yocto-ia32-1107, domain=, nis-domain=(none)
[   63.904038]      bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=
[   63.904043]      nameserver0=10.0.2.3
[   63.961469] Freeing unused decrypted memory: 2040K
[   63.985351] Freeing unused kernel image memory: 2508K
[   64.005729] Write protecting the kernel read-only data: 22528k
[   64.044194] Freeing unused kernel image memory: 2012K
[   64.067850] Freeing unused kernel image memory: 1924K
[   64.086195] rodata_test: all tests were successful
[   64.103894] Run /init as init process
Starting udev
[   64.535120] udevd[195]: starting version 3.2.7
[   64.554364] random: udevd: uninitialized urandom read (16 bytes read)
[   64.573554] random: udevd: uninitialized urandom read (16 bytes read)
[   64.593319] random: udevd: uninitialized urandom read (16 bytes read)
[   64.628175] udevd[195]: specified group 'kvm' unknown
[   64.661271] udevd[196]: starting eudev-3.2.7
[   64.836289] udevd[196]: specified group 'kvm' unknown
[   65.489696] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   65.524511] input: PC Speaker as /devices/platform/pcspkr/input/input5
[   65.587370] Floppy drive(s): fd0 is 2.88M AMI BIOS
[   65.671077] parport_pc 00:04: reported by Plug and Play ACPI
[   65.702057] libata version 3.00 loaded.
[   65.763820] FDC 0 is a S82078B
[   65.801929] ata_piix 0000:00:01.1: version 2.13
[   65.830710] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[   65.852160] scsi host0: ata_piix
[   65.914254] scsi host1: ata_piix
[   65.954142] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14
[   65.974338] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15
[   66.165496] ata2.01: NODEV after polling detection
[   66.194829] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[   66.239086] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[   66.525586] ppdev: user-space parallel port driver
[   66.673395] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 0: 0xfd000000 -> 0xfdffffff
[   66.702465] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 2: 0xfebf0000 -> 0xfebf0fff
[   66.729949] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
[   66.775543] Console: switching to colour dummy device 80x25
[   66.789086] [drm] Found bochs VGA, ID 0xb0c0.
[   66.796895] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[   66.811122] [TTM] Zone  kernel: Available graphics memory: 1017350 KiB
[   66.822981] [TTM] Initializing pool allocator
[   66.831505] [TTM] Initializing DMA pool allocator
[   66.854347] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[   66.880488] Error: Driver 'pcspkr' is already registered, aborting...
[   66.894373] fbcon: bochs-drmdrmfb (fb0) is primary device
[   66.905070] Console: switching to colour frame buffer device 128x48
[   66.917430] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[   66.917436] cdrom: Uniform CD-ROM driver Revision: 3.20
[   66.926509] sr 1:0:0:0: Attached scsi CD-ROM sr0
[   66.992979] bochs-drm 0000:00:02.0: fb0: bochs-drmdrmfb frame buffer device
[   68.767683] urandom_read: 4 callbacks suppressed
[   68.767687] random: dd: uninitialized urandom read (512 bytes read)
LKP: HOSTNAME vm-snb-yocto-ia32-1107, MAC 52:54:00:12:34:56, kernel 5.2.0-rc5-00484-ge0b179b 1, serial console /dev/ttyS0
Poky (Yocto Project Reference Distro) 2.7+snapshot vm-snb-yocto-ia32-1107 /dev/ttyS0
[   70.504574] /lkp/lkp/src/bin/run-lkp
[   70.504574] RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/0
[   70.504574] job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1107/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-e0-20190625-50441-1x22zkc-0.yaml
[   70.504574] result_service=raw_upload, RESULT_MNT=/inn/result, RESULT_ROOT=/inn/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/0
[   70.504574] run-job /lkp/jobs/scheduled/vm-snb-yocto-ia32-1107/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-e0-20190625-50441-1x22zkc-0.yaml
[   71.754420] target ucode: 
[   72.610138] spin_lock-torture:--- Start of test: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=3 onoff_holdoff=30
[   72.684312] spin_lock-torture: Creating torture_onoff task
[   72.717466] spin_lock-torture: Creating torture_shuffle task
[   72.785163] spin_lock-torture: torture_onoff task started
[   72.785188] spin_lock-torture: torture_onoff begin holdoff
[   72.797283] 2019-06-25 16:19:17 modprobe locktorture onoff_interval=3 onoff_holdoff=30
[   72.817214] spin_lock-torture: Creating torture_stutter task
[   72.817377] spin_lock-torture: torture_shuffle task started
[   72.817903] spin_lock-torture: Creating lock_torture_writer task
[   72.818091] spin_lock-torture: torture_stutter task started
[   72.818970] spin_lock-torture: lock_torture_writer task started
[   73.033829] spin_lock-torture: Creating lock_torture_writer task
[   73.069084] spin_lock-torture: Creating lock_torture_writer task
[   73.101040] spin_lock-torture: lock_torture_writer task started
[   73.101495] spin_lock-torture: Creating lock_torture_writer task
[   73.151851] spin_lock-torture: lock_torture_writer task started
[   73.190215] spin_lock-torture: lock_torture_writer task started
[   73.231151] spin_lock-torture: Creating lock_torture_stats task
[   73.263019] spin_lock-torture: lock_torture_stats task started
[   74.046368] 2019-06-25 16:19:18 sleep 300
[  102.832258] spin_lock-torture: torture_onoff end holdoff
[  102.912641] Unregister pv shared memory for cpu 0
[  102.947944] smpboot: CPU 0 is now offline
[  109.035024] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  109.055639] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  109.056005] masked ExtINT on CPU#0
[  109.129362] KVM setup async PF for cpu 0
[  109.148449] kvm-stealtime: cpu 0, msr 65e2d040
[  112.262376] Unregister pv shared memory for cpu 1
[  112.302127] smpboot: CPU 1 is now offline
[  118.448227] x86: Booting SMP configuration:
[  118.463846] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  118.518536] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  118.518860] masked ExtINT on CPU#1
[  118.591035] KVM setup async PF for cpu 1
[  118.623368] kvm-stealtime: cpu 1, msr 65f2d040
[  121.872930] Unregister pv shared memory for cpu 1
[  121.919105] smpboot: CPU 1 is now offline
[  127.984287] x86: Booting SMP configuration:
[  128.017892] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  128.059897] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  128.060183] masked ExtINT on CPU#1
[  128.109341] KVM setup async PF for cpu 1
[  128.126197] kvm-stealtime: cpu 1, msr 65f2d040
[  131.200249] Unregister pv shared memory for cpu 1
[  131.247301] smpboot: CPU 1 is now offline
[  133.552304] Writes:  Total: 12222405  Max/Min: 0/0   Fail: 0 
[  134.320237] x86: Booting SMP configuration:
[  134.338379] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  134.382023] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  134.382282] masked ExtINT on CPU#1
[  134.430412] KVM setup async PF for cpu 1
[  134.455593] kvm-stealtime: cpu 1, msr 65f2d040
[  137.550208] Unregister pv shared memory for cpu 1
[  137.601372] smpboot: CPU 1 is now offline
[  140.656732] x86: Booting SMP configuration:
[  140.676667] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  140.722492] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  140.722720] masked ExtINT on CPU#1
[  140.767131] KVM setup async PF for cpu 1
[  140.783011] kvm-stealtime: cpu 1, msr 65f2d040
[  143.879865] Unregister pv shared memory for cpu 1
[  143.916718] smpboot: CPU 1 is now offline
[  146.994178] x86: Booting SMP configuration:
[  147.016286] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  147.056024] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  147.056284] masked ExtINT on CPU#1
[  147.112447] KVM setup async PF for cpu 1
[  147.130559] kvm-stealtime: cpu 1, msr 65f2d040
[  150.209027] Unregister pv shared memory for cpu 1
[  150.230099] smpboot: CPU 1 is now offline
[  165.360218] x86: Booting SMP configuration:
[  165.389408] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  165.423984] random: fast init done
[  165.440877] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  165.441133] masked ExtINT on CPU#1
[  165.486504] KVM setup async PF for cpu 1
[  165.502023] kvm-stealtime: cpu 1, msr 65f2d040
[  168.573394] Unregister pv shared memory for cpu 0
[  168.613222] smpboot: CPU 0 is now offline
[  174.704375] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  174.737427] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  175.761614] WARNING: CPU: 0 PID: 0 at arch/x86/kernel/apic/apic.c:1473 setup_local_APIC+0x250/0x3b0
[  175.809476] Modules linked in: locktorture torture sr_mod snd_pcm cdrom bochs_drm ttm snd_timer ata_generic ppdev snd drm_kms_helper pata_acpi syscopyarea sysfillrect sysimgblt fb_sys_fops soundcore joydev crc32c_intel ata_piix parport_pc libata drm serio_raw pcspkr floppy i2c_piix4 parport
[  175.857595] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.2.0-rc5-00484-ge0b179b #1
[  175.870468] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[  175.886344] RIP: 0010:setup_local_APIC+0x250/0x3b0
[  175.895187] Code: 30 8b 0d eb 7a 7d 01 85 c9 74 26 0f 31 c1 e1 0a 48 c1 e2 20 41 89 ce 4c 03 34 24 48 09 d0 49 29 c6 4d 85 f6 0f 8f 74 fe ff ff <0f> 0b e9 22 ff ff ff 49 83 ee 01 eb ea a9 00 00 01 00 8b 15 2c cc
[  175.935860] RSP: 0000:ffffffff89003ee0 EFLAGS: 00010086
[  175.946711] RAX: 0000006090f9d45a RBX: 0000000000000020 RCX: 000000008a001800
[  175.960073] RDX: 0000006000000000 RSI: 0000000000000020 RDI: ffffffff89003ef0
[  175.976040] RBP: 0000000000000000 R08: ffffffff8994ade2 R09: 0000000000000033
[  175.990364] R10: ffffffff89003ee8 R11: 0000000000000000 R12: 00000000000000f0
[  176.001973] R13: 0000000000001000 R14: fffffffffffe3bf9 R15: 0000000000000000
[  176.015478] FS:  0000000000000000(0000) GS:ffff9d21e5e00000(0000) knlGS:0000000000000000
[  176.030653] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  176.040400] CR2: 0000000058501184 CR3: 000000004940a000 CR4: 00000000000006f0
[  176.052585] Call Trace:
[  176.057093]  apic_ap_setup+0xa/0x20
[  176.065049]  start_secondary+0x78/0x1e0
[  176.071665]  secondary_startup_64+0xb6/0xc0
[  176.079015] ---[ end trace b1393cf5f3ef0b63 ]---
[  176.087508] masked ExtINT on CPU#0
[  176.143251] KVM setup async PF for cpu 0
[  176.168851] kvm-stealtime: cpu 0, msr 65e2d040
[  179.266899] Unregister pv shared memory for cpu 1
[  179.302030] smpboot: CPU 1 is now offline
[  193.967718] Writes:  Total: 24338598  Max/Min: 0/0   Fail: 0 
[  194.418250] x86: Booting SMP configuration:
[  194.440019] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  194.488400] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  194.488660] masked ExtINT on CPU#1
[  194.542313] KVM setup async PF for cpu 1
[  194.557320] kvm-stealtime: cpu 1, msr 65f2d040
[  197.635888] Unregister pv shared memory for cpu 1
[  197.683870] smpboot: CPU 1 is now offline
[  200.752165] x86: Booting SMP configuration:
[  200.769438] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  200.811466] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  200.811721] masked ExtINT on CPU#1
[  200.852363] KVM setup async PF for cpu 1
[  200.870764] kvm-stealtime: cpu 1, msr 65f2d040
[  203.975724] Unregister pv shared memory for cpu 0
[  204.007961] smpboot: CPU 0 is now offline
[  237.168210] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  237.214632] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  237.214988] masked ExtINT on CPU#0
[  237.287985] KVM setup async PF for cpu 0
[  237.309923] kvm-stealtime: cpu 0, msr 65e2d040
[  240.383819] Unregister pv shared memory for cpu 0
[  240.409144] smpboot: CPU 0 is now offline
[  249.520229] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  249.565958] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  249.566213] masked ExtINT on CPU#0
[  249.623472] KVM setup async PF for cpu 0
[  249.642865] kvm-stealtime: cpu 0, msr 65e2d040
[  252.743638] Unregister pv shared memory for cpu 1
[  252.783713] smpboot: CPU 1 is now offline
[  254.066124] Writes:  Total: 37301937  Max/Min: 0/0   Fail: 0 
[  255.856224] x86: Booting SMP configuration:
[  255.899068] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  255.938853] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  255.939106] masked ExtINT on CPU#1
[  255.978249] KVM setup async PF for cpu 1
[  255.991887] kvm-stealtime: cpu 1, msr 65f2d040
[  259.081337] Unregister pv shared memory for cpu 0
[  259.128906] smpboot: CPU 0 is now offline
[  262.256200] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  262.318063] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  262.318318] masked ExtINT on CPU#0
[  262.406833] KVM setup async PF for cpu 0
[  262.423354] kvm-stealtime: cpu 0, msr 65e2d040
[  265.546500] Unregister pv shared memory for cpu 1
[  265.603516] smpboot: CPU 1 is now offline
[  266.619078] hrtimer: interrupt took 2944615 ns
[  268.656855] x86: Booting SMP configuration:
[  268.693970] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  268.732891] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  268.733733] masked ExtINT on CPU#1
[  268.778487] KVM setup async PF for cpu 1
[  268.802278] kvm-stealtime: cpu 1, msr 65f2d040
[  271.941424] Unregister pv shared memory for cpu 1
[  271.992823] smpboot: CPU 1 is now offline
[  281.072172] x86: Booting SMP configuration:
[  281.128714] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  281.200901] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  281.201128] masked ExtINT on CPU#1
[  281.243519] KVM setup async PF for cpu 1
[  281.260501] kvm-stealtime: cpu 1, msr 65f2d040
[  284.354420] Unregister pv shared memory for cpu 1
[  284.388219] smpboot: CPU 1 is now offline
[  302.512219] x86: Booting SMP configuration:
[  302.545912] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  302.581881] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  302.582135] masked ExtINT on CPU#1
[  302.623325] KVM setup async PF for cpu 1
[  302.639626] kvm-stealtime: cpu 1, msr 65f2d040
[  305.725932] Unregister pv shared memory for cpu 1
[  305.777017] smpboot: CPU 1 is now offline
[  314.903155] x86: Booting SMP configuration:
[  314.931411] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  314.952378] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  314.952630] masked ExtINT on CPU#1
[  315.003112] KVM setup async PF for cpu 1
[  315.014089] kvm-stealtime: cpu 1, msr 65f2d040
[  316.851202] Writes:  Total: 50091686  Max/Min: 0/0   Fail: 0 
[  318.091603] Unregister pv shared memory for cpu 1
[  318.143670] smpboot: CPU 1 is now offline
[  333.232223] x86: Booting SMP configuration:
[  333.264695] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  333.305509] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  333.305802] masked ExtINT on CPU#1
[  333.347248] KVM setup async PF for cpu 1
[  333.363090] kvm-stealtime: cpu 1, msr 65f2d040
[  336.474974] Unregister pv shared memory for cpu 1
[  336.522864] smpboot: CPU 1 is now offline
[  339.568205] x86: Booting SMP configuration:
[  339.599051] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  339.639018] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  339.639270] masked ExtINT on CPU#1
[  339.680006] KVM setup async PF for cpu 1
[  339.695180] kvm-stealtime: cpu 1, msr 65f2d040
[  342.782618] Unregister pv shared memory for cpu 0
[  342.833420] smpboot: CPU 0 is now offline
[  345.904236] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  345.944220] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  345.944504] masked ExtINT on CPU#0
[  346.035962] KVM setup async PF for cpu 0
[  346.062495] kvm-stealtime: cpu 0, msr 65e2d040
[  349.118241] Unregister pv shared memory for cpu 0
[  349.167559] smpboot: CPU 0 is now offline
[  355.248204] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  355.269603] kvm-clock: cpu 0, msr 4a001001, secondary cpu clock
[  355.269926] masked ExtINT on CPU#0
[  355.321142] KVM setup async PF for cpu 0
[  355.339549] kvm-stealtime: cpu 0, msr 65e2d040
[  358.394967] Unregister pv shared memory for cpu 1
[  358.468331] smpboot: CPU 1 is now offline
[  367.538486] x86: Booting SMP configuration:
[  367.562988] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  367.606306] kvm-clock: cpu 1, msr 4a001041, secondary cpu clock
[  367.606532] masked ExtINT on CPU#1
[  367.648715] KVM setup async PF for cpu 1
[  367.664207] kvm-stealtime: cpu 1, msr 65f2d040
[  370.767450] Unregister pv shared memory for cpu 0
[  370.810034] smpboot: CPU 0 is now offline
[  373.543041] spin_lock-torture: Stopping torture_shuffle task
[  373.562350] spin_lock-torture: Stopping torture_shuffle
[  373.580691] spin_lock-torture: Stopping torture_stutter
[  373.599356] spin_lock-torture: Stopping lock_torture_writer
[  373.614753] spin_lock-torture: Stopping lock_torture_writer
[  373.631406] spin_lock-torture: Stopping lock_torture_writer
[  373.657577] spin_lock-torture: Stopping torture_stutter task
[  373.684741] spin_lock-torture: Stopping lock_torture_writer
[  373.711501] spin_lock-torture: Stopping torture_onoff task
[  373.729940] spin_lock-torture: Stopping torture_onoff
[  373.750133] 2019-06-25 16:24:18 rmmod locktorture
[  373.776831] spin_lock-torture: Stopping lock_torture_writer task
[  373.801268] spin_lock-torture: Stopping lock_torture_writer task
[  373.819030] spin_lock-torture: Stopping lock_torture_writer task
[  373.837505] spin_lock-torture: Stopping lock_torture_writer task
[  373.854930] spin_lock-torture: Stopping lock_torture_stats task
[  373.872104] Writes:  Total: 61258320  Max/Min: 0/0   Fail: 0 
[  373.890874] spin_lock-torture: Stopping lock_torture_stats
[  373.910966] Writes:  Total: 61258320  Max/Min: 0/0   Fail: 0 
[  373.925705] spin_lock-torture:--- End of test: SUCCESS: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=3 onoff_holdoff=30
[  381.442188] sysrq: Emergency Sync
[  381.457021] Emergency Sync complete
[  381.472972] sysrq: Resetting

Elapsed time: 380

qemu-img create -f qcow2 disk-vm-snb-yocto-ia32-1107-0 256G
qemu-img create -f qcow2 disk-vm-snb-yocto-ia32-1107-1 256G

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-cpu SandyBridge
	-kernel $kernel
	-initrd initrd-vm-snb-yocto-ia32-1107
	-m 2048
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=disk-vm-snb-yocto-ia32-1107-0,media=disk,if=virtio
	-drive file=disk-vm-snb-yocto-ia32-1107-1,media=disk,if=virtio
	-serial stdio
	-display none
	-monitor null
)

append=(
	ip=::::vm-snb-yocto-ia32-1107::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-rhel-7.6
	branch=tip/WIP.x86/ipi
	commit=e0b179bc1ae221107c87e94dd54f0454eedf437e
	BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/vmlinuz-5.2.0-rc5-00484-ge0b179b
	max_uptime=1500
	RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/e0b179bc1ae221107c87e94dd54f0454eedf437e/0
	result_service=tmpfs
	debug
	apic=debug
	sysrq_always_enabled
	rcupdate.rcu_cpu_stall_timeout=100
	net.ifnames=0
	printk.devkmsg=on
	panic=-1
	softlockup_panic=1
	nmi_watchdog=panic
	oops=panic
	load_ramdisk=2
	prompt_ramdisk=0
	drbd.minor_count=8
	systemd.log_level=err
	ignore_loglevel
	console=tty0
	earlyprintk=ttyS0,115200
	console=ttyS0,115200
	vga=normal
	rw
	rcuperf.shutdown=0
)

"${kvm[@]}" -append "${append[*]}"
