early console in setup code
Probing EDD (edd=off to disable)... ok
early console in extract_kernel
input_data: 0x0000000002b073b1
input_len: 0x00000000008f8cc4
output: 0x0000000001000000
output_len: 0x00000000023cb680
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-00489-g4f3f6d6 (kbuild@lkp-kbuild14) (gcc version 7.3.0 (Debian 7.3.0-1)) #1 SMP Wed Jun 26 08:11:27 CST 2019
[    0.000000] Command line: ip=::::vm-snb-yocto-ia32-1127::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1127/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-4f-20190626-27266-f1pjha-wait_kernel-1.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/vmlinuz-5.2.0-rc5-00489-g4f3f6d6 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/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/1 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.shutdown
[    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.000004] kvm-clock: cpu 0, msr 29201001, primary cpu clock
[    0.000004] kvm-clock: using sched offset of 4707484842 cycles
[    0.013033] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.049708] tsc: Detected 2260.998 MHz processor
[    0.084386] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.099618] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.112156] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.124477] MTRR default type: write-back
[    0.133515] MTRR fixed ranges enabled:
[    0.142036]   00000-9FFFF write-back
[    0.150136]   A0000-BFFFF uncachable
[    0.158115]   C0000-FFFFF write-protect
[    0.166792] MTRR variable ranges enabled:
[    0.175932]   0 base 0080000000 mask FF80000000 uncachable
[    0.188182]   1 disabled
[    0.193947]   2 disabled
[    0.199557]   3 disabled
[    0.205387]   4 disabled
[    0.211093]   5 disabled
[    0.216470]   6 disabled
[    0.221459]   7 disabled
[    0.226549] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Memory KASLR using RDTSC...
[    0.245310] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.255204] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.265598] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.346422] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f]
[    0.357209]   mpc: f6a80-f6b74
[    0.364858] BRK [0x29401000, 0x29401fff] PGTABLE
[    0.374263] BRK [0x29402000, 0x29402fff] PGTABLE
[    0.384655] BRK [0x29403000, 0x29403fff] PGTABLE
[    0.395522] BRK [0x29404000, 0x29404fff] PGTABLE
[    0.405952] BRK [0x29405000, 0x29405fff] PGTABLE
[    0.418724] BRK [0x29406000, 0x29406fff] PGTABLE
[    0.429703] RAMDISK: [mem 0x74a67000-0x7ffcffff]
[    0.440405] ACPI: Early table checksum verification disabled
[    0.454014] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.466935] ACPI: RSDT 0x000000007FFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.486255] ACPI: FACP 0x000000007FFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.505437] ACPI: DSDT 0x000000007FFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.524531] ACPI: FACS 0x000000007FFE0000 000040
[    0.535223] ACPI: APIC 0x000000007FFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.554247] ACPI: HPET 0x000000007FFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.573394] ACPI: Local APIC address 0xfee00000
[    0.583792] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.601233] No NUMA configuration found
[    0.609912] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff]
[    0.624914] NODE_DATA(0) allocated [mem 0x74a3c000-0x74a66fff]
[    0.640897] cma: Reserved 200 MiB at 0x0000000068000000
[    0.652791] Zone ranges:
[    0.658389]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.672199]   DMA32    [mem 0x0000000001000000-0x000000007ffdbfff]
[    0.685321]   Normal   empty
[    0.691325]   Device   empty
[    0.696964] Movable zone start for each node
[    0.705674] Early memory node ranges
[    0.712966]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.724864]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.739439] Zeroed struct page in unavailable ranges: 98 pages
[    0.739446] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff]
[    0.764949] On node 0 totalpages: 524154
[    0.773052]   DMA zone: 64 pages used for memmap
[    0.782537]   DMA zone: 21 pages reserved
[    0.790482]   DMA zone: 3998 pages, LIFO batch:0
[    0.800307]   DMA32 zone: 8128 pages used for memmap
[    0.810795]   DMA32 zone: 520156 pages, LIFO batch:63
[    0.843210] ACPI: PM-Timer IO Port: 0x608
[    0.851604] ACPI: Local APIC address 0xfee00000
[    0.861060] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.873502] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.887571] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.900467] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.915118] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.928594] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.943184] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.956561] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.971400] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.985109] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.999892] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    1.014115] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    1.028667] ACPI: IRQ0 used by override.
[    1.036892] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    1.052295] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    1.066349] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    1.081132] ACPI: IRQ5 used by override.
[    1.089455] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    1.103138] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    1.116612] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    1.130384] ACPI: IRQ9 used by override.
[    1.138204] ACPI: IRQ10 used by override.
[    1.146208] ACPI: IRQ11 used by override.
[    1.154239] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    1.168503] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    1.183053] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    1.197725] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    1.212016] Using ACPI (MADT) for SMP configuration information
[    1.224075] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    1.233955] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    1.243417] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    1.254826] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    1.268436] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    1.280457] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    1.293038] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    1.305645] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    1.316363] Booting paravirtualized kernel on KVM
[    1.326059] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    3.530005] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    3.569472] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
[    3.582440] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
[    3.595041] pcpu-alloc: [0] 0 1 
[    3.601584] KVM setup async PF for cpu 0
[    3.609531] kvm-stealtime: cpu 0, msr 7482d040
[    3.618996] Built 1 zonelists, mobility grouping on.  Total pages: 515941
[    3.633115] Policy zone: DMA32
[    3.639206] Kernel command line: ip=::::vm-snb-yocto-ia32-1127::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1127/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-4f-20190626-27266-f1pjha-wait_kernel-1.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/vmlinuz-5.2.0-rc5-00489-g4f3f6d6 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/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/1 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.s
[    3.642680] sysrq: sysrq always enabled.
[    3.959221] Memory: 261668K/2096616K available (14339K kernel code, 2346K rwdata, 4224K rodata, 2508K init, 5272K bss, 256204K reserved, 204800K cma-reserved)
[    3.991567] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    4.005044] Kernel/User page tables isolation: enabled
[    4.015542] ftrace: allocating 42695 entries in 167 pages
[    4.217533] rcu: Hierarchical RCU implementation.
[    4.227117] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    4.240836] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    4.255644] 	Tasks RCU enabled.
[    4.262055] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    4.275799] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    4.325860] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    4.340639] random: get_random_bytes called from start_kernel+0x2fa/0x4de with crng_init=0
[    4.482867] Console: colour VGA+ 80x25
[    0.000000] Linux version 5.2.0-rc5-00489-g4f3f6d6 (kbuild@lkp-kbuild14) (gcc version 7.3.0 (Debian 7.3.0-1)) #1 SMP Wed Jun 26 08:11:27 CST 2019
[    0.000000] Command line: ip=::::vm-snb-yocto-ia32-1127::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1127/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-4f-20190626-27266-f1pjha-wait_kernel-1.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/vmlinuz-5.2.0-rc5-00489-g4f3f6d6 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/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/1 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.shutdown
[    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.000004] kvm-clock: cpu 0, msr 29201001, primary cpu clock
[    0.000004] kvm-clock: using sched offset of 4707484842 cycles
[    0.013033] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.049708] tsc: Detected 2260.998 MHz processor
[    0.084386] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.099618] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.112156] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.124477] MTRR default type: write-back
[    0.133515] MTRR fixed ranges enabled:
[    0.142036]   00000-9FFFF write-back
[    0.150136]   A0000-BFFFF uncachable
[    0.158115]   C0000-FFFFF write-protect
[    0.166792] MTRR variable ranges enabled:
[    0.175932]   0 base 0080000000 mask FF80000000 uncachable
[    0.188182]   1 disabled
[    0.193947]   2 disabled
[    0.199557]   3 disabled
[    0.205387]   4 disabled
[    0.211093]   5 disabled
[    0.216470]   6 disabled
[    0.221459]   7 disabled
[    0.226549] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.245310] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.255204] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.265598] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.346422] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f]
[    0.357209]   mpc: f6a80-f6b74
[    0.364858] BRK [0x29401000, 0x29401fff] PGTABLE
[    0.374263] BRK [0x29402000, 0x29402fff] PGTABLE
[    0.384655] BRK [0x29403000, 0x29403fff] PGTABLE
[    0.395522] BRK [0x29404000, 0x29404fff] PGTABLE
[    0.405952] BRK [0x29405000, 0x29405fff] PGTABLE
[    0.418724] BRK [0x29406000, 0x29406fff] PGTABLE
[    0.429703] RAMDISK: [mem 0x74a67000-0x7ffcffff]
[    0.440405] ACPI: Early table checksum verification disabled
[    0.454014] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.466935] ACPI: RSDT 0x000000007FFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.486255] ACPI: FACP 0x000000007FFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.505437] ACPI: DSDT 0x000000007FFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.524531] ACPI: FACS 0x000000007FFE0000 000040
[    0.535223] ACPI: APIC 0x000000007FFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.554247] ACPI: HPET 0x000000007FFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.573394] ACPI: Local APIC address 0xfee00000
[    0.583792] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.601233] No NUMA configuration found
[    0.609912] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff]
[    0.624914] NODE_DATA(0) allocated [mem 0x74a3c000-0x74a66fff]
[    0.640897] cma: Reserved 200 MiB at 0x0000000068000000
[    0.652791] Zone ranges:
[    0.658389]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.672199]   DMA32    [mem 0x0000000001000000-0x000000007ffdbfff]
[    0.685321]   Normal   empty
[    0.691325]   Device   empty
[    0.696964] Movable zone start for each node
[    0.705674] Early memory node ranges
[    0.712966]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.724864]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.739439] Zeroed struct page in unavailable ranges: 98 pages
[    0.739446] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff]
[    0.764949] On node 0 totalpages: 524154
[    0.773052]   DMA zone: 64 pages used for memmap
[    0.782537]   DMA zone: 21 pages reserved
[    0.790482]   DMA zone: 3998 pages, LIFO batch:0
[    0.800307]   DMA32 zone: 8128 pages used for memmap
[    0.810795]   DMA32 zone: 520156 pages, LIFO batch:63
[    0.843210] ACPI: PM-Timer IO Port: 0x608
[    0.851604] ACPI: Local APIC address 0xfee00000
[    0.861060] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.873502] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.887571] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.900467] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.915118] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.928594] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.943184] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.956561] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.971400] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.985109] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.999892] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    1.014115] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    1.028667] ACPI: IRQ0 used by override.
[    1.036892] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    1.052295] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    1.066349] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    1.081132] ACPI: IRQ5 used by override.
[    1.089455] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    1.103138] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    1.116612] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    1.130384] ACPI: IRQ9 used by override.
[    1.138204] ACPI: IRQ10 used by override.
[    1.146208] ACPI: IRQ11 used by override.
[    1.154239] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    1.168503] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    1.183053] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    1.197725] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    1.212016] Using ACPI (MADT) for SMP configuration information
[    1.224075] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    1.233955] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    1.243417] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    1.254826] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    1.268436] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    1.280457] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    1.293038] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    1.305645] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    1.316363] Booting paravirtualized kernel on KVM
[    1.326059] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    3.530005] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    3.569472] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
[    3.582440] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
[    3.595041] pcpu-alloc: [0] 0 1 
[    3.601584] KVM setup async PF for cpu 0
[    3.609531] kvm-stealtime: cpu 0, msr 7482d040
[    3.618996] Built 1 zonelists, mobility grouping on.  Total pages: 515941
[    3.633115] Policy zone: DMA32
[    3.639206] Kernel command line: ip=::::vm-snb-yocto-ia32-1127::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1127/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-4f-20190626-27266-f1pjha-wait_kernel-1.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=tip/WIP.x86/ipi commit=4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/vmlinuz-5.2.0-rc5-00489-g4f3f6d6 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/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/1 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.s
[    3.642680] sysrq: sysrq always enabled.
[    3.959221] Memory: 261668K/2096616K available (14339K kernel code, 2346K rwdata, 4224K rodata, 2508K init, 5272K bss, 256204K reserved, 204800K cma-reserved)
[    3.991567] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    4.005044] Kernel/User page tables isolation: enabled
[    4.015542] ftrace: allocating 42695 entries in 167 pages
[    4.217533] rcu: Hierarchical RCU implementation.
[    4.227117] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    4.240836] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    4.255644] 	Tasks RCU enabled.
[    4.262055] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    4.275799] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    4.325860] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    4.340639] random: get_random_bytes called from start_kernel+0x2fa/0x4de with crng_init=0
[    4.482867] Console: colour VGA+ 80x25
[    7.785886] printk: console [tty0] enabled
[    7.800469] printk: console [ttyS0] enabled
[    7.800469] printk: console [ttyS0] enabled
[    7.822798] printk: bootconsole [earlyser0] disabled
[    7.822798] printk: bootconsole [earlyser0] disabled
[    7.848526] ACPI: Core revision 20190509
[    7.864667] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    7.895549] hpet clockevent registered
[    7.912812] APIC: Switch to symmetric I/O mode setup
[    7.940221] x2apic enabled
[    7.961035] Switched APIC routing to physical x2apic.
[    7.980570] enabled ExtINT on CPU#0
[    8.011229] ENABLING IO-APIC IRQs
[    8.025868] init IO_APIC IRQs
[    8.038373]  apic 0 pin 0 not connected
[    8.053571] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:0)
[    8.093887] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[    8.139484] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:0)
[    8.169404] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:0)
[    8.209653] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:0)
[    8.252036] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:0)
[    8.284856] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:0)
[    8.318875] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:0)
[    8.353171] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:0)
[    8.401806] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:0)
[    8.436939] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:0)
[    8.472467] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:0)
[    8.509895] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:0)
[    8.551724] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:0)
[    8.590320] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:0)
[    8.658021]  apic 0 pin 16 not connected
[    8.680429]  apic 0 pin 17 not connected
[    8.717522]  apic 0 pin 18 not connected
[    8.741111]  apic 0 pin 19 not connected
[    8.758571]  apic 0 pin 20 not connected
[    8.776795]  apic 0 pin 21 not connected
[    8.795898]  apic 0 pin 22 not connected
[    8.812498]  apic 0 pin 23 not connected
[    8.834141] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    8.856838] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[    8.897210] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998)
[    8.898234] pid_max: default: 32768 minimum: 301
[    8.900532] LSM: Security Framework initializing
[    8.902454] Yama: becoming mindful.
[    8.903305] SELinux:  Initializing.
[    8.904432] *** VALIDATE SELinux ***
[    8.911244] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    8.914655] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    8.915394] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    8.916274] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    8.919432] *** VALIDATE proc ***
[    8.920689] *** VALIDATE cgroup1 ***
[    8.921235] *** VALIDATE cgroup2 ***
Poking KASLR using RDTSC...
[    8.930648] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    8.931213] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    8.932288] Spectre V2 : Mitigation: Full generic retpoline
[    8.933232] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    8.934235] Speculative Store Bypass: Vulnerable
[    8.935242] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    8.939240] Freeing SMP alternatives memory: 40K
[    8.963722] TSC deadline timer enabled
[    8.964202] smpboot: CPU0: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1)
[    8.965296] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    8.966631] rcu: Hierarchical SRCU implementation.
[    8.977168] NMI watchdog: Perf NMI watchdog permanently disabled
[    8.977874] smp: Bringing up secondary CPUs ...
[    8.979598] x86: Booting SMP configuration:
[    8.980277] .... node  #0, CPUs:      #1
[    4.523955] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[    4.523955] masked ExtINT on CPU#1
[    9.023500] KVM setup async PF for cpu 1
[    9.024202] kvm-stealtime: cpu 1, msr 7492d040
[    9.051910] smp: Brought up 1 node, 2 CPUs
[    9.052268] smpboot: Max logical packages: 2
[    9.053264] smpboot: Total of 2 processors activated (9043.99 BogoMIPS)
[    9.125387] node 0 initialised, 343486 pages in 69ms
[    9.149303] devtmpfs: initialized
[    9.151072] x86/mm: Memory block size: 128MB
[    9.161243] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    9.162299] futex hash table entries: 512 (order: 3, 32768 bytes)
[    9.174174] pinctrl core: initialized pinctrl subsystem
[    9.175262] PM: RTC time: 09:04:53, date: 2019-06-26
[    9.178140] NET: Registered protocol family 16
[    9.181945] audit: initializing netlink subsys (disabled)
[    9.182682] audit: type=2000 audit(1561511088.525:1): state=initialized audit_enabled=0 res=1
[    9.203302] cpuidle: using governor menu
[    9.218464] ACPI: bus type PCI registered
[    9.219242] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    9.221626] PCI: Using configuration type 1 for base access
[    9.250715] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    9.309981] ACPI: Added _OSI(Module Device)
[    9.310304] ACPI: Added _OSI(Processor Device)
[    9.311261] ACPI: Added _OSI(3.0 _SCP Extensions)
[    9.312259] ACPI: Added _OSI(Processor Aggregator Device)
[    9.313267] ACPI: Added _OSI(Linux-Dell-Video)
[    9.314267] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    9.315266] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    9.324550] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    9.332533] ACPI: Interpreter enabled
[    9.343451] ACPI: (supports S0 S3 S4 S5)
[    9.353267] ACPI: Using IOAPIC for interrupt routing
[    9.366580] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    9.394752] ACPI: Enabled 2 GPEs in block 00 to 0F
[    9.438725] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    9.454299] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
[    9.474497] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    9.508051] acpiphp: Slot [3] registered
[    9.518555] acpiphp: Slot [4] registered
[    9.530468] acpiphp: Slot [5] registered
[    9.541556] acpiphp: Slot [6] registered
[    9.553659] acpiphp: Slot [7] registered
[    9.565589] acpiphp: Slot [8] registered
[    9.577487] acpiphp: Slot [9] registered
[    9.589491] acpiphp: Slot [10] registered
[    9.600595] acpiphp: Slot [11] registered
[    9.611560] acpiphp: Slot [12] registered
[    9.623532] acpiphp: Slot [13] registered
[    9.635528] acpiphp: Slot [14] registered
[    9.647616] acpiphp: Slot [15] registered
[    9.659469] acpiphp: Slot [16] registered
[    9.671564] acpiphp: Slot [17] registered
[    9.683487] acpiphp: Slot [18] registered
[    9.694490] acpiphp: Slot [19] registered
[    9.705493] acpiphp: Slot [20] registered
[    9.717594] acpiphp: Slot [21] registered
[    9.730493] acpiphp: Slot [22] registered
[    9.741557] acpiphp: Slot [23] registered
[    9.752528] acpiphp: Slot [24] registered
[    9.763531] acpiphp: Slot [25] registered
[    9.775503] acpiphp: Slot [26] registered
[    9.787530] acpiphp: Slot [27] registered
[    9.798490] acpiphp: Slot [28] registered
[    9.811472] acpiphp: Slot [29] registered
[    9.814623] acpiphp: Slot [30] registered
[    9.824536] acpiphp: Slot [31] registered
[    9.835492] PCI host bridge to bus 0000:00
[    9.846298] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    9.859301] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    9.875333] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    9.918306] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
[    9.941363] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[    9.963274] pci_bus 0000:00: root bus resource [bus 00-ff]
[    9.976648] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    9.993260] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[   10.016840] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[   10.073370] pci 0000:00:01.1: reg 0x20: [io  0xc0c0-0xc0cf]
[   10.097549] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[   10.115272] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[   10.134272] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[   10.153304] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[   10.172202] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[   10.188807] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[   10.208431] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[   10.226696] pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x160 took 36132 usecs
[   10.245567] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[   10.270355] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[   10.307294] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[   10.402327] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[   10.421436] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[   10.450272] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[   10.480289] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[   10.550332] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[   10.569467] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[   10.602291] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[   10.630268] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[   10.694298] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[   10.742037] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[   10.745297] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
[   10.748267] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[   10.756268] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[   10.765852] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000
[   10.768268] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f]
[   10.792328] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[   10.794823] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[   10.796821] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[   10.798613] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[   10.800490] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[   10.807527] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[   10.808202] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[   10.808290] pci 0000:00:02.0: vgaarb: bridge control possible
[   10.809262] vgaarb: loaded
[   10.811583] SCSI subsystem initialized
[   10.812553] ACPI: bus type USB registered
[   10.813432] usbcore: registered new interface driver usbfs
[   10.814369] usbcore: registered new interface driver hub
[   10.815582] usbcore: registered new device driver usb
[   10.816436] pps_core: LinuxPPS API ver. 1 registered
[   10.817263] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[   10.818274] PTP clock support registered
[   10.820366] EDAC MC: Ver: 3.0.0
[   10.827616] PCI: Using ACPI for IRQ routing
[   10.838298] PCI: pci_cache_line_size set to 64 bytes
[   10.852984] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[   10.869268] e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff]
[   10.883488] NetLabel: Initializing
[   10.893395] NetLabel:  domain hash size = 128
[   10.904268] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[   10.918435] NetLabel:  unlabeled traffic allowed by default
[   10.939149] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[   10.952267] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[   10.975202] clocksource: Switched to clocksource kvm-clock
[   11.283627] VFS: Disk quotas dquot_6.6.0
[   11.298279] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[   11.317713] *** VALIDATE hugetlbfs ***
[   11.330063] pnp: PnP ACPI init
[   11.341014] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[   11.362143] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[   11.388230] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[   11.414341] pnp 00:03: [dma 2]
[   11.425035] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[   11.444092] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[   11.464866] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[   11.483917] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[   11.504132] pnp: PnP ACPI: found 7 devices
[   11.618826] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[   11.647007] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[   11.667130] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[   11.687093] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[   11.708775] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
[   11.730552] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[   11.760727] NET: Registered protocol family 2
[   11.778049] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes)
[   11.802480] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[   11.825620] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[   11.845123] TCP: Hash tables configured (established 16384 bind 16384)
[   11.864640] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[   11.880834] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[   11.899768] NET: Registered protocol family 1
[   11.915873] RPC: Registered named UNIX socket transport module.
[   11.933855] RPC: Registered udp transport module.
[   11.948898] RPC: Registered tcp transport module.
[   11.963280] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   11.981621] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[   12.001551] pci 0000:00:00.0: quirk_passive_release+0x0/0xa0 took 19557 usecs
[   12.024138] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[   12.044128] pci 0000:00:00.0: quirk_natoma+0x0/0x30 took 19514 usecs
[   12.065589] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[   12.085941] pci 0000:00:01.0: quirk_isa_dma_hangs+0x0/0x30 took 19848 usecs
[   12.109953] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[   12.138463] pci 0000:00:02.0: pci_fixup_video+0x0/0x110 took 28102 usecs
[   12.160863] PCI: CLS 0 bytes, default 64
[   12.175904] Trying to unpack rootfs image as initramfs...
[   54.160939] Freeing initrd memory: 185764K
[   54.192759] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[   54.287443] Initialise system trusted keyrings
[   54.302749] Key type blacklist registered
[   54.321345] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[   54.369009] zbud: loaded
[   54.384346] 9p: Installing v9fs 9p2000 file system support
[   54.424971] NET: Registered protocol family 38
[   54.439976] Key type asymmetric registered
[   54.454367] Asymmetric key parser 'x509' registered
[   54.481716] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[   54.509909] io scheduler mq-deadline registered
[   54.524979] io scheduler kyber registered
[   54.538873] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[   54.566344] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[   54.587605] intel_idle: Please enable MWAIT in BIOS SETUP
[   54.607277] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   54.633601] ACPI: Power Button [PWRF]
[   54.659794] ERST DBG: ERST support is disabled.
[   54.979985] PCI Interrupt Link [LNKD] enabled at IRQ 11
[   55.377156] PCI Interrupt Link [LNKA] enabled at IRQ 10
[   55.416390] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[   55.515460] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   55.611768] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   55.639963] Non-volatile memory driver v1.3
[   55.659760] Linux agpgart interface v0.103
[   55.739144] virtio_blk virtio0: [vda] 536870912 512-byte logical blocks (275 GB/256 GiB)
[   55.797478] virtio_blk virtio1: [vdb] 536870912 512-byte logical blocks (275 GB/256 GiB)
[   55.830950] rdac: device handler registered
[   55.850556] hp_sw: device handler registered
[   55.865265] emc: device handler registered
[   55.882004] alua: device handler registered
[   55.896302] MACsec IEEE 802.1AE
[   55.907893] libphy: Fixed MDIO Bus: probed
[   55.921025] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[   55.937577] e1000: Copyright (c) 1999-2006 Intel Corporation.
[   56.232993] PCI Interrupt Link [LNKC] enabled at IRQ 11
[   57.790042] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[   57.810331] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[   57.828765] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[   57.844094] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[   57.865375] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k
[   57.885724] igb: Copyright (c) 2007-2014 Intel Corporation.
[   57.902742] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k
[   57.927102] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[   57.948558] i40e: Intel(R) Ethernet Connection XL710 Network Driver - version 2.8.20-k
[   57.973508] i40e: Copyright (c) 2013 - 2014 Intel Corporation.
[   57.991914] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI
[   58.013113] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[   58.031513] usbcore: registered new interface driver catc
[   58.049764] usbcore: registered new interface driver kaweth
[   58.066356] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver
[   58.089355] usbcore: registered new interface driver pegasus
[   58.107345] usbcore: registered new interface driver rtl8150
[   58.125267] usbcore: registered new interface driver asix
[   58.142634] usbcore: registered new interface driver cdc_ether
[   58.184368] usbcore: registered new interface driver cdc_eem
[   58.203626] usbcore: registered new interface driver dm9601
[   58.223395] usbcore: registered new interface driver smsc75xx
[   58.243387] usbcore: registered new interface driver smsc95xx
[   58.263123] usbcore: registered new interface driver gl620a
[   58.283020] usbcore: registered new interface driver net1080
[   58.302866] usbcore: registered new interface driver plusb
[   58.333959] usbcore: registered new interface driver rndis_host
[   58.354404] usbcore: registered new interface driver cdc_subset
[   58.374602] usbcore: registered new interface driver zaurus
[   58.394563] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[   58.428931] usbcore: registered new interface driver int51x1
[   58.452723] usbcore: registered new interface driver ipheth
[   58.472462] usbcore: registered new interface driver sierra_net
[   58.497990] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   58.518307] ehci-pci: EHCI PCI platform driver
[   58.533086] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   58.552333] ohci-pci: OHCI PCI platform driver
[   58.567475] uhci_hcd: USB Universal Host Controller Interface driver
[   58.587795] usbcore: registered new interface driver usbserial_generic
[   58.607717] usbserial: USB Serial support registered for generic
[   58.625928] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   58.660310] serio: i8042 KBD port at 0x60,0x64 irq 1
[   58.676353] serio: i8042 AUX port at 0x60,0x64 irq 12
[   58.702639] mousedev: PS/2 mouse device common for all mice
[   58.723149] rtc_cmos 00:00: RTC can wake from S4
[   58.748297] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   58.749524] rtc_cmos 00:00: registered as rtc0
[   58.783747] hpet1: lost 1 rtc interrupts
[   58.803607] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[   58.845481] i6300ESB timer 0000:00:06.0: initialized. heartbeat=30 sec (nowayout=0)
[   58.865310] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[   58.873068] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[   58.914442] iTCO_vendor_support: vendor-support=0
[   58.919563] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[   58.930592] intel_pstate: CPU model not supported
[   58.979761] hidraw: raw HID events driver (C) Jiri Kosina
[   58.997419] usbcore: registered new interface driver usbhid
[   59.014143] usbhid: USB HID core driver
[   59.028985] drop_monitor: Initializing network drop monitor service
[   59.052001] Initializing XFRM netlink socket
[   59.065774] NET: Registered protocol family 10
[   59.083207] Segment Routing with IPv6
[   59.095555] NET: Registered protocol family 17
[   59.110784] 9pnet: Installing 9P2000 support
[   59.123709] mpls_gso: MPLS GSO support
[   59.141727] mce: Using 10 MCE banks
[   59.154602] IPI shorthand broadcast: enabled
[   59.169114] ... APIC ID:      00000000 (0)
[   59.170027] ... APIC VERSION: 00050014
[   59.170027] 0000000000000000000000000000000000000000000000000000000000000000
[   59.170027] 0000000000000000000000000000000000000000000000000000000000000000
[   59.170027] 0000000000000000000000000000000000000000000000000000000020000000
[   59.255292] number of MP IRQ sources: 15.
[   59.269062] number of IO-APIC #0 registers: 24.
[   59.282869] testing the IO APIC.......................
[   59.298945] IO APIC #0......
[   59.309113] .... register #00: 00000000
[   59.321452] .......    : physical APIC id: 00
[   59.335325] .......    : Delivery Type: 0
[   59.349290] .......    : LTS          : 0
[   59.362936] .... register #01: 00170011
[   59.376352] .......     : max redirection entries: 17
[   59.392457] .......     : PRQ implemented: 0
[   59.406746] .......     : IO APIC version: 11
[   59.421299] .... register #02: 00000000
[   59.434288] .......     : arbitration: 00
[   59.447745] .... IRQ redirection table:
[   59.459921] IOAPIC 0:
[   59.468772]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.494075]  pin01, enabled , edge , high, V(25), IRR(0), S(0), physical, D(01), M(0)
[   59.518056]  pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[   59.542726]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.568580]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.594636]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.621436]  pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.648108]  pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.672413]  pin08, enabled , edge , high, V(25), IRR(0), S(0), physical, D(00), M(0)
[   59.696033]  pin09, enabled , level, high, V(21), IRR(0), S(0), physical, D(01), M(0)
[   59.721829]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.744806]  pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.766815]  pin0c, enabled , edge , high, V(24), IRR(0), S(0), physical, D(00), M(0)
[   59.788849]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.812572]  pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.836552]  pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.857801]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.883735]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.928384]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   59.953825]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   60.002080]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   60.025635]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   60.049520]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   60.075576]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   60.101504] IRQ to pin mappings:
[   60.113586] IRQ0 -> 0:2
[   60.123569] IRQ1 -> 0:1
[   60.133359] IRQ3 -> 0:3
[   60.143533] IRQ4 -> 0:4
[   60.153320] IRQ5 -> 0:5
[   60.163034] IRQ6 -> 0:6
[   60.172894] IRQ7 -> 0:7
[   60.182543] IRQ8 -> 0:8
[   60.192525] IRQ9 -> 0:9
[   60.202314] IRQ10 -> 0:10
[   60.212621] IRQ11 -> 0:11
[   60.223024] IRQ12 -> 0:12
[   60.233586] IRQ13 -> 0:13
[   60.243994] IRQ14 -> 0:14
[   60.254515] IRQ15 -> 0:15
[   60.264790] .................................... done.
[   60.295163] sched_clock: Marking stable (55771263963, 4522955662)->(62370303802, -2076084177)
[   60.327537] registered taskstats version 1
[   60.341893] Loading compiled-in X.509 certificates
[   60.468642] Loaded X.509 cert 'Build time autogenerated kernel key: 810ecdb1365a61032b87c636b6565fa4cf8dd760'
[   60.514917] zswap: loaded using pool lzo/zbud
[   60.625509] Key type big_key registered
[   60.671411] Key type encrypted registered
[   60.684799] ima: No TPM chip found, activating TPM-bypass!
[   60.701906] ima: Allocated hash algorithm: sha1
[   60.716750] No architecture policies found
[   60.729973] evm: Initialising EVM extended attributes:
[   60.746276] evm: security.selinux
[   60.758753] evm: security.apparmor
[   60.771358] evm: security.ima
[   60.782863] evm: security.capability
[   60.795922] evm: HMAC attrs: 0x1
[   60.851149] PM:   Magic number: 7:420:71
[   60.874394] rtc_cmos 00:00: setting system clock to 2019-06-26T09:05:46 UTC (1561539946)
[   60.982418] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   61.023328] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   61.029388] Sending DHCP requests ., OK
[   61.083571] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[   61.121548] IP-Config: Complete:
[   61.136265]      device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[   61.165131]      host=vm-snb-yocto-ia32-1127, domain=, nis-domain=(none)
[   61.199870]      bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=
[   61.199873]      nameserver0=10.0.2.3
[   61.265176] Freeing unused decrypted memory: 2040K
[   61.285944] Freeing unused kernel image memory: 2508K
[   61.312949] Write protecting the kernel read-only data: 22528k
[   61.341885] Freeing unused kernel image memory: 2012K
[   61.364543] Freeing unused kernel image memory: 1920K
[   61.381810] rodata_test: all tests were successful
[   61.398074] Run /init as init process
Starting udev
[   61.833995] udevd[195]: starting version 3.2.7
[   61.855532] random: udevd: uninitialized urandom read (16 bytes read)
[   61.875986] random: udevd: uninitialized urandom read (16 bytes read)
[   61.896144] random: udevd: uninitialized urandom read (16 bytes read)
[   61.927475] udevd[195]: specified group 'kvm' unknown
[   61.961552] udevd[196]: starting eudev-3.2.7
[   62.135085] udevd[196]: specified group 'kvm' unknown
[   62.523322] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   62.589880] Floppy drive(s): fd0 is 2.88M AMI BIOS
[   62.730483] parport_pc 00:04: reported by Plug and Play ACPI
[   62.820262] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[   62.905106] libata version 3.00 loaded.
[   63.039023] input: PC Speaker as /devices/platform/pcspkr/input/input5
[   63.137015] FDC 0 is a S82078B
[   63.192801] ata_piix 0000:00:01.1: version 2.13
[   63.259749] scsi host0: ata_piix
[   63.388029] scsi host1: ata_piix
[   63.413011] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14
[   63.435343] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15
[   63.629053] ata2.01: NODEV after polling detection
[   63.659999] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[   63.710913] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[   63.867430] ppdev: user-space parallel port driver
[   63.956300] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 0: 0xfd000000 -> 0xfdffffff
[   64.014829] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 2: 0xfebf0000 -> 0xfebf0fff
[   64.044973] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
[   64.082738] Console: switching to colour dummy device 80x25
[   64.094796] [drm] Found bochs VGA, ID 0xb0c0.
[   64.104295] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[   64.124317] [TTM] Zone  kernel: Available graphics memory: 1017348 KiB
[   64.136894] [TTM] Initializing pool allocator
[   64.145801] [TTM] Initializing DMA pool allocator
[   64.190325] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[   64.228562] fbcon: bochs-drmdrmfb (fb0) is primary device
[   64.239935] Console: switching to colour frame buffer device 128x48
[   64.289947] bochs-drm 0000:00:02.0: fb0: bochs-drmdrmfb frame buffer device
[   64.334991] Error: Driver 'pcspkr' is already registered, aborting...
[   64.379361] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[   64.392346] cdrom: Uniform CD-ROM driver Revision: 3.20
[   64.406518] sr 1:0:0:0: Attached scsi CD-ROM sr0
[   66.116595] urandom_read: 4 callbacks suppressed
[   66.116599] random: dd: uninitialized urandom read (512 bytes read)
LKP: HOSTNAME vm-snb-yocto-ia32-1127, MAC 52:54:00:12:34:56, kernel 5.2.0-rc5-00489-g4f3f6d6 1, serial console /dev/ttyS0
Poky (Yocto Project Reference Distro) 2.7+snapshot vm-snb-yocto-ia32-1127 /dev/ttyS0
[   69.299956] spin_lock-torture: Creating torture_onoff task
[   69.312797] spin_lock-torture: Creating torture_shuffle task
[   69.312983] spin_lock-torture: torture_onoff task started
[   69.328381] spin_lock-torture: Creating torture_stutter task
[   69.361364] spin_lock-torture: torture_shuffle task started
[   69.367290] spin_lock-torture: torture_onoff begin holdoff
[   69.376384] spin_lock-torture: Creating lock_torture_writer task
[   69.423098] spin_lock-torture: lock_torture_writer task started
[   69.455939] spin_lock-torture: torture_stutter task started
[   69.455975] spin_lock-torture: Creating lock_torture_writer task
[   69.489815] spin_lock-torture: Creating lock_torture_writer task
[   69.489897] spin_lock-torture: lock_torture_writer task started
[   69.534188] spin_lock-torture: Creating lock_torture_writer task
[   69.565978] spin_lock-torture: lock_torture_writer task started
[   69.588395] spin_lock-torture: Creating lock_torture_stats task
[   69.588450] spin_lock-torture: lock_torture_writer task started
[   69.627351] spin_lock-torture: lock_torture_stats task started
[   99.967480] spin_lock-torture: torture_onoff end holdoff
[  100.048781] Unregister pv shared memory for cpu 1
[  100.087908] smpboot: CPU 1 is now offline
[  106.178946] x86: Booting SMP configuration:
[  106.197911] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  106.239137] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  106.239387] masked ExtINT on CPU#1
[  106.279868] KVM setup async PF for cpu 1
[  106.296876] kvm-stealtime: cpu 1, msr 7492d040
[  109.391433] Unregister pv shared memory for cpu 0
[  109.445376] smpboot: CPU 0 is now offline
[  115.568817] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  115.601277] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  115.601506] masked ExtINT on CPU#0
[  115.670018] KVM setup async PF for cpu 0
[  115.687806] kvm-stealtime: cpu 0, msr 7482d040
[  118.815945] Unregister pv shared memory for cpu 0
[  118.866374] smpboot: CPU 0 is now offline
[  121.920111] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  121.981935] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  121.982169] masked ExtINT on CPU#0
[  122.086370] KVM setup async PF for cpu 0
[  122.104418] kvm-stealtime: cpu 0, msr 7482d040
[  125.201499] Unregister pv shared memory for cpu 1
[  125.255370] smpboot: CPU 1 is now offline
[  128.320282] x86: Booting SMP configuration:
[  128.384415] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  128.431951] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  128.432213] masked ExtINT on CPU#1
[  128.475913] KVM setup async PF for cpu 1
[  128.492369] kvm-stealtime: cpu 1, msr 7492d040
[  129.664448] Writes:  Total: 11786277  Max/Min: 0/0   Fail: 0 
[  131.597639] Unregister pv shared memory for cpu 1
[  131.635072] smpboot: CPU 1 is now offline
[  137.727755] x86: Booting SMP configuration:
[  137.744577] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  137.784562] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  137.784860] masked ExtINT on CPU#1
[  137.827507] KVM setup async PF for cpu 1
[  137.844097] kvm-stealtime: cpu 1, msr 7492d040
[  140.967261] Unregister pv shared memory for cpu 0
[  141.005309] smpboot: CPU 0 is now offline
[  144.063841] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  144.124806] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  144.125070] masked ExtINT on CPU#0
[  144.195091] KVM setup async PF for cpu 0
[  144.214103] kvm-stealtime: cpu 0, msr 7482d040
[  147.284991] Unregister pv shared memory for cpu 0
[  147.307178] smpboot: CPU 0 is now offline
[  150.401077] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  150.432534] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  150.432854] masked ExtINT on CPU#0
[  150.508071] KVM setup async PF for cpu 0
[  150.534228] kvm-stealtime: cpu 0, msr 7482d040
[  153.613499] Unregister pv shared memory for cpu 1
[  153.658734] smpboot: CPU 1 is now offline
[  156.735854] x86: Booting SMP configuration:
[  156.781638] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  156.822470] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  156.822788] masked ExtINT on CPU#1
[  156.868415] KVM setup async PF for cpu 1
[  156.884587] kvm-stealtime: cpu 1, msr 7492d040
[  159.949436] Unregister pv shared memory for cpu 1
[  159.999552] smpboot: CPU 1 is now offline
[  181.119822] x86: Booting SMP configuration:
[  181.158843] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  181.196930] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  181.197164] masked ExtINT on CPU#1
[  181.238979] KVM setup async PF for cpu 1
[  181.281748] kvm-stealtime: cpu 1, msr 7492d040
[  184.502758] Unregister pv shared memory for cpu 1
[  184.524071] smpboot: CPU 1 is now offline
[  186.064220] random: fast init done
[  187.583778] x86: Booting SMP configuration:
[  187.601246] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  187.658927] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  187.659185] masked ExtINT on CPU#1
[  187.708619] KVM setup async PF for cpu 1
[  187.726607] kvm-stealtime: cpu 1, msr 7492d040
[  190.079368] Writes:  Total: 23682057  Max/Min: 0/0   Fail: 0 
[  190.808851] Unregister pv shared memory for cpu 0
[  190.855388] smpboot: CPU 0 is now offline
[  202.943856] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  203.004502] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  203.004816] masked ExtINT on CPU#0
[  203.053000] KVM setup async PF for cpu 0
[  203.070782] kvm-stealtime: cpu 0, msr 7482d040
[  206.184845] Unregister pv shared memory for cpu 1
[  206.251380] smpboot: CPU 1 is now offline
[  209.343798] x86: Booting SMP configuration:
[  209.359611] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  209.402461] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  209.402785] masked ExtINT on CPU#1
[  209.443457] KVM setup async PF for cpu 1
[  209.455450] kvm-stealtime: cpu 1, msr 7492d040
[  212.559088] Unregister pv shared memory for cpu 1
[  212.590822] smpboot: CPU 1 is now offline
[  218.623883] x86: Booting SMP configuration:
[  218.682118] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  218.724417] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  218.724710] masked ExtINT on CPU#1
[  218.764427] KVM setup async PF for cpu 1
[  218.785579] kvm-stealtime: cpu 1, msr 7492d040
[  221.841058] Unregister pv shared memory for cpu 1
[  221.869754] smpboot: CPU 1 is now offline
[  228.031753] x86: Booting SMP configuration:
[  228.051318] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  228.096437] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  228.096758] masked ExtINT on CPU#1
[  228.138980] KVM setup async PF for cpu 1
[  228.155802] kvm-stealtime: cpu 1, msr 7492d040
[  231.245223] Unregister pv shared memory for cpu 1
[  231.280319] smpboot: CPU 1 is now offline
[  234.368547] x86: Booting SMP configuration:
[  234.388863] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  234.425609] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  234.425931] masked ExtINT on CPU#1
[  234.476871] KVM setup async PF for cpu 1
[  234.493433] kvm-stealtime: cpu 1, msr 7492d040
[  237.588764] Unregister pv shared memory for cpu 0
[  237.627284] smpboot: CPU 0 is now offline
[  240.706759] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  240.724813] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  240.725072] masked ExtINT on CPU#0
[  240.777173] KVM setup async PF for cpu 0
[  240.789643] kvm-stealtime: cpu 0, msr 7482d040
[  243.852197] Unregister pv shared memory for cpu 0
[  243.900079] smpboot: CPU 0 is now offline
[  246.975865] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  247.015501] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  247.015827] masked ExtINT on CPU#0
[  247.071986] KVM setup async PF for cpu 0
[  247.091037] kvm-stealtime: cpu 0, msr 7482d040
[  250.192298] Unregister pv shared memory for cpu 1
[  250.224984] smpboot: CPU 1 is now offline
[  250.276351] Writes:  Total: 37151388  Max/Min: 0/0   Fail: 0 
[  256.319873] x86: Booting SMP configuration:
[  256.336956] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  256.378822] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  256.379077] masked ExtINT on CPU#1
[  256.421011] KVM setup async PF for cpu 1
[  256.435949] kvm-stealtime: cpu 1, msr 7492d040
[  259.534948] Unregister pv shared memory for cpu 1
[  259.582828] smpboot: CPU 1 is now offline
[  265.663762] x86: Booting SMP configuration:
[  265.704506] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  265.749867] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  265.750120] masked ExtINT on CPU#1
[  265.788910] KVM setup async PF for cpu 1
[  265.804630] kvm-stealtime: cpu 1, msr 7492d040
[  268.885218] Unregister pv shared memory for cpu 1
[  268.935291] smpboot: CPU 1 is now offline
[  278.016631] x86: Booting SMP configuration:
[  278.058765] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  278.086432] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  278.086820] masked ExtINT on CPU#1
[  278.128330] KVM setup async PF for cpu 1
[  278.145416] kvm-stealtime: cpu 1, msr 7492d040
[  281.231494] Unregister pv shared memory for cpu 0
[  281.270582] smpboot: CPU 0 is now offline
[  284.411816] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  284.463532] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  285.487917] WARNING: CPU: 0 PID: 0 at arch/x86/kernel/apic/apic.c:1486 setup_local_APIC+0x24c/0x3b0
[  285.544815] Modules linked in: locktorture torture sr_mod cdrom snd_pcm snd_timer ata_generic bochs_drm ttm snd pata_acpi ppdev drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops soundcore crc32c_intel ata_piix drm pcspkr joydev serio_raw parport_pc i2c_piix4 parport floppy libata
[  285.589349] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.2.0-rc5-00489-g4f3f6d6 #1
[  285.602370] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[  285.616830] RIP: 0010:setup_local_APIC+0x24c/0x3b0
[  285.625279] Code: 30 8b 0d ef 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 a9 fe ff ff <0f> 0b e9 53 ff ff ff 49 83 ee 01 eb ea a9 00 00 01 00 8b 15 30 cc
[  285.658404] RSP: 0000:ffffffffbb603ee0 EFLAGS: 00010082
[  285.667800] RAX: 0000009a6ea4c5e1 RBX: 0000000000000020 RCX: 000000008a001800
[  285.679720] RDX: 0000009a00000000 RSI: 0000000000000020 RDI: ffffffffbb603ef0
[  285.692845] RBP: 0000000000000000 R08: ffffffffbbf4ade2 R09: 0000000000000033
[  285.705289] R10: ffff8f83a8eaeec0 R11: 0000000000000000 R12: 00000000000000f0
[  285.718357] R13: 0000000000001000 R14: fffffffffffa75d5 R15: 0000000000000000
[  285.731184] FS:  0000000000000000(0000) GS:ffff8f83f4800000(0000) knlGS:0000000000000000
[  285.745636] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  285.755786] CR2: 00000000f7ee7710 CR3: 000000002860a000 CR4: 00000000000006f0
[  285.768356] Call Trace:
[  285.772861]  apic_ap_setup+0xa/0x20
[  285.779050]  start_secondary+0x78/0x1e0
[  285.785984]  secondary_startup_64+0xb6/0xc0
[  285.793375] ---[ end trace 59ff7fd7e53f78d2 ]---
[  285.801574] masked ExtINT on CPU#0
[  285.837638] KVM setup async PF for cpu 0
[  285.854492] kvm-stealtime: cpu 0, msr 7482d040
[  288.957638] Unregister pv shared memory for cpu 1
[  288.992638] smpboot: CPU 1 is now offline
[  292.031763] x86: Booting SMP configuration:
[  292.045274] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  292.114279] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  292.114509] masked ExtINT on CPU#1
[  292.163018] KVM setup async PF for cpu 1
[  292.183404] kvm-stealtime: cpu 1, msr 7492d040
[  295.253788] Unregister pv shared memory for cpu 0
[  295.285898] smpboot: CPU 0 is now offline
[  298.367771] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  298.427164] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  298.427395] masked ExtINT on CPU#0
[  298.496956] KVM setup async PF for cpu 0
[  298.515240] kvm-stealtime: cpu 0, msr 7482d040
[  301.597852] Unregister pv shared memory for cpu 0
[  301.631462] smpboot: CPU 0 is now offline
[  302.816622] hrtimer: interrupt took 4279019 ns
[  304.703773] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  304.752522] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  304.752844] masked ExtINT on CPU#0
[  304.830074] KVM setup async PF for cpu 0
[  304.846346] kvm-stealtime: cpu 0, msr 7482d040
[  307.926453] Unregister pv shared memory for cpu 0
[  307.976442] smpboot: CPU 0 is now offline
[  310.399381] Writes:  Total: 50508528  Max/Min: 0/0   Fail: 0 
[  311.039749] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  311.079189] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  311.079419] masked ExtINT on CPU#0
[  311.134930] KVM setup async PF for cpu 0
[  311.151162] kvm-stealtime: cpu 0, msr 7482d040
[  314.263397] Unregister pv shared memory for cpu 0
[  314.304399] smpboot: CPU 0 is now offline
[  323.429321] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  323.448918] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  323.449146] masked ExtINT on CPU#0
[  323.547038] KVM setup async PF for cpu 0
[  323.572995] kvm-stealtime: cpu 0, msr 7482d040
[  326.672588] Unregister pv shared memory for cpu 1
[  326.738327] smpboot: CPU 1 is now offline
[  332.864740] x86: Booting SMP configuration:
[  332.913350] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  332.936288] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  332.936521] masked ExtINT on CPU#1
[  333.022040] KVM setup async PF for cpu 1
[  333.039238] kvm-stealtime: cpu 1, msr 7492d040
[  336.141354] Unregister pv shared memory for cpu 1
[  336.175316] smpboot: CPU 1 is now offline
[  339.263820] x86: Booting SMP configuration:
[  339.308315] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  339.336642] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  339.336963] masked ExtINT on CPU#1
[  339.434375] KVM setup async PF for cpu 1
[  339.451791] kvm-stealtime: cpu 1, msr 7492d040
[  342.545947] Unregister pv shared memory for cpu 1
[  342.618454] smpboot: CPU 1 is now offline
[  345.668516] x86: Booting SMP configuration:
[  345.704359] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  345.746152] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  345.746414] masked ExtINT on CPU#1
[  345.789936] KVM setup async PF for cpu 1
[  345.806459] kvm-stealtime: cpu 1, msr 7492d040
[  348.890936] Unregister pv shared memory for cpu 0
[  348.962135] smpboot: CPU 0 is now offline
[  358.081415] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  358.117007] kvm-clock: cpu 0, msr 29201001, secondary cpu clock
[  358.117263] masked ExtINT on CPU#0
[  358.189066] KVM setup async PF for cpu 0
[  358.204889] kvm-stealtime: cpu 0, msr 7482d040
[  361.296499] Unregister pv shared memory for cpu 1
[  361.338500] smpboot: CPU 1 is now offline
[  367.487819] x86: Booting SMP configuration:
[  367.503762] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  367.541288] kvm-clock: cpu 1, msr 29201041, secondary cpu clock
[  367.541516] masked ExtINT on CPU#1
[  367.585032] KVM setup async PF for cpu 1
[  367.601072] kvm-stealtime: cpu 1, msr 7492d040
[  369.859387] spin_lock-torture: Stopping torture_shuffle task
[  369.923359] spin_lock-torture: Stopping torture_stutter
[  369.952207] spin_lock-torture: Stopping torture_shuffle
[  369.979947] spin_lock-torture: Stopping lock_torture_writer
[  369.999621] spin_lock-torture: Stopping lock_torture_writer
[  370.018555] spin_lock-torture: Stopping torture_stutter task
[  370.018739] spin_lock-torture: Stopping lock_torture_writer
[  370.085500] spin_lock-torture: Stopping lock_torture_writer
[  370.086035] spin_lock-torture: Stopping torture_onoff task
[  370.122809] spin_lock-torture: Stopping torture_onoff
[  370.149580] spin_lock-torture: Stopping lock_torture_writer task
[  370.170578] spin_lock-torture: Stopping lock_torture_writer task
[  370.190889] spin_lock-torture: Stopping lock_torture_writer task
[  370.208530] spin_lock-torture: Stopping lock_torture_writer task
[  370.232542] spin_lock-torture: Stopping lock_torture_stats task
[  370.251330] Writes:  Total: 62736722  Max/Min: 0/0   Fail: 0 
[  370.269775] spin_lock-torture: Stopping lock_torture_stats
[  370.301038] Writes:  Total: 62736722  Max/Min: 0/0   Fail: 0 
[  370.319962] 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
[  377.799415] sysrq: Emergency Sync
[  377.817467] Emergency Sync complete
[  377.835393] sysrq: Resetting

Elapsed time: 370

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

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-cpu SandyBridge
	-kernel $kernel
	-initrd initrd-vm-snb-yocto-ia32-1127
	-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-1127-0,media=disk,if=virtio
	-drive file=disk-vm-snb-yocto-ia32-1127-1,media=disk,if=virtio
	-serial stdio
	-display none
	-monitor null
)

append=(
	ip=::::vm-snb-yocto-ia32-1127::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-rhel-7.6
	branch=tip/WIP.x86/ipi
	commit=4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1
	BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/vmlinuz-5.2.0-rc5-00489-g4f3f6d6
	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/4f3f6d6a7f8e583f92aaedc5fdec3c3a00c8a5e1/1
	result_service=tmpfs
	debug
	apic=debug
	sysrq_always_enabled
	rcupdate.rcu_cpu_stall_timeout=100
	net.ifnames=0
	printk.devkmsg=on
	panic=-1
	softlockup_panic=1
	nmi_watchdog=panic
	oops=panic
	load_ramdisk=2
	prompt_ramdisk=0
	drbd.minor_count=8
	systemd.log_level=err
	ignore_loglevel
	console=tty0
	earlyprintk=ttyS0,115200
	console=ttyS0,115200
	vga=normal
	rw
	rcuperf.shutdown=0
)

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