early console in setup code
Probing EDD (edd=off to disable)... ok
early console in extract_kernel
input_data: 0x0000000002b073b1
input_len: 0x00000000008f8c16
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-00491-g91b46d2 (kbuild@lkp-kbuild25) (gcc version 7.4.0 (Debian 7.4.0-9)) #1 SMP Wed Jun 26 12:40:35 CST 2019
[    0.000000] Command line: ip=::::vm-snb-yocto-ia32-1101::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1101/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-91-20190701-27635-1d7csr0-13.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=feng/master commit=91b46d2112940bcef670d71a80e57673e5ec904f BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/91b46d2112940bcef670d71a80e57673e5ec904f/vmlinuz-5.2.0-rc5-00491-g91b46d2 lapic=notscdeadline 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/91b46d2112940bcef670d71a80e57673e5ec904f/13 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
[    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 12601001, primary cpu clock
[    0.000003] kvm-clock: using sched offset of 3397270840 cycles
[    0.011149] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.043195] tsc: Detected 2260.998 MHz processor
[    0.079946] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.093457] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.104251] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.113425] MTRR default type: write-back
[    0.120032] MTRR fixed ranges enabled:
[    0.125968]   00000-9FFFF write-back
[    0.131779]   A0000-BFFFF uncachable
[    0.137232]   C0000-FFFFF write-protect
[    0.143672] MTRR variable ranges enabled:
[    0.150056]   0 base 0080000000 mask FF80000000 uncachable
[    0.158577]   1 disabled
[    0.162566]   2 disabled
[    0.166230]   3 disabled
[    0.170051]   4 disabled
[    0.173685]   5 disabled
[    0.177507]   6 disabled
[    0.180982]   7 disabled
[    0.184969] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Memory KASLR using RDTSC...
[    0.198579] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.206351] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.214135] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.274310] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f]
[    0.283803]   mpc: f6a80-f6b74
[    0.290180] BRK [0x12801000, 0x12801fff] PGTABLE
[    0.297799] BRK [0x12802000, 0x12802fff] PGTABLE
[    0.305029] BRK [0x12803000, 0x12803fff] PGTABLE
[    0.312476] BRK [0x12804000, 0x12804fff] PGTABLE
[    0.319740] BRK [0x12805000, 0x12805fff] PGTABLE
[    0.328423] BRK [0x12806000, 0x12806fff] PGTABLE
[    0.336422] RAMDISK: [mem 0x74844000-0x7ffcffff]
[    0.344275] ACPI: Early table checksum verification disabled
[    0.354473] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.364367] ACPI: RSDT 0x000000007FFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.378232] ACPI: FACP 0x000000007FFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.392051] ACPI: DSDT 0x000000007FFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.406063] ACPI: FACS 0x000000007FFE0000 000040
[    0.413867] ACPI: APIC 0x000000007FFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.426506] ACPI: HPET 0x000000007FFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.439051] ACPI: Local APIC address 0xfee00000
[    0.445851] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.457800] No NUMA configuration found
[    0.463371] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff]
[    0.473018] NODE_DATA(0) allocated [mem 0x74819000-0x74843fff]
[    0.483856] cma: Reserved 200 MiB at 0x0000000068000000
[    0.491564] Zone ranges:
[    0.495459]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.504299]   DMA32    [mem 0x0000000001000000-0x000000007ffdbfff]
[    0.513475]   Normal   empty
[    0.517654]   Device   empty
[    0.521832] Movable zone start for each node
[    0.528093] Early memory node ranges
[    0.533242]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.542379]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.553460] Zeroed struct page in unavailable ranges: 98 pages
[    0.553465] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff]
[    0.572624] On node 0 totalpages: 524154
[    0.578458]   DMA zone: 64 pages used for memmap
[    0.585330]   DMA zone: 21 pages reserved
[    0.591442]   DMA zone: 3998 pages, LIFO batch:0
[    0.598965]   DMA32 zone: 8128 pages used for memmap
[    0.606199]   DMA32 zone: 520156 pages, LIFO batch:63
[    0.649493] ACPI: PM-Timer IO Port: 0x608
[    0.655549] ACPI: Local APIC address 0xfee00000
[    0.663455] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.673570] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.685044] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.696162] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.710219] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.723798] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.738251] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.751311] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.765018] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.778367] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.792953] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.806234] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.820240] ACPI: IRQ0 used by override.
[    0.827788] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.841921] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.855950] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.870016] ACPI: IRQ5 used by override.
[    0.877470] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.891979] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.906155] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.920188] ACPI: IRQ9 used by override.
[    0.928510] ACPI: IRQ10 used by override.
[    0.936743] ACPI: IRQ11 used by override.
[    0.944656] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.956451] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.966361] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.976310] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.986249] Using ACPI (MADT) for SMP configuration information
[    0.994280] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    1.001449] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    1.008316] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    1.015773] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    1.024526] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    1.033565] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    1.043190] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    1.052979] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    1.062303] Booting paravirtualized kernel on KVM
[    1.069340] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    2.781346] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    2.819036] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
[    2.830160] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
[    2.841086] pcpu-alloc: [0] 0 1 
[    2.846334] KVM setup async PF for cpu 0
[    2.853009] kvm-stealtime: cpu 0, msr 65e2d040
[    2.860852] Built 1 zonelists, mobility grouping on.  Total pages: 515941
[    2.872326] Policy zone: DMA32
[    2.877876] Kernel command line: ip=::::vm-snb-yocto-ia32-1101::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1101/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-91-20190701-27635-1d7csr0-13.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=feng/master commit=91b46d2112940bcef670d71a80e57673e5ec904f BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/91b46d2112940bcef670d71a80e57673e5ec904f/vmlinuz-5.2.0-rc5-00491-g91b46d2 lapic=notscdeadline 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/91b46d2112940bcef670d71a80e57673e5ec904f/13 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 rc
[    2.880881] sysrq: sysrq always enabled.
[    3.112532] Memory: 261668K/2096616K available (14339K kernel code, 2346K rwdata, 4224K rodata, 2508K init, 5272K bss, 258392K reserved, 204800K cma-reserved)
[    3.140400] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    3.151257] Kernel/User page tables isolation: enabled
[    3.159254] ftrace: allocating 42695 entries in 167 pages
[    3.308669] rcu: Hierarchical RCU implementation.
[    3.316018] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    3.326774] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    3.338052] 	Tasks RCU enabled.
[    3.342853] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    3.354263] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    3.390973] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    3.401579] random: get_random_bytes called from start_kernel+0x2fa/0x4de with crng_init=0
[    3.499304] Console: colour VGA+ 80x25
[    0.000000] Linux version 5.2.0-rc5-00491-g91b46d2 (kbuild@lkp-kbuild25) (gcc version 7.4.0 (Debian 7.4.0-9)) #1 SMP Wed Jun 26 12:40:35 CST 2019
[    0.000000] Command line: ip=::::vm-snb-yocto-ia32-1101::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1101/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-91-20190701-27635-1d7csr0-13.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=feng/master commit=91b46d2112940bcef670d71a80e57673e5ec904f BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/91b46d2112940bcef670d71a80e57673e5ec904f/vmlinuz-5.2.0-rc5-00491-g91b46d2 lapic=notscdeadline 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/91b46d2112940bcef670d71a80e57673e5ec904f/13 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
[    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 12601001, primary cpu clock
[    0.000003] kvm-clock: using sched offset of 3397270840 cycles
[    0.011149] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.043195] tsc: Detected 2260.998 MHz processor
[    0.079946] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.093457] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.104251] last_pfn = 0x7ffdc max_arch_pfn = 0x400000000
[    0.113425] MTRR default type: write-back
[    0.120032] MTRR fixed ranges enabled:
[    0.125968]   00000-9FFFF write-back
[    0.131779]   A0000-BFFFF uncachable
[    0.137232]   C0000-FFFFF write-protect
[    0.143672] MTRR variable ranges enabled:
[    0.150056]   0 base 0080000000 mask FF80000000 uncachable
[    0.158577]   1 disabled
[    0.162566]   2 disabled
[    0.166230]   3 disabled
[    0.170051]   4 disabled
[    0.173685]   5 disabled
[    0.177507]   6 disabled
[    0.180982]   7 disabled
[    0.184969] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.198579] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.206351] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.214135] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.274310] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f]
[    0.283803]   mpc: f6a80-f6b74
[    0.290180] BRK [0x12801000, 0x12801fff] PGTABLE
[    0.297799] BRK [0x12802000, 0x12802fff] PGTABLE
[    0.305029] BRK [0x12803000, 0x12803fff] PGTABLE
[    0.312476] BRK [0x12804000, 0x12804fff] PGTABLE
[    0.319740] BRK [0x12805000, 0x12805fff] PGTABLE
[    0.328423] BRK [0x12806000, 0x12806fff] PGTABLE
[    0.336422] RAMDISK: [mem 0x74844000-0x7ffcffff]
[    0.344275] ACPI: Early table checksum verification disabled
[    0.354473] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS )
[    0.364367] ACPI: RSDT 0x000000007FFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.378232] ACPI: FACP 0x000000007FFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.392051] ACPI: DSDT 0x000000007FFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.406063] ACPI: FACS 0x000000007FFE0000 000040
[    0.413867] ACPI: APIC 0x000000007FFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.426506] ACPI: HPET 0x000000007FFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.439051] ACPI: Local APIC address 0xfee00000
[    0.445851] mapped APIC to ffffffffff5fc000 (        fee00000)
[    0.457800] No NUMA configuration found
[    0.463371] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff]
[    0.473018] NODE_DATA(0) allocated [mem 0x74819000-0x74843fff]
[    0.483856] cma: Reserved 200 MiB at 0x0000000068000000
[    0.491564] Zone ranges:
[    0.495459]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.504299]   DMA32    [mem 0x0000000001000000-0x000000007ffdbfff]
[    0.513475]   Normal   empty
[    0.517654]   Device   empty
[    0.521832] Movable zone start for each node
[    0.528093] Early memory node ranges
[    0.533242]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.542379]   node   0: [mem 0x0000000000100000-0x000000007ffdbfff]
[    0.553460] Zeroed struct page in unavailable ranges: 98 pages
[    0.553465] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff]
[    0.572624] On node 0 totalpages: 524154
[    0.578458]   DMA zone: 64 pages used for memmap
[    0.585330]   DMA zone: 21 pages reserved
[    0.591442]   DMA zone: 3998 pages, LIFO batch:0
[    0.598965]   DMA32 zone: 8128 pages used for memmap
[    0.606199]   DMA32 zone: 520156 pages, LIFO batch:63
[    0.649493] ACPI: PM-Timer IO Port: 0x608
[    0.655549] ACPI: Local APIC address 0xfee00000
[    0.663455] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.673570] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.685044] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.696162] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.710219] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.723798] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.738251] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.751311] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.765018] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.778367] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.792953] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.806234] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.820240] ACPI: IRQ0 used by override.
[    0.827788] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.841921] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.855950] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.870016] ACPI: IRQ5 used by override.
[    0.877470] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.891979] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.906155] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.920188] ACPI: IRQ9 used by override.
[    0.928510] ACPI: IRQ10 used by override.
[    0.936743] ACPI: IRQ11 used by override.
[    0.944656] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.956451] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.966361] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.976310] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.986249] Using ACPI (MADT) for SMP configuration information
[    0.994280] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    1.001449] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    1.008316] mapped IOAPIC to ffffffffff5fb000 (fec00000)
[    1.015773] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    1.024526] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    1.033565] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    1.043190] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    1.052979] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    1.062303] Booting paravirtualized kernel on KVM
[    1.069340] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    2.781346] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    2.819036] percpu: Embedded 55 pages/cpu s188416 r8192 d28672 u1048576
[    2.830160] pcpu-alloc: s188416 r8192 d28672 u1048576 alloc=1*2097152
[    2.841086] pcpu-alloc: [0] 0 1 
[    2.846334] KVM setup async PF for cpu 0
[    2.853009] kvm-stealtime: cpu 0, msr 65e2d040
[    2.860852] Built 1 zonelists, mobility grouping on.  Total pages: 515941
[    2.872326] Policy zone: DMA32
[    2.877876] Kernel command line: ip=::::vm-snb-yocto-ia32-1101::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1101/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-91-20190701-27635-1d7csr0-13.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.6 branch=feng/master commit=91b46d2112940bcef670d71a80e57673e5ec904f BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/91b46d2112940bcef670d71a80e57673e5ec904f/vmlinuz-5.2.0-rc5-00491-g91b46d2 lapic=notscdeadline 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/91b46d2112940bcef670d71a80e57673e5ec904f/13 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 rc
[    2.880881] sysrq: sysrq always enabled.
[    3.112532] Memory: 261668K/2096616K available (14339K kernel code, 2346K rwdata, 4224K rodata, 2508K init, 5272K bss, 258392K reserved, 204800K cma-reserved)
[    3.140400] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    3.151257] Kernel/User page tables isolation: enabled
[    3.159254] ftrace: allocating 42695 entries in 167 pages
[    3.308669] rcu: Hierarchical RCU implementation.
[    3.316018] rcu: 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[    3.326774] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    3.338052] 	Tasks RCU enabled.
[    3.342853] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    3.354263] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    3.390973] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[    3.401579] random: get_random_bytes called from start_kernel+0x2fa/0x4de with crng_init=0
[    3.499304] Console: colour VGA+ 80x25
[    5.992847] printk: console [tty0] enabled
[    6.004485] printk: console [ttyS0] enabled
[    6.004485] printk: console [ttyS0] enabled
[    6.027492] printk: bootconsole [earlyser0] disabled
[    6.027492] printk: bootconsole [earlyser0] disabled
[    6.057310] ACPI: Core revision 20190509
[    6.071016] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    6.100308] hpet clockevent registered
[    6.112519] APIC: Switch to symmetric I/O mode setup
[    6.128781] x2apic enabled
[    6.143251] Switched APIC routing to physical x2apic.
[    6.156528] enabled ExtINT on CPU#0
[    6.187537] ENABLING IO-APIC IRQs
[    6.202057] init IO_APIC IRQs
[    6.214790]  apic 0 pin 0 not connected
[    6.227215] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:0)
[    6.252201] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[    6.278150] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:0)
[    6.300553] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:0)
[    6.322814] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:0)
[    6.344850] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:0)
[    6.368944] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:0)
[    6.391531] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:0)
[    6.412959] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:0)
[    6.437769] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:0)
[    6.465335] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:0)
[    6.492770] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:0)
[    6.519679] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:0)
[    6.551188] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:0)
[    6.586180] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:0)
[    6.616049]  apic 0 pin 16 not connected
[    6.629126]  apic 0 pin 17 not connected
[    6.641311]  apic 0 pin 18 not connected
[    6.653302]  apic 0 pin 19 not connected
[    6.669559]  apic 0 pin 20 not connected
[    6.686088]  apic 0 pin 21 not connected
[    6.701096]  apic 0 pin 22 not connected
[    6.724367]  apic 0 pin 23 not connected
[    6.738820] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    6.758048] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[    6.789549] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998)
[    6.790582] pid_max: default: 32768 minimum: 301
[    6.792679] LSM: Security Framework initializing
[    6.794617] Yama: becoming mindful.
[    6.795619] SELinux:  Initializing.
[    6.796647] *** VALIDATE SELinux ***
[    6.801528] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    6.803870] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    6.804624] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    6.805617] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    6.808356] *** VALIDATE proc ***
[    6.809001] *** VALIDATE cgroup1 ***
[    6.809551] *** VALIDATE cgroup2 ***
Poking KASLR using RDTSC...
[    6.816805] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    6.817577] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    6.818610] Spectre V2 : Mitigation: Full generic retpoline
[    6.820526] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    6.821548] Speculative Store Bypass: Vulnerable
[    6.822639] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    6.825906] Freeing SMP alternatives memory: 40K
[    6.841397] Using local APIC timer interrupts.
[    6.841397] calibrating APIC timer ...
[    6.842520] ... lapic delta = 6249517
[    6.842520] ... PM-Timer delta = 357923
[    6.842520] ... PM-Timer result ok
[    6.842520] ..... delta 6249517
[    6.842520] ..... mult: 268413981
[    6.842520] ..... calibration result: 999922
[    6.842520] ..... CPU clock speed is 2260.0797 MHz.
[    6.842520] ..... host bus clock speed is 999.0922 MHz.
[    6.843095] smpboot: CPU0: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1)
[    6.845230] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    6.846031] rcu: Hierarchical SRCU implementation.
[    6.854717] NMI watchdog: Perf NMI watchdog permanently disabled
[    6.856972] smp: Bringing up secondary CPUs ...
[    6.859107] x86: Booting SMP configuration:
[    6.859556] .... node  #0, CPUs:      #1
[    3.351301] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[    3.351301] masked ExtINT on CPU#1
[    6.883748] KVM setup async PF for cpu 1
[    6.884520] kvm-stealtime: cpu 1, msr 65f2d040
[    6.907807] smp: Brought up 1 node, 2 CPUs
[    6.908528] smpboot: Max logical packages: 2
[    6.909574] smpboot: Total of 2 processors activated (9043.99 BogoMIPS)
[    6.973591] node 0 initialised, 342939 pages in 61ms
[    6.988548] devtmpfs: initialized
[    6.990073] x86/mm: Memory block size: 128MB
[    6.998838] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    6.999586] futex hash table entries: 512 (order: 3, 32768 bytes)
[    7.008812] pinctrl core: initialized pinctrl subsystem
[    7.010947] PM: RTC time: 16:22:35, date: 2019-07-01
[    7.013709] NET: Registered protocol family 16
[    7.018232] audit: initializing netlink subsys (disabled)
[    7.019934] audit: type=2000 audit(1561969351.802:1): state=initialized audit_enabled=0 res=1
[    7.050620] cpuidle: using governor menu
[    7.055554] ACPI: bus type PCI registered
[    7.056618] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    7.059574] PCI: Using configuration type 1 for base access
[    7.078716] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    7.133100] ACPI: Added _OSI(Module Device)
[    7.148552] ACPI: Added _OSI(Processor Device)
[    7.164556] ACPI: Added _OSI(3.0 _SCP Extensions)
[    7.176578] ACPI: Added _OSI(Processor Aggregator Device)
[    7.190555] ACPI: Added _OSI(Linux-Dell-Video)
[    7.191589] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    7.192553] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    7.199806] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    7.208648] ACPI: Interpreter enabled
[    7.209648] ACPI: (supports S0 S3 S4 S5)
[    7.210550] ACPI: Using IOAPIC for interrupt routing
[    7.211683] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    7.214428] ACPI: Enabled 2 GPEs in block 00 to 0F
[    7.239807] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    7.240585] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
[    7.242549] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    7.247908] acpiphp: Slot [3] registered
[    7.248913] acpiphp: Slot [4] registered
[    7.250589] acpiphp: Slot [5] registered
[    7.252620] acpiphp: Slot [6] registered
[    7.253772] acpiphp: Slot [7] registered
[    7.254718] acpiphp: Slot [8] registered
[    7.255758] acpiphp: Slot [9] registered
[    7.256848] acpiphp: Slot [10] registered
[    7.258583] acpiphp: Slot [11] registered
[    7.259780] acpiphp: Slot [12] registered
[    7.260808] acpiphp: Slot [13] registered
[    7.262715] acpiphp: Slot [14] registered
[    7.263738] acpiphp: Slot [15] registered
[    7.265585] acpiphp: Slot [16] registered
[    7.267747] acpiphp: Slot [17] registered
[    7.268788] acpiphp: Slot [18] registered
[    7.270849] acpiphp: Slot [19] registered
[    7.271843] acpiphp: Slot [20] registered
[    7.273719] acpiphp: Slot [21] registered
[    7.274751] acpiphp: Slot [22] registered
[    7.275781] acpiphp: Slot [23] registered
[    7.276716] acpiphp: Slot [24] registered
[    7.278646] acpiphp: Slot [25] registered
[    7.280626] acpiphp: Slot [26] registered
[    7.281718] acpiphp: Slot [27] registered
[    7.282744] acpiphp: Slot [28] registered
[    7.283715] acpiphp: Slot [29] registered
[    7.284713] acpiphp: Slot [30] registered
[    7.285713] acpiphp: Slot [31] registered
[    7.286651] PCI host bridge to bus 0000:00
[    7.287554] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    7.288531] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    7.289551] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    7.290551] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
[    7.291551] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[    7.293553] pci_bus 0000:00: root bus resource [bus 00-ff]
[    7.295874] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    7.301002] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    7.307430] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    7.319555] pci 0000:00:01.1: reg 0x20: [io  0xc0c0-0xc0cf]
[    7.325723] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    7.326551] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    7.328528] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    7.329550] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    7.332773] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    7.339294] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    7.339740] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    7.344648] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    7.349579] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    7.357553] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    7.369553] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    7.372550] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    7.376551] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    7.379552] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    7.397550] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    7.401135] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    7.405552] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    7.408574] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    7.417560] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[    7.427262] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[    7.430574] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
[    7.435556] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[    7.445657] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[    7.457162] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000
[    7.461583] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f]
[    7.486916] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    7.489001] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    7.491014] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    7.493136] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    7.494721] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    7.499744] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    7.500520] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    7.500680] pci 0000:00:02.0: vgaarb: bridge control possible
[    7.501582] vgaarb: loaded
[    7.504882] SCSI subsystem initialized
[    7.505814] ACPI: bus type USB registered
[    7.507715] usbcore: registered new interface driver usbfs
[    7.508642] usbcore: registered new interface driver hub
[    7.510805] usbcore: registered new device driver usb
[    7.511754] pps_core: LinuxPPS API ver. 1 registered
[    7.512548] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    7.513584] PTP clock support registered
[    7.515869] EDAC MC: Ver: 3.0.0
[    7.520720] PCI: Using ACPI for IRQ routing
[    7.521550] PCI: pci_cache_line_size set to 64 bytes
[    7.524741] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    7.525530] e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff]
[    7.528061] NetLabel: Initializing
[    7.528548] NetLabel:  domain hash size = 128
[    7.529526] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    7.530655] NetLabel:  unlabeled traffic allowed by default
[    7.535744] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    7.536552] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    7.545685] clocksource: Switched to clocksource kvm-clock
[    7.754336] VFS: Disk quotas dquot_6.6.0
[    7.767655] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    7.792371] *** VALIDATE hugetlbfs ***
[    7.808938] pnp: PnP ACPI init
[    7.821286] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    7.837061] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    7.853152] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    7.868846] pnp 00:03: [dma 2]
[    7.877750] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    7.894385] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    7.909945] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    7.925944] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    7.942932] pnp: PnP ACPI: found 7 devices
[    8.013786] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    8.037110] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    8.052893] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    8.068453] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    8.085950] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
[    8.103615] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[    8.121433] NET: Registered protocol family 2
[    8.134852] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes)
[    8.155889] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[    8.176118] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[    8.193683] TCP: Hash tables configured (established 16384 bind 16384)
[    8.210594] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[    8.226415] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[    8.244811] NET: Registered protocol family 1
[    8.260779] RPC: Registered named UNIX socket transport module.
[    8.276110] RPC: Registered udp transport module.
[    8.288786] RPC: Registered tcp transport module.
[    8.301651] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    8.318817] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    8.334106] pci 0000:00:00.0: quirk_passive_release+0x0/0xa0 took 15056 usecs
[    8.351974] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    8.367298] pci 0000:00:00.0: quirk_natoma+0x0/0x30 took 14879 usecs
[    8.383609] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    8.399131] pci 0000:00:01.0: quirk_isa_dma_hangs+0x0/0x30 took 15133 usecs
[    8.417305] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    8.439119] pci 0000:00:02.0: pci_fixup_video+0x0/0x110 took 21520 usecs
[    8.456417] PCI: CLS 0 bytes, default 64
[    8.468368] Trying to unpack rootfs image as initramfs...
[   40.669773] Freeing initrd memory: 187952K
[   40.686453] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x20974986637, max_idle_ns: 440795286310 ns
[   40.754871] Initialise system trusted keyrings
[   40.766670] Key type blacklist registered
[   40.778138] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[   40.812808] zbud: loaded
[   40.825390] 9p: Installing v9fs 9p2000 file system support
[   40.854580] NET: Registered protocol family 38
[   40.867312] Key type asymmetric registered
[   40.880733] Asymmetric key parser 'x509' registered
[   40.893917] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[   40.915388] io scheduler mq-deadline registered
[   40.928034] io scheduler kyber registered
[   40.940918] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[   40.959002] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[   40.974660] intel_idle: Please enable MWAIT in BIOS SETUP
[   40.990928] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   41.015062] ACPI: Power Button [PWRF]
[   41.030366] ERST DBG: ERST support is disabled.
[   41.271407] PCI Interrupt Link [LNKD] enabled at IRQ 11
[   41.521150] PCI Interrupt Link [LNKA] enabled at IRQ 10
[   41.548031] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[   41.633732] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   41.717146] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   41.742404] Non-volatile memory driver v1.3
[   41.754059] Linux agpgart interface v0.103
[   41.778872] virtio_blk virtio0: [vda] 536870912 512-byte logical blocks (275 GB/256 GiB)
[   41.818294] virtio_blk virtio1: [vdb] 536870912 512-byte logical blocks (275 GB/256 GiB)
[   41.843920] rdac: device handler registered
[   41.855711] hp_sw: device handler registered
[   41.866609] emc: device handler registered
[   41.877888] alua: device handler registered
[   41.888668] MACsec IEEE 802.1AE
[   41.897940] libphy: Fixed MDIO Bus: probed
[   41.910340] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[   41.927612] e1000: Copyright (c) 1999-2006 Intel Corporation.
[   42.156993] PCI Interrupt Link [LNKC] enabled at IRQ 11
[   43.423837] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[   43.438756] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[   43.455297] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[   43.470742] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[   43.486828] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k
[   43.514679] igb: Copyright (c) 2007-2014 Intel Corporation.
[   43.536039] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k
[   43.565195] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[   43.594815] i40e: Intel(R) Ethernet Connection XL710 Network Driver - version 2.8.20-k
[   43.612647] i40e: Copyright (c) 2013 - 2014 Intel Corporation.
[   43.628904] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI
[   43.647580] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[   43.683798] usbcore: registered new interface driver catc
[   43.696764] usbcore: registered new interface driver kaweth
[   43.713458] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver
[   43.735621] usbcore: registered new interface driver pegasus
[   43.752766] usbcore: registered new interface driver rtl8150
[   43.768351] usbcore: registered new interface driver asix
[   43.783650] usbcore: registered new interface driver cdc_ether
[   43.797651] usbcore: registered new interface driver cdc_eem
[   43.812483] usbcore: registered new interface driver dm9601
[   43.826979] usbcore: registered new interface driver smsc75xx
[   43.841843] usbcore: registered new interface driver smsc95xx
[   43.856688] usbcore: registered new interface driver gl620a
[   43.871146] usbcore: registered new interface driver net1080
[   43.885915] usbcore: registered new interface driver plusb
[   43.900087] usbcore: registered new interface driver rndis_host
[   43.915672] usbcore: registered new interface driver cdc_subset
[   43.930898] usbcore: registered new interface driver zaurus
[   43.945909] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[   43.966459] usbcore: registered new interface driver int51x1
[   43.981011] usbcore: registered new interface driver ipheth
[   43.993125] usbcore: registered new interface driver sierra_net
[   44.006343] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   44.023879] ehci-pci: EHCI PCI platform driver
[   44.059654] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   44.072818] ohci-pci: OHCI PCI platform driver
[   44.082953] uhci_hcd: USB Universal Host Controller Interface driver
[   44.096951] usbcore: registered new interface driver usbserial_generic
[   44.114715] usbserial: USB Serial support registered for generic
[   44.128292] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   44.155867] serio: i8042 KBD port at 0x60,0x64 irq 1
[   44.170774] serio: i8042 AUX port at 0x60,0x64 irq 12
[   44.186818] mousedev: PS/2 mouse device common for all mice
[   44.205150] rtc_cmos 00:00: RTC can wake from S4
[   44.207842] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   44.250718] rtc_cmos 00:00: registered as rtc0
[   44.262916] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[   44.311421] i6300ESB timer 0000:00:06.0: initialized. heartbeat=30 sec (nowayout=0)
[   44.311463] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
[   44.330285] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[   44.369939] iTCO_vendor_support: vendor-support=0
[   44.381738] intel_pstate: CPU model not supported
[   44.396804] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[   44.400406] hidraw: raw HID events driver (C) Jiri Kosina
[   44.441886] usbcore: registered new interface driver usbhid
[   44.456920] usbhid: USB HID core driver
[   44.469918] drop_monitor: Initializing network drop monitor service
[   44.486673] Initializing XFRM netlink socket
[   44.499993] NET: Registered protocol family 10
[   44.514860] Segment Routing with IPv6
[   44.525693] NET: Registered protocol family 17
[   44.540915] 9pnet: Installing 9P2000 support
[   44.552710] mpls_gso: MPLS GSO support
[   44.568390] mce: Using 10 MCE banks
[   44.578666] IPI shorthand broadcast: enabled
[   44.590718] ... APIC ID:      00000000 (0)
[   44.591555] ... APIC VERSION: 00050014
[   44.591555] 0000000000000000000000000000000000000000000000000000000000000000
[   44.591555] 0000000000000000000000000000000000000000000000000000000000000000
[   44.591555] 0000000000000000000000000000000000000000000000000000000000001000
[   44.660293] number of MP IRQ sources: 15.
[   44.671836] number of IO-APIC #0 registers: 24.
[   44.684351] testing the IO APIC.......................
[   44.697933] IO APIC #0......
[   44.706873] .... register #00: 00000000
[   44.717693] .......    : physical APIC id: 00
[   44.731793] .......    : Delivery Type: 0
[   44.746167] .......    : LTS          : 0
[   44.759643] .... register #01: 00170011
[   44.771115] .......     : max redirection entries: 17
[   44.784709] .......     : PRQ implemented: 0
[   44.796435] .......     : IO APIC version: 11
[   44.808166] .... register #02: 00000000
[   44.818797] .......     : arbitration: 00
[   44.829945] .... IRQ redirection table:
[   44.840704] IOAPIC 0:
[   44.848443]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   44.869036]  pin01, enabled , edge , high, V(25), IRR(0), S(0), physical, D(01), M(0)
[   44.890002]  pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[   44.910737]  pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   44.932185]  pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   44.953065]  pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   44.973508]  pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   44.991783]  pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.010351]  pin08, enabled , edge , high, V(25), IRR(0), S(0), physical, D(00), M(0)
[   45.028727]  pin09, enabled , level, high, V(21), IRR(0), S(0), physical, D(01), M(0)
[   45.048080]  pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.067064]  pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.087421]  pin0c, enabled , edge , high, V(24), IRR(0), S(0), physical, D(00), M(0)
[   45.113176]  pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.137195]  pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.162798]  pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.188276]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.212190]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.235393]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.261826]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.287200]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.312445]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.337502]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.362770]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   45.387693] IRQ to pin mappings:
[   45.427163] IRQ0 -> 0:2
[   45.436793] IRQ1 -> 0:1
[   45.446609] IRQ3 -> 0:3
[   45.456168] IRQ4 -> 0:4
[   45.465766] IRQ5 -> 0:5
[   45.475354] IRQ6 -> 0:6
[   45.484878] IRQ7 -> 0:7
[   45.494606] IRQ8 -> 0:8
[   45.501900] IRQ9 -> 0:9
[   45.509800] IRQ10 -> 0:10
[   45.518127] IRQ11 -> 0:11
[   45.526615] IRQ12 -> 0:12
[   45.534944] IRQ13 -> 0:13
[   45.543372] IRQ14 -> 0:14
[   45.551699] IRQ15 -> 0:15
[   45.560030] .................................... done.
[   45.573963] sched_clock: Marking stable (42223339392, 3350301373)->(48022017182, -2448376417)
[   45.614446] registered taskstats version 1
[   45.628706] Loading compiled-in X.509 certificates
[   45.671164] Loaded X.509 cert 'Build time autogenerated kernel key: 6c02f7b8f05e56552a779ffa004cbf1c35e0788b'
[   45.702430] zswap: loaded using pool lzo/zbud
[   45.771046] Key type big_key registered
[   45.810866] Key type encrypted registered
[   45.824904] ima: No TPM chip found, activating TPM-bypass!
[   45.842729] ima: Allocated hash algorithm: sha1
[   45.857995] No architecture policies found
[   45.872352] evm: Initialising EVM extended attributes:
[   45.888937] evm: security.selinux
[   45.900863] evm: security.apparmor
[   45.913051] evm: security.ima
[   45.924070] evm: security.capability
[   45.936668] evm: HMAC attrs: 0x1
[   45.955961] PM:   Magic number: 11:599:387
[   45.971394] rtc_cmos 00:00: setting system clock to 2019-07-01T16:23:15 UTC (1561998195)
[   46.049039] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   46.081796] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   46.087876] Sending DHCP requests ., OK
[   46.144424] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[   46.166657] IP-Config: Complete:
[   46.178870]      device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[   46.208879]      host=vm-snb-yocto-ia32-1101, domain=, nis-domain=(none)
[   46.229622]      bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=
[   46.229625]      nameserver0=10.0.2.3
[   46.314688] Freeing unused decrypted memory: 2040K
[   46.339039] Freeing unused kernel image memory: 2508K
[   46.356473] Write protecting the kernel read-only data: 22528k
[   46.382358] Freeing unused kernel image memory: 2012K
[   46.404437] Freeing unused kernel image memory: 1920K
[   46.428864] rodata_test: all tests were successful
[   46.445590] Run /init as init process
Starting udev
[   46.774292] udevd[196]: starting version 3.2.7
[   46.792082] random: udevd: uninitialized urandom read (16 bytes read)
[   46.812429] random: udevd: uninitialized urandom read (16 bytes read)
[   46.832454] random: udevd: uninitialized urandom read (16 bytes read)
[   46.862727] udevd[196]: specified group 'kvm' unknown
[   46.896194] udevd[197]: starting eudev-3.2.7
[   47.008656] udevd[197]: specified group 'kvm' unknown
[   47.289065] parport_pc 00:04: reported by Plug and Play ACPI
[   47.313144] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[   47.451620] Floppy drive(s): fd0 is 2.88M AMI BIOS
[   47.482116] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   47.650881] libata version 3.00 loaded.
[   47.685856] input: PC Speaker as /devices/platform/pcspkr/input/input5
[   47.709926] FDC 0 is a S82078B
[   47.845156] ata_piix 0000:00:01.1: version 2.13
[   47.997054] scsi host0: ata_piix
[   48.024058] scsi host1: ata_piix
[   48.055524] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14
[   48.078789] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15
[   48.270318] ata2.01: NODEV after polling detection
[   48.296694] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[   48.341422] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[   48.447775] Error: Driver 'pcspkr' is already registered, aborting...
[   48.520520] ppdev: user-space parallel port driver
[   48.551092] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 0: 0xfd000000 -> 0xfdffffff
[   48.605399] bochs-drm 0000:00:02.0: remove_conflicting_pci_framebuffers: bar 2: 0xfebf0000 -> 0xfebf0fff
[   48.645990] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console
[   48.688929] Console: switching to colour dummy device 80x25
[   48.704451] [drm] Found bochs VGA, ID 0xb0c0.
[   48.712296] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[   48.726848] [TTM] Zone  kernel: Available graphics memory: 1017348 KiB
[   48.739998] [TTM] Initializing pool allocator
[   48.749011] [TTM] Initializing DMA pool allocator
[   48.760453] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[   48.799662] fbcon: bochs-drmdrmfb (fb0) is primary device
[   48.809766] Console: switching to colour frame buffer device 128x48
[   48.852484] bochs-drm 0000:00:02.0: fb0: bochs-drmdrmfb frame buffer device
[   48.879380] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[   48.893727] cdrom: Uniform CD-ROM driver Revision: 3.20
[   48.912945] sr 1:0:0:0: Attached scsi CD-ROM sr0
[   50.313823] urandom_read: 4 callbacks suppressed
[   50.313827] random: dd: uninitialized urandom read (512 bytes read)
LKP: HOSTNAME vm-snb-yocto-ia32-1101, MAC 52:54:00:12:34:56, kernel 5.2.0-rc5-00491-g91b46d2 1, serial console /dev/ttyS0
Poky (Yocto Project Reference Distro) 2.7+snapshot vm-snb-yocto-ia32-1101 /dev/ttyS0
[   51.893306] /lkp/lkp/src/bin/run-lkp
[   51.893306] RESULT_ROOT=/result/locktorture/300s-cpuhotplug/vm-snb-yocto-ia32/yocto-minimal-i386-2019-05-20.cgz/x86_64-rhel-7.6/gcc-7/91b46d2112940bcef670d71a80e57673e5ec904f/13
[   51.893306] job=/lkp/jobs/scheduled/vm-snb-yocto-ia32-1101/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-91-20190701-27635-1d7csr0-13.yaml
[   51.893306] 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/91b46d2112940bcef670d71a80e57673e5ec904f/13
[   51.893306] run-job /lkp/jobs/scheduled/vm-snb-yocto-ia32-1101/locktorture-300s-cpuhotplug-yocto-minimal-i386-2019-05-20.cgz-91-20190701-27635-1d7csr0-13.yaml
[   53.353008] 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
[   53.387612] spin_lock-torture: Creating torture_onoff task
[   53.432927] spin_lock-torture: Creating torture_shuffle task
[   53.463065] spin_lock-torture: torture_onoff task started
[   53.483443] spin_lock-torture: torture_onoff begin holdoff
[   53.484710] spin_lock-torture: Creating torture_stutter task
[   53.529439] spin_lock-torture: torture_shuffle task started
[   53.552379] spin_lock-torture: Creating lock_torture_writer task
[   53.604449] spin_lock-torture: torture_stutter task started
[   53.608465] spin_lock-torture: Creating lock_torture_writer task
[   53.638967] spin_lock-torture: lock_torture_writer task started
[   53.655640] spin_lock-torture: Creating lock_torture_writer task
[   53.712738] spin_lock-torture: lock_torture_writer task started
[   53.749349] spin_lock-torture: Creating lock_torture_writer task
[   53.781792] spin_lock-torture: lock_torture_writer task started
[   53.815423] spin_lock-torture: Creating lock_torture_stats task
[   53.815623] spin_lock-torture: lock_torture_writer task started
[   53.864123] spin_lock-torture: lock_torture_stats task started
[   54.055696] 2019-07-01 16:23:22 modprobe locktorture onoff_interval=3 onoff_holdoff=30
[   54.055696] 2019-07-01 16:23:23 sleep 300
[   84.030932] spin_lock-torture: torture_onoff end holdoff
[   84.097094] Unregister pv shared memory for cpu 0
[   84.120964] smpboot: CPU 0 is now offline
[   90.238994] smpboot: Booting Node 0 Processor 0 APIC 0x0
[   90.255855] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[   90.256060] masked ExtINT on CPU#0
[   90.306373] KVM setup async PF for cpu 0
[   90.323706] kvm-stealtime: cpu 0, msr 65e2d040
[   93.389724] Unregister pv shared memory for cpu 0
[   93.432630] smpboot: CPU 0 is now offline
[   99.583187] smpboot: Booting Node 0 Processor 0 APIC 0x0
[   99.619844] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[   99.620099] masked ExtINT on CPU#0
[   99.682528] KVM setup async PF for cpu 0
[   99.698921] kvm-stealtime: cpu 0, msr 65e2d040
[  102.800904] Unregister pv shared memory for cpu 0
[  102.848848] smpboot: CPU 0 is now offline
[  114.238827] Writes:  Total: 15075179  Max/Min: 0/0   Fail: 0 
[  114.943107] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  114.965883] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  114.966138] masked ExtINT on CPU#0
[  115.020190] KVM setup async PF for cpu 0
[  115.037488] kvm-stealtime: cpu 0, msr 65e2d040
[  118.093894] Unregister pv shared memory for cpu 1
[  118.131040] smpboot: CPU 1 is now offline
[  118.157960] random: fast init done
[  121.264978] x86: Booting SMP configuration:
[  121.278586] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  121.313324] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  121.313516] masked ExtINT on CPU#1
[  121.361357] KVM setup async PF for cpu 1
[  121.375674] kvm-stealtime: cpu 1, msr 65f2d040
[  124.435900] Unregister pv shared memory for cpu 0
[  124.462588] smpboot: CPU 0 is now offline
[  127.551055] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  127.614378] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  127.614614] masked ExtINT on CPU#0
[  127.807101] KVM setup async PF for cpu 0
[  127.822525] kvm-stealtime: cpu 0, msr 65e2d040
[  130.897283] Unregister pv shared memory for cpu 0
[  130.930191] smpboot: CPU 0 is now offline
[  134.015008] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  134.076168] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  134.076465] masked ExtINT on CPU#0
[  134.126174] KVM setup async PF for cpu 0
[  134.140404] kvm-stealtime: cpu 0, msr 65e2d040
[  137.237330] Unregister pv shared memory for cpu 1
[  137.274014] smpboot: CPU 1 is now offline
[  143.359187] x86: Booting SMP configuration:
[  143.388814] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  143.428933] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  143.429165] masked ExtINT on CPU#1
[  143.469806] KVM setup async PF for cpu 1
[  143.485838] kvm-stealtime: cpu 1, msr 65f2d040
[  146.576784] Unregister pv shared memory for cpu 1
[  146.626872] smpboot: CPU 1 is now offline
[  149.695057] x86: Booting SMP configuration:
[  149.712890] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  149.751273] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  149.751505] masked ExtINT on CPU#1
[  149.796456] KVM setup async PF for cpu 1
[  149.811950] kvm-stealtime: cpu 1, msr 65f2d040
[  152.933899] Unregister pv shared memory for cpu 0
[  152.973399] smpboot: CPU 0 is now offline
[  159.046015] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  159.079639] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  159.079900] masked ExtINT on CPU#0
[  159.150583] KVM setup async PF for cpu 0
[  159.165366] kvm-stealtime: cpu 0, msr 65e2d040
[  162.251798] Unregister pv shared memory for cpu 1
[  162.317626] smpboot: CPU 1 is now offline
[  171.392739] x86: Booting SMP configuration:
[  171.410179] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  171.435775] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  171.436032] masked ExtINT on CPU#1
[  171.479422] KVM setup async PF for cpu 1
[  171.495956] kvm-stealtime: cpu 1, msr 65f2d040
[  174.542737] Unregister pv shared memory for cpu 0
[  174.569465] smpboot: CPU 0 is now offline
[  174.583952] Writes:  Total: 30459655  Max/Min: 0/0   Fail: 0 
[  186.751200] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  186.784383] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  186.784636] masked ExtINT on CPU#0
[  186.851291] KVM setup async PF for cpu 0
[  186.867755] kvm-stealtime: cpu 0, msr 65e2d040
[  189.967747] Unregister pv shared memory for cpu 1
[  189.996041] smpboot: CPU 1 is now offline
[  196.095023] x86: Booting SMP configuration:
[  196.105690] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  196.137027] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  196.137355] masked ExtINT on CPU#1
[  196.179003] KVM setup async PF for cpu 1
[  196.195916] kvm-stealtime: cpu 1, msr 65f2d040
[  199.252812] Unregister pv shared memory for cpu 1
[  199.272877] smpboot: CPU 1 is now offline
[  202.302994] x86: Booting SMP configuration:
[  202.317256] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  202.354823] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  202.355054] masked ExtINT on CPU#1
[  202.399187] KVM setup async PF for cpu 1
[  202.415363] kvm-stealtime: cpu 1, msr 65f2d040
[  205.517753] Unregister pv shared memory for cpu 0
[  205.563071] smpboot: CPU 0 is now offline
[  208.639135] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  208.653197] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  208.653519] masked ExtINT on CPU#0
[  208.717427] KVM setup async PF for cpu 0
[  208.735187] kvm-stealtime: cpu 0, msr 65e2d040
[  211.792675] Unregister pv shared memory for cpu 0
[  211.811408] smpboot: CPU 0 is now offline
[  214.847147] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  214.885460] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  214.885760] masked ExtINT on CPU#0
[  214.937401] KVM setup async PF for cpu 0
[  214.949654] kvm-stealtime: cpu 0, msr 65e2d040
[  217.996948] Unregister pv shared memory for cpu 0
[  218.018137] smpboot: CPU 0 is now offline
[  221.055093] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  221.075413] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  222.099696] WARNING: CPU: 0 PID: 0 at arch/x86/kernel/apic/apic.c:1486 setup_local_APIC+0x272/0x410
[  222.152666] Modules linked in: locktorture torture sr_mod cdrom ata_generic pata_acpi bochs_drm ppdev ttm drm_kms_helper snd_pcm syscopyarea snd_timer snd sysfillrect sysimgblt joydev soundcore fb_sys_fops ata_piix crc32c_intel serio_raw drm pcspkr libata i2c_piix4 floppy parport_pc parport
[  222.201174] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.2.0-rc5-00491-g91b46d2 #1
[  222.214921] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[  222.229857] RIP: 0010:setup_local_APIC+0x272/0x410
[  222.237887] Code: 00 8b 0d 59 7a 7d 01 85 c9 74 78 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 83 fe ff ff <0f> 0b 48 c7 c6 e0 98 a0 99 89 e9 44 89 ea 48 c7 c7 d8 0c cc 99 bb
[  222.270476] RSP: 0000:ffffffff9a003ee0 EFLAGS: 00010086
[  222.279962] RAX: 000000787500c6a0 RBX: 0000000000000020 RCX: 000000008a001800
[  222.293026] RDX: 0000007800000000 RSI: 0000000000000020 RDI: ffffffff9a003ef0
[  222.304591] RBP: 0000000000000000 R08: ffffffff9a94ade2 R09: 0000000000000033
[  222.317780] R10: ffff9ec33ff71980 R11: 0000000000000000 R12: 00000000000000f0
[  222.330614] R13: 0000000000001000 R14: fffffffffffd0796 R15: 0000000000000000
[  222.341834] FS:  0000000000000000(0000) GS:ffff9ec325e00000(0000) knlGS:0000000000000000
[  222.356407] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  222.365967] CR2: 00000000f7ef8710 CR3: 0000000011a0a000 CR4: 00000000000006f0
[  222.378611] Call Trace:
[  222.383014]  apic_ap_setup+0xa/0x20
[  222.389337]  start_secondary+0x78/0x1e0
[  222.395655]  secondary_startup_64+0xb6/0xc0
[  222.402691] ---[ end trace 484bbeaff9f01564 ]---
[  222.410302] [apic_pending_intr_clear()]: queued = 0x1000 acked = 0
[  222.410302] 
[  222.422847] masked ExtINT on CPU#0
[  222.462363] KVM setup async PF for cpu 0
[  222.473352] kvm-stealtime: cpu 0, msr 65e2d040
[  225.554209] Unregister pv shared memory for cpu 0
[  225.599751] smpboot: CPU 0 is now offline
[  228.671025] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  228.720337] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  228.720602] masked ExtINT on CPU#0
[  228.835461] KVM setup async PF for cpu 0
[  228.851956] kvm-stealtime: cpu 0, msr 65e2d040
[  231.951802] Unregister pv shared memory for cpu 0
[  231.991654] smpboot: CPU 0 is now offline
[  235.070604] Writes:  Total: 46216442  Max/Min: 0/0   Fail: 0 
[  238.143475] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  238.163833] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  238.164094] masked ExtINT on CPU#0
[  238.221202] KVM setup async PF for cpu 0
[  238.237765] kvm-stealtime: cpu 0, msr 65e2d040
[  241.291004] Unregister pv shared memory for cpu 0
[  241.336662] smpboot: CPU 0 is now offline
[  244.415082] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  244.432125] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  244.432451] masked ExtINT on CPU#0
[  244.486202] KVM setup async PF for cpu 0
[  244.501788] kvm-stealtime: cpu 0, msr 65e2d040
[  247.566796] Unregister pv shared memory for cpu 0
[  247.600108] smpboot: CPU 0 is now offline
[  250.687168] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  250.707819] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  250.708053] masked ExtINT on CPU#0
[  250.777293] KVM setup async PF for cpu 0
[  250.793335] kvm-stealtime: cpu 0, msr 65e2d040
[  253.838842] Unregister pv shared memory for cpu 0
[  253.867787] smpboot: CPU 0 is now offline
[  259.902993] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  259.916553] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  259.916786] masked ExtINT on CPU#0
[  259.969227] KVM setup async PF for cpu 0
[  259.981805] kvm-stealtime: cpu 0, msr 65e2d040
[  263.058201] Unregister pv shared memory for cpu 0
[  263.102661] smpboot: CPU 0 is now offline
[  266.175148] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  266.195421] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  266.195637] masked ExtINT on CPU#0
[  266.264385] KVM setup async PF for cpu 0
[  266.280779] kvm-stealtime: cpu 0, msr 65e2d040
[  269.367823] Unregister pv shared memory for cpu 0
[  269.418634] smpboot: CPU 0 is now offline
[  278.463196] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  278.482360] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  278.482592] masked ExtINT on CPU#0
[  278.554488] KVM setup async PF for cpu 0
[  278.571080] kvm-stealtime: cpu 0, msr 65e2d040
[  281.679422] Unregister pv shared memory for cpu 1
[  281.740143] smpboot: CPU 1 is now offline
[  290.815177] x86: Booting SMP configuration:
[  290.832769] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  290.873848] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  290.874099] masked ExtINT on CPU#1
[  290.915225] KVM setup async PF for cpu 1
[  290.931105] kvm-stealtime: cpu 1, msr 65f2d040
[  294.030128] Unregister pv shared memory for cpu 1
[  294.080034] smpboot: CPU 1 is now offline
[  295.486823] Writes:  Total: 62230700  Max/Min: 0/0   Fail: 0 
[  297.151001] x86: Booting SMP configuration:
[  297.188788] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  297.231789] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  297.232041] masked ExtINT on CPU#1
[  297.273234] KVM setup async PF for cpu 1
[  297.289528] kvm-stealtime: cpu 1, msr 65f2d040
[  300.365860] Unregister pv shared memory for cpu 1
[  300.416177] smpboot: CPU 1 is now offline
[  306.495321] x86: Booting SMP configuration:
[  306.541732] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  306.585498] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  306.585851] masked ExtINT on CPU#1
[  306.634360] KVM setup async PF for cpu 1
[  306.650564] kvm-stealtime: cpu 1, msr 65f2d040
[  309.707908] Unregister pv shared memory for cpu 0
[  309.743756] smpboot: CPU 0 is now offline
[  315.839106] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  315.874557] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  315.874814] masked ExtINT on CPU#0
[  315.930427] KVM setup async PF for cpu 0
[  315.948117] kvm-stealtime: cpu 0, msr 65e2d040
[  318.988327] Unregister pv shared memory for cpu 1
[  319.020383] smpboot: CPU 1 is now offline
[  322.047347] x86: Booting SMP configuration:
[  322.078777] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  322.121178] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  322.121345] masked ExtINT on CPU#1
[  322.161415] KVM setup async PF for cpu 1
[  322.178364] kvm-stealtime: cpu 1, msr 65f2d040
[  325.263018] Unregister pv shared memory for cpu 1
[  325.284907] smpboot: CPU 1 is now offline
[  337.370834] x86: Booting SMP configuration:
[  337.391580] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  337.420834] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  337.421089] masked ExtINT on CPU#1
[  337.464162] KVM setup async PF for cpu 1
[  337.480550] kvm-stealtime: cpu 1, msr 65f2d040
[  340.562385] Unregister pv shared memory for cpu 0
[  340.627142] smpboot: CPU 0 is now offline
[  343.679075] smpboot: Booting Node 0 Processor 0 APIC 0x0
[  343.699413] kvm-clock: cpu 0, msr 12601001, secondary cpu clock
[  343.699667] masked ExtINT on CPU#0
[  343.769272] KVM setup async PF for cpu 0
[  343.785320] kvm-stealtime: cpu 0, msr 65e2d040
[  346.830847] Unregister pv shared memory for cpu 1
[  346.892198] smpboot: CPU 1 is now offline
[  349.951155] x86: Booting SMP configuration:
[  349.982197] smpboot: Booting Node 0 Processor 1 APIC 0x1
[  350.025609] kvm-clock: cpu 1, msr 12601041, secondary cpu clock
[  350.025866] masked ExtINT on CPU#1
[  350.066367] KVM setup async PF for cpu 1
[  350.082672] kvm-stealtime: cpu 1, msr 65f2d040
[  353.173467] Unregister pv shared memory for cpu 1
[  353.224660] smpboot: CPU 1 is now offline
[  354.085272] spin_lock-torture: Stopping torture_shuffle task
[  354.105395] spin_lock-torture: Stopping torture_stutter
[  354.124167] spin_lock-torture: Stopping torture_shuffle
[  354.143736] spin_lock-torture: Stopping lock_torture_writer
[  354.163180] spin_lock-torture: Stopping torture_stutter task
[  354.183333] spin_lock-torture: Stopping lock_torture_writer
[  354.202657] spin_lock-torture: Stopping lock_torture_writer
[  354.222904] spin_lock-torture: Stopping torture_onoff task
[  354.242156] spin_lock-torture: Stopping torture_onoff
[  354.261271] spin_lock-torture: Stopping lock_torture_writer task
[  354.281140] spin_lock-torture: Stopping lock_torture_writer
[  354.339080] spin_lock-torture: Stopping lock_torture_writer task
[  354.371330] spin_lock-torture: Stopping lock_torture_writer task
[  354.391193] spin_lock-torture: Stopping lock_torture_writer task
[  354.410438] spin_lock-torture: Stopping lock_torture_stats task
[  354.429856] Writes:  Total: 77018034  Max/Min: 0/0   Fail: 0 
[  354.446836] spin_lock-torture: Stopping lock_torture_stats
[  354.462445] Writes:  Total: 77018034  Max/Min: 0/0   Fail: 0 
[  354.477682] 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
[  362.502055] sysrq: Emergency Sync
[  362.517004] Emergency Sync complete
[  362.532981] sysrq: Resetting

Elapsed time: 360

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

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

append=(
	ip=::::vm-snb-yocto-ia32-1101::dhcp
	root=/dev/ram0
	user=lkp
	job=/job-script
	ARCH=x86_64
	kconfig=x86_64-rhel-7.6
	branch=feng/master
	commit=91b46d2112940bcef670d71a80e57673e5ec904f
	BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.6/gcc-7/91b46d2112940bcef670d71a80e57673e5ec904f/vmlinuz-5.2.0-rc5-00491-g91b46d2
	lapic=notscdeadline
	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/91b46d2112940bcef670d71a80e57673e5ec904f/13
	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[*]}"
