[migration_cpu_stop] WARNING: CPU: 0 PID: 11 at arch/x86/kernel/smp.c:128 native_smp_send_reschedule+0x69/0x9e

From: Fengguang Wu
Date: Mon Oct 30 2017 - 02:45:51 EST

Next message: Christoffer Dall: "Re: [PATCH v5 01/26] irqchip/gic-v3-its: Setup VLPI properties at map time"
Previous message: Oleksandr Andrushchenko: "[RFC] ALSA: vsnd: Add Xen para-virtualized frontend driver"
In reply to: Peter Zijlstra: "Re: Linux 4.14-rc6: WARNING: CPU: 9 PID: 5377 at arch/x86/events/intel/core.c:2228 intel_pmu_handle_irq+0x4a8/0x4c0"
Next in thread: Fengguang Wu: "[haswell_crtc_enable] WARNING: CPU: 3 PID: 109 at drivers/gpu/drm/drm_vblank.c:1066 drm_wait_one_vblank+0x18f/0x1a0 [drm]"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

On Sun, Oct 29, 2017 at 11:51:55PM +0100, Fengguang Wu wrote:

Hi Linus,

Up to now we see the below boot error/warnings when testing v4.14-rc6.

They hit the RC release mainly due to various imperfections in 0day's
auto bisection. So I manually list them here and CC the likely easy to
debug ones to the corresponding maintainers in the followup emails.

boot_successes: 4700
boot_failures: 247

[...]

WARNING:at_arch/x86/kernel/smp.c:#native_smp_send_reschedule: 1

That's bisected to an update of RCU torture parameter:

commit 492b95e59735998312f678d77a2d5fe20af6b0b9
Author: Paul E. McKenney <paulmck@xxxxxxxxxxxxxxxxxx>
AuthorDate: Fri Apr 21 16:09:15 2017 -0700
Commit: Paul E. McKenney <paulmck@xxxxxxxxxxxxxxxxxx>
CommitDate: Thu Jun 8 08:25:31 2017 -0700

rcuperf: Set more user-friendly defaults

Common-case use of rcuperf must set rcuperf.nreaders=0 and if not built
as a module, rcuperf.shutdown. This commit therefore sets the default
for rcuperf.nreaders to zero and sets the default for rcuperf.shutdown
to zero if rcuperf is built as a module and to one otherwise.

Signed-off-by: Paul E. McKenney <paulmck@xxxxxxxxxxxxxxxxxx>
---
kernel/rcu/rcuperf.c | 5 +++--
1 file changed, 3 insertions(+), 2 deletions(-)

diff --git a/kernel/rcu/rcuperf.c b/kernel/rcu/rcuperf.c
index 5158ddba67162..49c8ed6bd2fda 100644
--- a/kernel/rcu/rcuperf.c
+++ b/kernel/rcu/rcuperf.c
@@ -63,9 +63,10 @@ torture_param(bool, gp_async, false, "Use asynchronous GP wait primitives");
torture_param(int, gp_async_max, 1000, "Max # outstanding waits per reader");
torture_param(bool, gp_exp, false, "Use expedited GP wait primitives");
torture_param(int, holdoff, 10, "Holdoff time before test start (s)");
-torture_param(int, nreaders, -1, "Number of RCU reader threads");
+torture_param(int, nreaders, 0, "Number of RCU reader threads");
torture_param(int, nwriters, -1, "Number of RCU updater threads");
-torture_param(bool, shutdown, false, "Shutdown at end of performance tests.");
+torture_param(bool, shutdown, !IS_ENABLED(MODULE),
+ "Shutdown at end of performance tests.");
torture_param(bool, verbose, true, "Enable verbose debugging printk()s");

static char *perf_type = "rcu";

Attached the dmesg. Here are the call traces -- note that the first
WARNING only shows up in latest mainline tree, so might be irrelevant
to the above commit.

[ 24.691613] rcu-perf: 1 writer-duration: 97 13680809
[ 24.692466] rcu-perf: 1 writer-duration: 98 9561892
[ 24.693192] rcu-perf: 1 writer-duration: 99 7970345
[ 24.693816] rcu-perf: 1 writer-duration: 100 9788023
[ 24.700210] no ifx modem active;
[ 24.701420] ACPI: Preparing to enter system sleep state S5
[ 24.704534] reboot: Power down
[ 24.705645] acpi_power_off called
[ 24.713350] ------------[ cut here ]------------
[ 24.713804] WARNING: CPU: 0 PID: 11 at kernel/sched/core.c:1179 set_task_cpu+0x157/0x285
[ 24.714779] Modules linked in: i2c_piix4 input_leds psmouse i2c_core button
[ 24.715437] CPU: 0 PID: 11 Comm: migration/0 Not tainted 4.14.0-rc6-00077-g11224e1 #1
[ 24.716188] task: ffff880032955000 task.stack: ffff88003294c000
[ 24.716757] RIP: 0010:set_task_cpu+0x157/0x285
[ 24.717193] RSP: 0018:ffff88003294fe08 EFLAGS: 00010002
[ 24.717746] RAX: 0000000000000001 RBX: ffff880031449000 RCX: 0000000000000000
[ 24.718416] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff82e2ad60
[ 24.719091] RBP: ffff88003294fe20 R08: 0000000000000000 R09: 0000000000000000
[ 24.719789] R10: ffff880014483b60 R11: 0000000000000002 R12: 0000000000000001
[ 24.720477] R13: 0000000000000001 R14: ffff88003294fe68 R15: ffff8800347eb300
[ 24.721155] FS: 0000000000000000(0000) GS:ffff880034600000(0000) knlGS:0000000000000000
[ 24.721947] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 24.722479] CR2: 00007f1cdeec8fd0 CR3: 000000003722d000 CR4: 00000000000006b0
[ 24.723144] Call Trace:
[ 24.723388] move_queued_task+0x8c/0x108
[ 24.723758] migration_cpu_stop+0x82/0xb7
[ 24.724131] ? sched_ttwu_pending+0x78/0x78
[ 24.724748] ? sort_range+0x17/0x17
[ 24.725214] cpu_stopper_thread+0x87/0x14c
[ 24.725785] ? sort_range+0x17/0x17
[ 24.726194] smpboot_thread_fn+0x2a9/0x2c0
[ 24.726718] kthread+0xf8/0x100
[ 24.727113] ? kthread_unpark+0x5a/0x5a
[ 24.727532] ret_from_fork+0x25/0x30
[ 24.727856] Code: 0f a3 2d 85 d2 e3 01 41 0f 92 c5 41 83 f5 01 31 c9 31 d2 45 0f b6 ed 48 c7 c7 60 ad e2 82 44 89 ee e8 53 24 08 00 45 85 ed 74 02 <0f> ff 31 c9 31 d2 44 89 ee 48 c7 c7 30 ad e2 82 45 31 ed e8 36 [ 24.729721] ---[ end trace b7ae64ce6fa90a4f ]---
[ 24.730154] sched: Unexpected reschedule of offline CPU#1!
[ 24.730700] ------------[ cut here ]------------
[ 24.731133] WARNING: CPU: 0 PID: 11 at arch/x86/kernel/smp.c:128 native_smp_send_reschedule+0x69/0x9e
[ 24.732162] Modules linked in: i2c_piix4 input_leds psmouse i2c_core button
[ 24.732859] CPU: 0 PID: 11 Comm: migration/0 Tainted: G W 4.14.0-rc6-00077-g11224e1 #1
[ 24.733690] task: ffff880032955000 task.stack: ffff88003294c000
[ 24.734449] RIP: 0010:native_smp_send_reschedule+0x69/0x9e
[ 24.735158] RSP: 0018:ffff88003294fdf8 EFLAGS: 00010092
[ 24.735704] RAX: 000000000000002e RBX: 0000000000000001 RCX: 0000000000000000
[ 24.736382] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff82e32ef0
[ 24.737009] RBP: 0000000000000001 R08: 0000000000000001 R09: 0000000000000000
[ 24.737711] R10: ffff88003294fd30 R11: 0000000000000005 R12: ffff880031449000
[ 24.738370] R13: 0000000000000000 R14: ffff88003294fe68 R15: ffff8800347eb300
[ 24.738997] FS: 0000000000000000(0000) GS:ffff880034600000(0000) knlGS:0000000000000000
[ 24.739754] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 24.740261] CR2: 00007f1cdeec8fd0 CR3: 000000003722d000 CR4: 00000000000006b0
[ 24.740921] Call Trace:
[ 24.741144] check_preempt_curr+0x47/0xc1
[ 24.741534] move_queued_task+0xfa/0x108
[ 24.741886] migration_cpu_stop+0x82/0xb7
[ 24.742240] ? sched_ttwu_pending+0x78/0x78
[ 24.742649] ? sort_range+0x17/0x17
[ 24.742974] cpu_stopper_thread+0x87/0x14c
[ 24.743401] ? sort_range+0x17/0x17
[ 24.743731] smpboot_thread_fn+0x2a9/0x2c0
[ 24.744111] kthread+0xf8/0x100
[ 24.744414] ? kthread_unpark+0x5a/0x5a
[ 24.744769] ret_from_fork+0x25/0x30
[ 24.745116] Code: ed 74 44 b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 50 c4 e1 82 e8 e4 d4 0d 00 89 de 48 c7 c7 76 4c 86 81 31 c0 e8 e1 5e 08 00 <0f> ff 5b 5d b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 20 c4 [ 24.746913] ---[ end trace b7ae64ce6fa90a50 ]---

Elapsed time: 2040
BUG: kernel hang in test stage

Thanks,
Fengguang
early console in setup code
Probing EDD (edd=off to disable)... ok
[ 0.000000] Linux version 4.14.0-rc6-00077-g11224e1 (kbuild@lkp-ne02) (gcc version 4.8.4 (Debian 4.8.4-1)) #1 SMP PREEMPT Sat Oct 28 16:32:05 CST 2017
[ 0.000000] Command line: ip=::::vm-vp-1G-1::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-1G-1/boot-1-debian-x86_64-2016-08-31.cgz-11224e1fc40a6556dc285573ea93dd522f8c563f-20171028-56276-1pkvi7m-3.yaml ARCH=x86_64 kconfig=x86_64-randconfig-n0-10180128 branch=linux-devel/devel-spot-201710172231 commit=11224e1fc40a6556dc285573ea93dd522f8c563f BOOT_IMAGE=/pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/vmlinuz-4.14.0-rc6-00077-g11224e1 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-1G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/3 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
[ 0.000000] x86/fpu: x87 FPU will use FXSAVE
[ 0.000000] e820: BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd3fff] usable
[ 0.000000] BIOS-e820: [mem 0x000000003ffd4000-0x000000003fffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[ 0.000000] debug: ignoring loglevel setting.
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] tsc: Fast TSC calibration using PIT
[ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.000000] e820: last_pfn = 0x3ffd4 max_arch_pfn = 0x400000000
[ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC
[ 0.000000] Scan for SMP in [mem 0x00000000-0x000003ff]
[ 0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[ 0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff]
[ 0.000000] found SMP MP-table at [mem 0x000f6a50-0x000f6a5f] mapped at [fffffffffd200a50]
[ 0.000000] mpc: f6a60-f6b74
[ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[ 0.000000] BRK [0x046ad000, 0x046adfff] PGTABLE
[ 0.000000] BRK [0x046ae000, 0x046aefff] PGTABLE
[ 0.000000] BRK [0x046af000, 0x046affff] PGTABLE
[ 0.000000] BRK [0x046b0000, 0x046b0fff] PGTABLE
[ 0.000000] RAMDISK: [mem 0x34f9f000-0x3ffcffff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F6840 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000003FFE1628 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x000000003FFE147C 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x000000003FFE0040 00143C (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x000000003FFE0000 000040
[ 0.000000] ACPI: APIC 0x000000003FFE1570 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 0x000000003FFE15F0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000)
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffd3fff]
[ 0.000000] NODE_DATA(0) allocated [mem 0x34f60000-0x34f9efff]
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:34ee0001, primary cpu clock
[ 0.000000] kvm-clock: using sched offset of 288428507 cycles
[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffd3fff]
[ 0.000000] Normal empty
[ 0.000000] Device empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.000000] node 0: [mem 0x0000000000100000-0x000000003ffd3fff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd3fff]
[ 0.000000] On node 0 totalpages: 262002
[ 0.000000] DMA zone: 64 pages used for memmap
[ 0.000000] DMA zone: 21 pages reserved
[ 0.000000] DMA zone: 3998 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 4032 pages used for memmap
[ 0.000000] DMA32 zone: 258004 pages, LIFO batch:31
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[ 0.000000] ACPI: IRQ5 used by override.
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] ACPI: IRQ10 used by override.
[ 0.000000] ACPI: IRQ11 used by override.
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[ 0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000)
[ 0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[ 0.000000] random: fast init done
[ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[ 0.000000] percpu: Embedded 502 pages/cpu @ffff880034600000 s2017024 r8192 d30976 u2097152
[ 0.000000] pcpu-alloc: s2017024 r8192 d30976 u2097152 alloc=1*2097152
[ 0.000000] pcpu-alloc: [0] 0 [0] 1
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 3460d680
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 257885
[ 0.000000] Policy zone: DMA32
[ 0.000000] Kernel command line: ip=::::vm-vp-1G-1::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-1G-1/boot-1-debian-x86_64-2016-08-31.cgz-11224e1fc40a6556dc285573ea93dd522f8c563f-20171028-56276-1pkvi7m-3.yaml ARCH=x86_64 kconfig=x86_64-randconfig-n0-10180128 branch=linux-devel/devel-spot-201710172231 commit=11224e1fc40a6556dc285573ea93dd522f8c563f BOOT_IMAGE=/pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/vmlinuz-4.14.0-rc6-00077-g11224e1 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-1G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/3 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
[ 0.000000] sysrq: sysrq always enabled.
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] Memory: 790260K/1048008K available (4915K kernel code, 21248K rwdata, 3412K rodata, 3488K init, 19368K bss, 257748K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000]
[ 0.000000] **********************************************************
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] ** **
[ 0.000000] ** trace_printk() being used. Allocating extra memory. **
[ 0.000000] ** **
[ 0.000000] ** This means that this is a DEBUG kernel and it is **
[ 0.000000] ** unsafe for production use. **
[ 0.000000] ** **
[ 0.000000] ** If you see this message and you are not debugging **
[ 0.000000] ** the kernel, report this immediately to your vendor! **
[ 0.000000] ** **
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] **********************************************************
[ 0.004000] Preemptible hierarchical RCU implementation.
[ 0.004000] RCU event tracing is enabled.
[ 0.004000] RCU dyntick-idle grace-period acceleration is enabled.
[ 0.004000] RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[ 0.004000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[ 0.004000] Tasks RCU enabled.
[ 0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[ 0.004000] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[ 0.004000] console [ttyS0] enabled
[ 0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.004000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.004000] ... MAX_LOCK_DEPTH: 48
[ 0.004000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.004000] ... CLASSHASH_SIZE: 4096
[ 0.004000] ... MAX_LOCKDEP_ENTRIES: 32768
[ 0.004000] ... MAX_LOCKDEP_CHAINS: 65536
[ 0.004000] ... CHAINHASH_SIZE: 32768
[ 0.004000] memory used by lock dependency info: 7871 kB
[ 0.004000] per task-struct memory footprint: 2688 bytes
[ 0.004000] ------------------------
[ 0.004000] | Locking API testsuite:
[ 0.004000] ----------------------------------------------------------------------------
[ 0.004000] | spin |wlock |rlock |mutex | wsem | rsem |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] A-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] double unlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.004000] initialize held: ok | ok | ok | ok | ok | ok | ok |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] recursive read-lock: | ok | |failed|
[ 0.004000] recursive read-lock #2: | ok | |failed|
[ 0.004000] mixed read-write-lock: |failed| |failed|
[ 0.004000] mixed write-read-lock: |failed| |failed|
[ 0.004000] mixed read-lock/lock-write ABBA: |failed| |failed|
[ 0.004000] mixed read-lock/lock-read ABBA: | ok | |failed|
[ 0.004000] mixed write-lock/lock-write ABBA: |failed| |failed|
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] hard-irqs-on + irq-safe-A/12:failed|failed| ok |
[ 0.004000] soft-irqs-on + irq-safe-A/12:failed|failed| ok |
[ 0.004000] hard-irqs-on + irq-safe-A/21:failed|failed| ok |
[ 0.004000] soft-irqs-on + irq-safe-A/21:failed|failed| ok |
[ 0.004000] sirq-safe-A => hirqs-on/12:failed|failed| ok |
[ 0.004000] sirq-safe-A => hirqs-on/21:failed|failed| ok |
[ 0.004000] hard-safe-A + irqs-on/12:failed|failed| ok |
[ 0.004000] soft-safe-A + irqs-on/12:failed|failed| ok |
[ 0.004000] hard-safe-A + irqs-on/21:failed|failed| ok |
[ 0.004000] soft-safe-A + irqs-on/21:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/123:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/123:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/132:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/132:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/213:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/213:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/231:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/231:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/312:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/312:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/321:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/321:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/123:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/123:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/132:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/132:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/213:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/213:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/231:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/231:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/312:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/312:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/321:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/321:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/123:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/123:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/132:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/132:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/213:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/213:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/231:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/231:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/312:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/312:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/321:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/321:failed|failed| ok |
[ 0.004000] hard-irq read-recursion/123: ok |
[ 0.004000] soft-irq read-recursion/123: ok |
[ 0.004000] hard-irq read-recursion/132: ok |
[ 0.004000] soft-irq read-recursion/132: ok |
[ 0.004000] hard-irq read-recursion/213: ok |
[ 0.004000] soft-irq read-recursion/213: ok |
[ 0.004000] hard-irq read-recursion/231: ok |
[ 0.004000] soft-irq read-recursion/231: ok |
[ 0.004000] hard-irq read-recursion/312: ok |
[ 0.004000] soft-irq read-recursion/312: ok |
[ 0.004000] hard-irq read-recursion/321: ok |
[ 0.004000] soft-irq read-recursion/321: ok |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] | Wound/wait tests |
[ 0.004000] ---------------------
[ 0.004000] ww api failures: ok | ok | ok |
[ 0.004000] ww contexts mixing:failed| ok |
[ 0.004000] finishing ww context: ok | ok | ok | ok |
[ 0.004000] locking mismatches: ok | ok | ok |
[ 0.004000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok |
[ 0.004000] spinlock nest unlocked: ok |
[ 0.004000] -----------------------------------------------------
[ 0.004000] |block | try |context|
[ 0.004000] -----------------------------------------------------
[ 0.004000] context:failed| ok | ok |
[ 0.004000] try:failed| ok |failed|
[ 0.004000] block:failed| ok |failed|
[ 0.004000] spinlock:failed| ok |failed|
[ 0.004000] --------------------------------------------------------
[ 0.004000] 153 out of 262 testcases failed, as expected. |
[ 0.004000] ----------------------------------------------------
[ 0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 0.004000] hpet clockevent registered
[ 0.004031] tsc: Detected 2693.508 MHz processor
[ 0.004818] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=10774032)
[ 0.004872] pid_max: default: 32768 minimum: 301
[ 0.005418] ACPI: Core revision 20170728
[ 0.052600] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.054167] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.055027] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.055791] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.056036] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.057725] CPU: Physical Processor ID: 0
[ 0.058170] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.058710] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.060049] Freeing SMP alternatives memory: 12K
[ 0.061067] smpboot: Max logical packages: 2
[ 0.061727] x2apic enabled
[ 0.062221] Switched APIC routing to physical x2apic.
[ 0.062754] enabled ExtINT on CPU#0
[ 0.063767] ENABLING IO-APIC IRQs
[ 0.064053] init IO_APIC IRQs
[ 0.064387] apic 0 pin 0 not connected
[ 0.064819] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:0)
[ 0.065669] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[ 0.066497] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:0)
[ 0.067322] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:0)
[ 0.068000] apic 0 pin 16 not connected
[ 0.068000] apic 0 pin 17 not connected
[ 0.068000] apic 0 pin 18 not connected
[ 0.068000] apic 0 pin 19 not connected
[ 0.068000] apic 0 pin 20 not connected
[ 0.068000] apic 0 pin 21 not connected
[ 0.068000] apic 0 pin 22 not connected
[ 0.068000] apic 0 pin 23 not connected
[ 0.068000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.068000] Using local APIC timer interrupts.
[ 0.068000] calibrating APIC timer ...
[ 0.072000] ... lapic delta = 6251053
[ 0.072000] ... PM-Timer delta = 358019
[ 0.072000] ... PM-Timer result ok
[ 0.072000] ..... delta 6251053
[ 0.072000] ..... mult: 268480682
[ 0.072000] ..... calibration result: 4000673
[ 0.072000] ..... CPU clock speed is 2693.3664 MHz.
[ 0.072000] ..... host bus clock speed is 1000.0673 MHz.
[ 0.072051] smpboot: CPU0: Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3)
[ 0.088076] Performance Events: unsupported p6 CPU model 26 no PMU driver, software events only.
[ 0.096028] Hierarchical SRCU implementation.
[ 0.120020] smp: Bringing up secondary CPUs ...
[ 0.155902] x86: Booting SMP configuration:
[ 0.156027] .... node #0, CPUs: #1
[ 0.004000] kvm-clock: cpu 1, msr 0:34ee0041, secondary cpu clock
[ 0.004000] masked ExtINT on CPU#1
[ 0.164163] KVM setup async PF for cpu 1
[ 0.164756] kvm-stealtime: cpu 1, msr 3480d680
[ 0.165514] smp: Brought up 1 node, 2 CPUs
[ 0.165514] ----------------
[ 0.168040] | NMI testsuite:
[ 0.168522] --------------------
[ 0.169139] remote IPI: ok |
[ 0.169747] local IPI: ok |
[ 0.170371] --------------------
[ 0.171003] Good, all 2 testcases passed! |
[ 0.172012] ---------------------------------
[ 0.172828] smpboot: Total of 2 processors activated (10774.03 BogoMIPS)
[ 0.176101] devtmpfs: initialized
[ 0.177076] x86/mm: Memory block size: 128MB
[ 0.181091] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.182151] futex hash table entries: 512 (order: 4, 65536 bytes)
[ 0.183417] prandom: seed boundary self test passed
[ 0.184335] prandom: 100 self tests passed
[ 0.184764] pinctrl core: initialized pinctrl subsystem
[ 0.186145] regulator-dummy: no parameters
[ 0.186961] regulator-dummy: no parameters
[ 0.187605] regulator-dummy: Failed to create debugfs directory
[ 0.188877] NET: Registered protocol family 16
[ 0.191404] workqueue: round-robin CPU selection forced, expect performance impact
[ 0.192206] cpuidle: using governor menu
[ 0.192847] ACPI: bus type PCI registered
[ 0.193261] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.194271] PCI: Using configuration type 1 for base access
[ 0.212312] ACPI: Added _OSI(Module Device)
[ 0.212771] ACPI: Added _OSI(Processor Device)
[ 0.213273] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.213738] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.236777] ACPI: Interpreter enabled
[ 0.238083] ACPI: (supports S0 S3 S5)
[ 0.238701] ACPI: Using IOAPIC for interrupt routing
[ 0.239717] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.331611] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.332035] acpi PNP0A03:00: _OSC: OS supports [Segments MSI]
[ 0.332710] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.333517] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.340432] acpiphp: Slot [3] registered
[ 0.341036] acpiphp: Slot [4] registered
[ 0.341658] acpiphp: Slot [5] registered
[ 0.342276] acpiphp: Slot [6] registered
[ 0.342889] acpiphp: Slot [7] registered
[ 0.343506] acpiphp: Slot [8] registered
[ 0.344125] acpiphp: Slot [9] registered
[ 0.344736] acpiphp: Slot [10] registered
[ 0.345371] acpiphp: Slot [11] registered
[ 0.345967] acpiphp: Slot [12] registered
[ 0.346570] acpiphp: Slot [13] registered
[ 0.347169] acpiphp: Slot [14] registered
[ 0.347777] acpiphp: Slot [15] registered
[ 0.348200] acpiphp: Slot [16] registered
[ 0.348800] acpiphp: Slot [17] registered
[ 0.349411] acpiphp: Slot [18] registered
[ 0.350012] acpiphp: Slot [19] registered
[ 0.350603] acpiphp: Slot [20] registered
[ 0.351206] acpiphp: Slot [21] registered
[ 0.352010] acpiphp: Slot [22] registered
[ 0.352607] acpiphp: Slot [23] registered
[ 0.353211] acpiphp: Slot [24] registered
[ 0.353809] acpiphp: Slot [25] registered
[ 0.354403] acpiphp: Slot [26] registered
[ 0.355005] acpiphp: Slot [27] registered
[ 0.355602] acpiphp: Slot [28] registered
[ 0.356202] acpiphp: Slot [29] registered
[ 0.356793] acpiphp: Slot [30] registered
[ 0.357398] acpiphp: Slot [31] registered
[ 0.357903] PCI host bridge to bus 0000:00
[ 0.358328] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.359002] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.359677] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.360015] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[ 0.360761] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.361363] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 0.364488] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 0.367144] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 0.380957] pci 0000:00:01.1: reg 0x20: [io 0xc1c0-0xc1cf]
[ 0.385044] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.385764] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.386409] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.387105] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.390473] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 0.392150] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.392872] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.395323] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 0.400015] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[ 0.408012] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 0.428016] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 0.432953] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 0.436018] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 0.444022] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 0.473914] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 0.477314] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[ 0.484021] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f]
[ 0.496022] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[ 0.524017] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[ 0.541182] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[ 0.548026] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf]
[ 0.560036] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[ 0.582794] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[ 0.593977] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[ 0.600017] pci 0000:00:06.0: reg 0x10: [io 0xc0c0-0xc0ff]
[ 0.606661] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff]
[ 0.628024] pci 0000:00:06.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref]
[ 0.642371] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
[ 0.650769] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc13f]
[ 0.656018] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff]
[ 0.678773] pci 0000:00:07.0: reg 0x20: [mem 0xfe00c000-0xfe00ffff 64bit pref]
[ 0.689818] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[ 0.696017] pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f]
[ 0.708015] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff]
[ 0.740019] pci 0000:00:08.0: reg 0x20: [mem 0xfe010000-0xfe013fff 64bit pref]
[ 0.753561] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000
[ 0.760026] pci 0000:00:09.0: reg 0x10: [io 0xc180-0xc1bf]
[ 0.772025] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff]
[ 0.800031] pci 0000:00:09.0: reg 0x20: [mem 0xfe014000-0xfe017fff 64bit pref]
[ 0.813175] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000
[ 0.816921] pci 0000:00:0a.0: reg 0x10: [mem 0xfebf7000-0xfebf700f]
[ 0.841184] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.843423] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.845883] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.849073] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.850872] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.856514] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 0.856764] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 0.857632] pci 0000:00:02.0: vgaarb: bridge control possible
[ 0.858214] vgaarb: loaded
[ 0.861014] PCI: Using ACPI for IRQ routing
[ 0.861014] PCI: pci_cache_line_size set to 64 bytes
[ 0.861739] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[ 0.862516] e820: reserve RAM buffer [mem 0x3ffd4000-0x3fffffff]
[ 0.865453] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.866316] clocksource: Switched to clocksource kvm-clock
[ 0.870163] Warning: could not register annotated branches stats
[ 1.040506] VFS: Disk quotas dquot_6.6.0
[ 1.048301] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 1.049822] pnp: PnP ACPI init
[ 1.051644] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 1.053104] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[ 1.054200] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 1.054998] pnp 00:03: [dma 2]
[ 1.055560] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[ 1.057370] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[ 1.059110] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.060507] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.064945] pnp: PnP ACPI: found 7 devices
[ 1.073415] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 1.075137] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 1.076121] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 1.077008] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 1.078004] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
[ 1.080044] NET: Registered protocol family 1
[ 1.080753] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 1.081552] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 1.082146] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 1.082836] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.083760] PCI: CLS 0 bytes, default 64
[ 1.084686] Unpacking initramfs...
[ 6.829808] Freeing initrd memory: 180420K
[ 6.833880] cryptomgr_test (30) used greatest stack depth: 15144 bytes left
[ 6.833942] CPU feature 'AVX registers' is not supported.
[ 6.833944] AVX2 instructions are not detected.
[ 6.835849] cryptomgr_test (31) used greatest stack depth: 15080 bytes left
[ 7.840114] tsc: Refined TSC clocksource calibration: 2693.509 MHz
[ 7.840732] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34aa491a, max_idle_ns: 440795256568 ns
[ 13.416346] rcu-perf:--- Start of test: nreaders=0 nwriters=2 verbose=1 shutdown=1
[ 13.419410] rcu-torture: Creating rcu_perf_shutdown task
[ 13.428134] rcu-torture: Creating rcu_perf_writer task
[ 13.429238] rcu-perf: rcu_perf_writer task started
[ 13.429251] rcu-torture: Creating rcu_perf_writer task
[ 13.430878] rcu-perf: rcu_perf_writer task started
[ 13.438002] Initialise system trusted keyrings
[ 13.438807] Key type blacklist registered
[ 13.439778] workingset: timestamp_bits=52 max_order=18 bucket_order=0
[ 13.509511] fuse init (API version 7.26)
[ 13.684124] Key type asymmetric registered
[ 13.684587] Asymmetric key parser 'x509' registered
[ 13.744108] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 930
[ 13.747915] test_bitmap: all 460506 tests passed
[ 13.748558] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[ 13.749257] glob: 64 self-tests passed, 0 failed
[ 13.749735] interval tree insert/remove
[ 14.252249] -> 13534 cycles
[ 14.255659] interval tree search
[ 14.524311] -> 72357 cycles (2692 results)
[ 14.555750] acpiphp_ibm: ibm_acpiphp_init: acpi_walk_namespace failed
[ 14.556719] switchtec: loaded.
[ 14.561587] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 14.586652] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 14.588865] console [ttyS0] disabled
[ 0.000000] Linux version 4.14.0-rc6-00077-g11224e1 (kbuild@lkp-ne02) (gcc version 4.8.4 (Debian 4.8.4-1)) #1 SMP PREEMPT Sat Oct 28 16:32:05 CST 2017
[ 0.000000] Command line: ip=::::vm-vp-1G-1::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-1G-1/boot-1-debian-x86_64-2016-08-31.cgz-11224e1fc40a6556dc285573ea93dd522f8c563f-20171028-56276-1pkvi7m-3.yaml ARCH=x86_64 kconfig=x86_64-randconfig-n0-10180128 branch=linux-devel/devel-spot-201710172231 commit=11224e1fc40a6556dc285573ea93dd522f8c563f BOOT_IMAGE=/pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/vmlinuz-4.14.0-rc6-00077-g11224e1 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-1G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/3 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
[ 0.000000] x86/fpu: x87 FPU will use FXSAVE
[ 0.000000] e820: BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd3fff] usable
[ 0.000000] BIOS-e820: [mem 0x000000003ffd4000-0x000000003fffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[ 0.000000] debug: ignoring loglevel setting.
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] tsc: Fast TSC calibration using PIT
[ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[ 0.000000] e820: last_pfn = 0x3ffd4 max_arch_pfn = 0x400000000
[ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC
[ 0.000000] Scan for SMP in [mem 0x00000000-0x000003ff]
[ 0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[ 0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff]
[ 0.000000] found SMP MP-table at [mem 0x000f6a50-0x000f6a5f] mapped at [fffffffffd200a50]
[ 0.000000] mpc: f6a60-f6b74
[ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[ 0.000000] BRK [0x046ad000, 0x046adfff] PGTABLE
[ 0.000000] BRK [0x046ae000, 0x046aefff] PGTABLE
[ 0.000000] BRK [0x046af000, 0x046affff] PGTABLE
[ 0.000000] BRK [0x046b0000, 0x046b0fff] PGTABLE
[ 0.000000] RAMDISK: [mem 0x34f9f000-0x3ffcffff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F6840 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000003FFE1628 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x000000003FFE147C 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x000000003FFE0040 00143C (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x000000003FFE0000 000040
[ 0.000000] ACPI: APIC 0x000000003FFE1570 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 0x000000003FFE15F0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000)
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffd3fff]
[ 0.000000] NODE_DATA(0) allocated [mem 0x34f60000-0x34f9efff]
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:34ee0001, primary cpu clock
[ 0.000000] kvm-clock: using sched offset of 288428507 cycles
[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffd3fff]
[ 0.000000] Normal empty
[ 0.000000] Device empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.000000] node 0: [mem 0x0000000000100000-0x000000003ffd3fff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd3fff]
[ 0.000000] On node 0 totalpages: 262002
[ 0.000000] DMA zone: 64 pages used for memmap
[ 0.000000] DMA zone: 21 pages reserved
[ 0.000000] DMA zone: 3998 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 4032 pages used for memmap
[ 0.000000] DMA32 zone: 258004 pages, LIFO batch:31
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[ 0.000000] ACPI: IRQ5 used by override.
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] ACPI: IRQ10 used by override.
[ 0.000000] ACPI: IRQ11 used by override.
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[ 0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000)
[ 0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[ 0.000000] random: fast init done
[ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[ 0.000000] percpu: Embedded 502 pages/cpu @ffff880034600000 s2017024 r8192 d30976 u2097152
[ 0.000000] pcpu-alloc: s2017024 r8192 d30976 u2097152 alloc=1*2097152
[ 0.000000] pcpu-alloc: [0] 0 [0] 1
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 3460d680
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 257885
[ 0.000000] Policy zone: DMA32
[ 0.000000] Kernel command line: ip=::::vm-vp-1G-1::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-1G-1/boot-1-debian-x86_64-2016-08-31.cgz-11224e1fc40a6556dc285573ea93dd522f8c563f-20171028-56276-1pkvi7m-3.yaml ARCH=x86_64 kconfig=x86_64-randconfig-n0-10180128 branch=linux-devel/devel-spot-201710172231 commit=11224e1fc40a6556dc285573ea93dd522f8c563f BOOT_IMAGE=/pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/vmlinuz-4.14.0-rc6-00077-g11224e1 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-1G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/3 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
[ 0.000000] sysrq: sysrq always enabled.
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] Memory: 790260K/1048008K available (4915K kernel code, 21248K rwdata, 3412K rodata, 3488K init, 19368K bss, 257748K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[ 0.000000]
[ 0.000000] **********************************************************
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] ** **
[ 0.000000] ** trace_printk() being used. Allocating extra memory. **
[ 0.000000] ** **
[ 0.000000] ** This means that this is a DEBUG kernel and it is **
[ 0.000000] ** unsafe for production use. **
[ 0.000000] ** **
[ 0.000000] ** If you see this message and you are not debugging **
[ 0.000000] ** the kernel, report this immediately to your vendor! **
[ 0.000000] ** **
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] **********************************************************
[ 0.004000] Preemptible hierarchical RCU implementation.
[ 0.004000] RCU event tracing is enabled.
[ 0.004000] RCU dyntick-idle grace-period acceleration is enabled.
[ 0.004000] RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2.
[ 0.004000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[ 0.004000] Tasks RCU enabled.
[ 0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[ 0.004000] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16
[ 0.004000] console [ttyS0] enabled
[ 0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.004000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.004000] ... MAX_LOCK_DEPTH: 48
[ 0.004000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.004000] ... CLASSHASH_SIZE: 4096
[ 0.004000] ... MAX_LOCKDEP_ENTRIES: 32768
[ 0.004000] ... MAX_LOCKDEP_CHAINS: 65536
[ 0.004000] ... CHAINHASH_SIZE: 32768
[ 0.004000] memory used by lock dependency info: 7871 kB
[ 0.004000] per task-struct memory footprint: 2688 bytes
[ 0.004000] ------------------------
[ 0.004000] | Locking API testsuite:
[ 0.004000] ----------------------------------------------------------------------------
[ 0.004000] | spin |wlock |rlock |mutex | wsem | rsem |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] A-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed|failed|
[ 0.004000] double unlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.004000] initialize held: ok | ok | ok | ok | ok | ok | ok |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] recursive read-lock: | ok | |failed|
[ 0.004000] recursive read-lock #2: | ok | |failed|
[ 0.004000] mixed read-write-lock: |failed| |failed|
[ 0.004000] mixed write-read-lock: |failed| |failed|
[ 0.004000] mixed read-lock/lock-write ABBA: |failed| |failed|
[ 0.004000] mixed read-lock/lock-read ABBA: | ok | |failed|
[ 0.004000] mixed write-lock/lock-write ABBA: |failed| |failed|
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] hard-irqs-on + irq-safe-A/12:failed|failed| ok |
[ 0.004000] soft-irqs-on + irq-safe-A/12:failed|failed| ok |
[ 0.004000] hard-irqs-on + irq-safe-A/21:failed|failed| ok |
[ 0.004000] soft-irqs-on + irq-safe-A/21:failed|failed| ok |
[ 0.004000] sirq-safe-A => hirqs-on/12:failed|failed| ok |
[ 0.004000] sirq-safe-A => hirqs-on/21:failed|failed| ok |
[ 0.004000] hard-safe-A + irqs-on/12:failed|failed| ok |
[ 0.004000] soft-safe-A + irqs-on/12:failed|failed| ok |
[ 0.004000] hard-safe-A + irqs-on/21:failed|failed| ok |
[ 0.004000] soft-safe-A + irqs-on/21:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/123:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/123:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/132:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/132:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/213:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/213:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/231:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/231:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/312:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/312:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #1/321:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #1/321:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/123:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/123:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/132:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/132:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/213:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/213:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/231:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/231:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/312:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/312:failed|failed| ok |
[ 0.004000] hard-safe-A + unsafe-B #2/321:failed|failed| ok |
[ 0.004000] soft-safe-A + unsafe-B #2/321:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/123:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/123:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/132:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/132:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/213:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/213:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/231:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/231:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/312:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/312:failed|failed| ok |
[ 0.004000] hard-irq lock-inversion/321:failed|failed| ok |
[ 0.004000] soft-irq lock-inversion/321:failed|failed| ok |
[ 0.004000] hard-irq read-recursion/123: ok |
[ 0.004000] soft-irq read-recursion/123: ok |
[ 0.004000] hard-irq read-recursion/132: ok |
[ 0.004000] soft-irq read-recursion/132: ok |
[ 0.004000] hard-irq read-recursion/213: ok |
[ 0.004000] soft-irq read-recursion/213: ok |
[ 0.004000] hard-irq read-recursion/231: ok |
[ 0.004000] soft-irq read-recursion/231: ok |
[ 0.004000] hard-irq read-recursion/312: ok |
[ 0.004000] soft-irq read-recursion/312: ok |
[ 0.004000] hard-irq read-recursion/321: ok |
[ 0.004000] soft-irq read-recursion/321: ok |
[ 0.004000] --------------------------------------------------------------------------
[ 0.004000] | Wound/wait tests |
[ 0.004000] ---------------------
[ 0.004000] ww api failures: ok | ok | ok |
[ 0.004000] ww contexts mixing:failed| ok |
[ 0.004000] finishing ww context: ok | ok | ok | ok |
[ 0.004000] locking mismatches: ok | ok | ok |
[ 0.004000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok |
[ 0.004000] spinlock nest unlocked: ok |
[ 0.004000] -----------------------------------------------------
[ 0.004000] |block | try |context|
[ 0.004000] -----------------------------------------------------
[ 0.004000] context:failed| ok | ok |
[ 0.004000] try:failed| ok |failed|
[ 0.004000] block:failed| ok |failed|
[ 0.004000] spinlock:failed| ok |failed|
[ 0.004000] --------------------------------------------------------
[ 0.004000] 153 out of 262 testcases failed, as expected. |
[ 0.004000] ----------------------------------------------------
[ 0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 0.004000] hpet clockevent registered
[ 0.004031] tsc: Detected 2693.508 MHz processor
[ 0.004818] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=10774032)
[ 0.004872] pid_max: default: 32768 minimum: 301
[ 0.005418] ACPI: Core revision 20170728
[ 0.052600] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.054167] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.055027] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.055791] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.056036] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.057725] CPU: Physical Processor ID: 0
[ 0.058170] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.058710] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.060049] Freeing SMP alternatives memory: 12K
[ 0.061067] smpboot: Max logical packages: 2
[ 0.061727] x2apic enabled
[ 0.062221] Switched APIC routing to physical x2apic.
[ 0.062754] enabled ExtINT on CPU#0
[ 0.063767] ENABLING IO-APIC IRQs
[ 0.064053] init IO_APIC IRQs
[ 0.064387] apic 0 pin 0 not connected
[ 0.064819] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:0)
[ 0.065669] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[ 0.066497] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:0)
[ 0.067322] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:0)
[ 0.068000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:0)
[ 0.068000] apic 0 pin 16 not connected
[ 0.068000] apic 0 pin 17 not connected
[ 0.068000] apic 0 pin 18 not connected
[ 0.068000] apic 0 pin 19 not connected
[ 0.068000] apic 0 pin 20 not connected
[ 0.068000] apic 0 pin 21 not connected
[ 0.068000] apic 0 pin 22 not connected
[ 0.068000] apic 0 pin 23 not connected
[ 0.068000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.068000] Using local APIC timer interrupts.
[ 0.068000] calibrating APIC timer ...
[ 0.072000] ... lapic delta = 6251053
[ 0.072000] ... PM-Timer delta = 358019
[ 0.072000] ... PM-Timer result ok
[ 0.072000] ..... delta 6251053
[ 0.072000] ..... mult: 268480682
[ 0.072000] ..... calibration result: 4000673
[ 0.072000] ..... CPU clock speed is 2693.3664 MHz.
[ 0.072000] ..... host bus clock speed is 1000.0673 MHz.
[ 0.072051] smpboot: CPU0: Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3)
[ 0.088076] Performance Events: unsupported p6 CPU model 26 no PMU driver, software events only.
[ 0.096028] Hierarchical SRCU implementation.
[ 0.120020] smp: Bringing up secondary CPUs ...
[ 0.155902] x86: Booting SMP configuration:
[ 0.156027] .... node #0, CPUs: #1
[ 0.004000] kvm-clock: cpu 1, msr 0:34ee0041, secondary cpu clock
[ 0.004000] masked ExtINT on CPU#1
[ 0.164163] KVM setup async PF for cpu 1
[ 0.164756] kvm-stealtime: cpu 1, msr 3480d680
[ 0.165514] smp: Brought up 1 node, 2 CPUs
[ 0.165514] ----------------
[ 0.168040] | NMI testsuite:
[ 0.168522] --------------------
[ 0.169139] remote IPI: ok |
[ 0.169747] local IPI: ok |
[ 0.170371] --------------------
[ 0.171003] Good, all 2 testcases passed! |
[ 0.172012] ---------------------------------
[ 0.172828] smpboot: Total of 2 processors activated (10774.03 BogoMIPS)
[ 0.176101] devtmpfs: initialized
[ 0.177076] x86/mm: Memory block size: 128MB
[ 0.181091] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.182151] futex hash table entries: 512 (order: 4, 65536 bytes)
[ 0.183417] prandom: seed boundary self test passed
[ 0.184335] prandom: 100 self tests passed
[ 0.184764] pinctrl core: initialized pinctrl subsystem
[ 0.186145] regulator-dummy: no parameters
[ 0.186961] regulator-dummy: no parameters
[ 0.187605] regulator-dummy: Failed to create debugfs directory
[ 0.188877] NET: Registered protocol family 16
[ 0.191404] workqueue: round-robin CPU selection forced, expect performance impact
[ 0.192206] cpuidle: using governor menu
[ 0.192847] ACPI: bus type PCI registered
[ 0.193261] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.194271] PCI: Using configuration type 1 for base access
[ 0.212312] ACPI: Added _OSI(Module Device)
[ 0.212771] ACPI: Added _OSI(Processor Device)
[ 0.213273] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.213738] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.236777] ACPI: Interpreter enabled
[ 0.238083] ACPI: (supports S0 S3 S5)
[ 0.238701] ACPI: Using IOAPIC for interrupt routing
[ 0.239717] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.331611] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.332035] acpi PNP0A03:00: _OSC: OS supports [Segments MSI]
[ 0.332710] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.333517] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.340432] acpiphp: Slot [3] registered
[ 0.341036] acpiphp: Slot [4] registered
[ 0.341658] acpiphp: Slot [5] registered
[ 0.342276] acpiphp: Slot [6] registered
[ 0.342889] acpiphp: Slot [7] registered
[ 0.343506] acpiphp: Slot [8] registered
[ 0.344125] acpiphp: Slot [9] registered
[ 0.344736] acpiphp: Slot [10] registered
[ 0.345371] acpiphp: Slot [11] registered
[ 0.345967] acpiphp: Slot [12] registered
[ 0.346570] acpiphp: Slot [13] registered
[ 0.347169] acpiphp: Slot [14] registered
[ 0.347777] acpiphp: Slot [15] registered
[ 0.348200] acpiphp: Slot [16] registered
[ 0.348800] acpiphp: Slot [17] registered
[ 0.349411] acpiphp: Slot [18] registered
[ 0.350012] acpiphp: Slot [19] registered
[ 0.350603] acpiphp: Slot [20] registered
[ 0.351206] acpiphp: Slot [21] registered
[ 0.352010] acpiphp: Slot [22] registered
[ 0.352607] acpiphp: Slot [23] registered
[ 0.353211] acpiphp: Slot [24] registered
[ 0.353809] acpiphp: Slot [25] registered
[ 0.354403] acpiphp: Slot [26] registered
[ 0.355005] acpiphp: Slot [27] registered
[ 0.355602] acpiphp: Slot [28] registered
[ 0.356202] acpiphp: Slot [29] registered
[ 0.356793] acpiphp: Slot [30] registered
[ 0.357398] acpiphp: Slot [31] registered
[ 0.357903] PCI host bridge to bus 0000:00
[ 0.358328] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.359002] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.359677] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.360015] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[ 0.360761] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.361363] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 0.364488] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 0.367144] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 0.380957] pci 0000:00:01.1: reg 0x20: [io 0xc1c0-0xc1cf]
[ 0.385044] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.385764] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.386409] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.387105] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.390473] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 0.392150] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.392872] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.395323] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 0.400015] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[ 0.408012] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 0.428016] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 0.432953] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 0.436018] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 0.444022] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 0.473914] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 0.477314] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[ 0.484021] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f]
[ 0.496022] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[ 0.524017] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
[ 0.541182] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[ 0.548026] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf]
[ 0.560036] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[ 0.582794] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
[ 0.593977] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[ 0.600017] pci 0000:00:06.0: reg 0x10: [io 0xc0c0-0xc0ff]
[ 0.606661] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff]
[ 0.628024] pci 0000:00:06.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref]
[ 0.642371] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
[ 0.650769] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc13f]
[ 0.656018] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff]
[ 0.678773] pci 0000:00:07.0: reg 0x20: [mem 0xfe00c000-0xfe00ffff 64bit pref]
[ 0.689818] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[ 0.696017] pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f]
[ 0.708015] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff]
[ 0.740019] pci 0000:00:08.0: reg 0x20: [mem 0xfe010000-0xfe013fff 64bit pref]
[ 0.753561] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000
[ 0.760026] pci 0000:00:09.0: reg 0x10: [io 0xc180-0xc1bf]
[ 0.772025] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff]
[ 0.800031] pci 0000:00:09.0: reg 0x20: [mem 0xfe014000-0xfe017fff 64bit pref]
[ 0.813175] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000
[ 0.816921] pci 0000:00:0a.0: reg 0x10: [mem 0xfebf7000-0xfebf700f]
[ 0.841184] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.843423] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.845883] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.849073] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.850872] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.856514] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 0.856764] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 0.857632] pci 0000:00:02.0: vgaarb: bridge control possible
[ 0.858214] vgaarb: loaded
[ 0.861014] PCI: Using ACPI for IRQ routing
[ 0.861014] PCI: pci_cache_line_size set to 64 bytes
[ 0.861739] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[ 0.862516] e820: reserve RAM buffer [mem 0x3ffd4000-0x3fffffff]
[ 0.865453] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.866316] clocksource: Switched to clocksource kvm-clock
[ 0.870163] Warning: could not register annotated branches stats
[ 1.040506] VFS: Disk quotas dquot_6.6.0
[ 1.048301] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 1.049822] pnp: PnP ACPI init
[ 1.051644] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 1.053104] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[ 1.054200] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 1.054998] pnp 00:03: [dma 2]
[ 1.055560] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[ 1.057370] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[ 1.059110] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.060507] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.064945] pnp: PnP ACPI: found 7 devices
[ 1.073415] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 1.075137] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 1.076121] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 1.077008] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 1.078004] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]
[ 1.080044] NET: Registered protocol family 1
[ 1.080753] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 1.081552] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 1.082146] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 1.082836] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.083760] PCI: CLS 0 bytes, default 64
[ 1.084686] Unpacking initramfs...
[ 6.829808] Freeing initrd memory: 180420K
[ 6.833880] cryptomgr_test (30) used greatest stack depth: 15144 bytes left
[ 6.833942] CPU feature 'AVX registers' is not supported.
[ 6.833944] AVX2 instructions are not detected.
[ 6.835849] cryptomgr_test (31) used greatest stack depth: 15080 bytes left
[ 7.840114] tsc: Refined TSC clocksource calibration: 2693.509 MHz
[ 7.840732] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34aa491a, max_idle_ns: 440795256568 ns
[ 13.416346] rcu-perf:--- Start of test: nreaders=0 nwriters=2 verbose=1 shutdown=1
[ 13.419410] rcu-torture: Creating rcu_perf_shutdown task
[ 13.428134] rcu-torture: Creating rcu_perf_writer task
[ 13.429238] rcu-perf: rcu_perf_writer task started
[ 13.429251] rcu-torture: Creating rcu_perf_writer task
[ 13.430878] rcu-perf: rcu_perf_writer task started
[ 13.438002] Initialise system trusted keyrings
[ 13.438807] Key type blacklist registered
[ 13.439778] workingset: timestamp_bits=52 max_order=18 bucket_order=0
[ 13.509511] fuse init (API version 7.26)
[ 13.684124] Key type asymmetric registered
[ 13.684587] Asymmetric key parser 'x509' registered
[ 13.744108] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 930
[ 13.747915] test_bitmap: all 460506 tests passed
[ 13.748558] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[ 13.749257] glob: 64 self-tests passed, 0 failed
[ 13.749735] interval tree insert/remove
[ 14.252249] -> 13534 cycles
[ 14.255659] interval tree search
[ 14.524311] -> 72357 cycles (2692 results)
[ 14.555750] acpiphp_ibm: ibm_acpiphp_init: acpi_walk_namespace failed
[ 14.556719] switchtec: loaded.
[ 14.561587] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 14.586652] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 14.588865] console [ttyS0] disabled
[ 14.613135] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 14.929283] console [ttyS0] enabled
[ 14.954291] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 14.980104] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 14.985944] Initializing Nozomi driver 2.1d
[ 14.986668] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $
[ 14.987440] ac.o: No PCI boards found.
[ 14.987826] ac.o: For an ISA board you must supply memory and irq parameters.
[ 14.992802] kworker/u4:4 (89) used greatest stack depth: 14112 bytes left
[ 14.992920] ppdev: user-space parallel port driver
[ 14.992966] telclk_interrupt = 0xf non-mcpbl0010 hw.
[ 14.993048] smapi::smapi_init, ERROR invalid usSmapiID
[ 14.993050] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine
[ 14.993052] mwave: mwavedd::mwave_init: Error: Failed to initialize board data
[ 14.993054] mwave: mwavedd::mwave_init: Error: Failed to initialize
[ 14.993223] parport_pc 00:04: reported by Plug and Play ACPI
[ 14.993711] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[ 15.085333] parport_pc 00:04: reported by Plug and Play ACPI
[ 15.086302] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE,EPP]
[ 15.191298] ibmasm: IBM ASM Service Processor Driver version 1.0 loaded
[ 15.194048] L440GX flash mapping: failed to find PIIX4 ISA bridge, cannot continue
[ 15.194848] device id = 2440
[ 15.195160] device id = 2480
[ 15.195496] device id = 24c0
[ 15.195808] device id = 24d0
[ 15.196150] device id = 25a1
[ 15.196478] device id = 2670
[ 15.206952] HSI/SSI char device loaded
[ 15.208465] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 15.210540] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 15.211085] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 15.213059] mousedev: PS/2 mouse device common for all mice
[ 15.214808] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[ 15.215695] evbug: Connected device: input0 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[ 15.216976] evbug: Disconnected device: input0
[ 15.222137] pc87360: PC8736x not detected, module not inserted
[ 15.223931] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[ 15.224846] ledtrig-cpu: registered to indicate activity on CPUs
[ 15.224862] evbug: Connected device: input1 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[ 15.230919] fw_cfg: probe of QEMU0002:00 failed with error -16
[ 15.268664] hidraw: raw HID events driver (C) Jiri Kosina
[ 15.283509] start plist test
[ 15.290493] end plist test
[ 15.491476] String selftests succeeded
[ 15.492128] ... APIC ID: 00000000 (0)
[ 15.492556] ... APIC VERSION: 01050014
[ 15.492954] 0000000000000000000000000000000000000000000000000000000000000000
[ 15.493734] 0000000000000000000000000000000000000000000000000000000000000000
[ 15.494494] 0000000000000000000000000000000000000000000000000000000000000000
[ 15.495276] number of MP IRQ sources: 15.
[ 15.495684] number of IO-APIC #0 registers: 24.
[ 15.496172] testing the IO APIC.......................
[ 15.496709] IO APIC #0......
[ 15.497011] .... register #00: 00000000
[ 15.497401] ....... : physical APIC id: 00
[ 15.497838] ....... : Delivery Type: 0
[ 15.498240] ....... : LTS : 0
[ 15.498646] .... register #01: 00170011
[ 15.499029] ....... : max redirection entries: 17
[ 15.499532] ....... : PRQ implemented: 0
[ 15.499959] ....... : IO APIC version: 11
[ 15.500430] .... register #02: 00000000
[ 15.500818] ....... : arbitration: 00
[ 15.501221] .... IRQ redirection table:
[ 15.501612] IOAPIC 0:
[ 15.501862] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.502643] pin01, enabled , edge , high, V(31), IRR(0), S(0), physical, D(00), M(0)
[ 15.503426] pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[ 15.504248] pin03, enabled , edge , high, V(33), IRR(0), S(0), physical, D(00), M(0)
[ 15.505028] pin04, enabled , edge , high, V(34), IRR(0), S(0), physical, D(00), M(0)
[ 15.505809] pin05, disabled, level, high, V(35), IRR(0), S(0), physical, D(00), M(0)
[ 15.506584] pin06, enabled , edge , high, V(36), IRR(0), S(0), physical, D(00), M(0)
[ 15.507357] pin07, enabled , edge , high, V(37), IRR(0), S(0), physical, D(00), M(0)
[ 15.508169] pin08, enabled , edge , high, V(38), IRR(0), S(0), physical, D(00), M(0)
[ 15.508957] pin09, disabled, level, high, V(39), IRR(0), S(0), physical, D(00), M(0)
[ 15.509735] pin0a, disabled, level, high, V(3A), IRR(0), S(0), physical, D(00), M(0)
[ 15.510515] pin0b, disabled, level, high, V(3B), IRR(0), S(0), physical, D(00), M(0)
[ 15.511293] pin0c, enabled , edge , high, V(3C), IRR(0), S(0), physical, D(00), M(0)
[ 15.512105] pin0d, enabled , edge , high, V(3D), IRR(0), S(0), physical, D(00), M(0)
[ 15.512885] pin0e, enabled , edge , high, V(3E), IRR(0), S(0), physical, D(00), M(0)
[ 15.513659] pin0f, enabled , edge , high, V(3F), IRR(0), S(0), physical, D(00), M(0)
[ 15.514445] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.515216] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.515992] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.516801] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.517580] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.518359] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.519132] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.519910] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 15.520707] IRQ to pin mappings:
[ 15.521042] IRQ0 -> 0:2
[ 15.521316] IRQ1 -> 0:1
[ 15.521591] IRQ3 -> 0:3
[ 15.521857] IRQ4 -> 0:4
[ 15.522125] IRQ5 -> 0:5
[ 15.522397] IRQ6 -> 0:6
[ 15.522664] IRQ7 -> 0:7
[ 15.522929] IRQ8 -> 0:8
[ 15.523195] IRQ9 -> 0:9
[ 15.523466] IRQ10 -> 0:10
[ 15.523747] IRQ11 -> 0:11
[ 15.524063] IRQ12 -> 0:12
[ 15.524354] IRQ13 -> 0:13
[ 15.524637] IRQ14 -> 0:14
[ 15.524920] IRQ15 -> 0:15
[ 15.525206] .................................... done.
[ 15.525806] sched_clock: Marking stable (15524042889, 0)->(16052083915, -528041026)
[ 15.528193] Loading compiled-in X.509 certificates
[ 15.529047] page_owner is disabled
[ 15.533637] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found
[ 15.546612] Freeing unused kernel memory: 3488K
[ 15.547091] Write protecting the kernel read-only data: 10240k
[ 15.550562] Freeing unused kernel memory: 1220K
[ 15.553823] Freeing unused kernel memory: 684K
[ 15.554276] rodata_test: all tests were successful
[ 15.572662] systemd[1]: RTC configured in localtime, applying delta of 480 minutes to system time.
[ 15.576696] systemd[1]: Failed to insert module 'autofs4': No such file or directory

[ 15.623931] systemd-getty-g (101) used greatest stack depth: 14072 bytes left
[ 15.626188] systemd-system- (102) used greatest stack depth: 14024 bytes left
[ 15.679480] systemd-rc-loca (106) used greatest stack depth: 14008 bytes left
[ 15.681369] systemd-sysv-ge (105) used greatest stack depth: 13784 bytes left
Mounting Debug File System...
Mounting RPC Pipe File System...
Starting Remount Root and Kernel File Systems...
Starting Load Kernel Modules...
Starting Create list of required st... nodes for the current kernel...
Starting Journal Service...
See 'systemctl status run-rpc_pipefs.mount' for details.
Starting Create Static Device Nodes in /dev...
Mounting FUSE Control File System...
Mounting Configuration File System...
Starting Apply Kernel Variables...
Starting udev Coldplug all Devices...
Starting Load/Save Random Seed...
Starting udev Kernel Device Manager...
Starting Preprocess NFS configuration...
Starting Flush Journal to Persistent Storage...
Starting Create Volatile Files and Directories...
Starting Network Time Synchronization...
Starting RPC bind portmap service...
Starting Update UTMP about System Boot/Shutdown...
Starting OpenBSD Secure Shell server...
Starting /etc/rc.local Compatibility...
Starting LKP bootstrap...
[ 16.633220] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[ 16.634006] evbug: Connected device: input3 (Power Button at LNXPWRBN/button/input0)
[ 16.649532] ACPI: Power Button [PWRF]
[ 16.649922] evbug: Disconnected device: input3
Starting Login Service...
Starting Permit User Sessions...
[ 16.684237] rc.local[190]: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/lkp/lkp/src/bin
[ 16.704765] button: probe of LNXPWRBN:00 failed with error -22
See 'systemctl status ssh.service' for details.
[ 16.719707] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5
rvice.
[ 16.723035] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4
m] Reached target Login Prompts.
[ 16.733993] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[ 16.735426] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[ 16.744165] evbug: Disconnected device: input5
[ 16.780117] evbug: Disconnected device: input4
[ 16.785808] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input7
[ 16.787642] evbug: Connected device: input7 (VirtualPS/2 VMware VMMouse at isa0060/serio1/input1)
[ 16.789210] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6
[ 16.790407] evbug: Connected device: input6 (VirtualPS/2 VMware VMMouse at isa0060/serio1/input0)
[ 16.877275] systemd-udevd (156) used greatest stack depth: 13528 bytes left
[ 16.881209] systemd-udevd (176) used greatest stack depth: 13464 bytes left
LKP: HOSTNAME vm-vp-1G-1, MAC , kernel 4.14.0-rc6-00077-g11224e1 1, serial console /dev/ttyS0
[ 16.898845] Kernel tests: Boot OK!
[ 16.898858]
[ 16.906558] install debs round one: dpkg -i --force-depends /opt/deb/debconf_1.5.63_all.deb
[ 16.906572]
[ 16.908481] /opt/deb/libtext-charwidth-perl_0.04-7+b7_amd64.deb
[ 16.908494]
[ 16.910038] /opt/deb/libtext-iconv-perl_1.7-5+b6_amd64.deb
[ 16.910050]
[ 16.911396] /opt/deb/perl-base_5.26.0-5_amd64.deb
[ 16.911407]
[ 16.912984] /opt/deb/liblocale-gettext-perl_1.07-3+b3_amd64.deb
[ 16.912997]
[ 16.914505] /opt/deb/perl-modules-5.26_5.26.0-5_all.deb
[ 16.914518]
[ 16.915875] /opt/deb/libperl5.26_5.26.0-5_amd64.deb
[ 16.915887]
[ 16.917135] /opt/deb/perl_5.26.0-5_amd64.deb
[ 16.917147]
[ 16.918437] /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb
[ 16.918449]
[ 16.919723] /opt/deb/libssl1.1_1.1.0f-3_amd64.deb
[ 16.919733]
[ 16.920991] /opt/deb/openssl_1.1.0f-3_amd64.deb
[ 16.921001]
[ 16.922392] /opt/deb/ca-certificates_20161130+nmu1_all.deb
[ 16.922405]
[ 16.944496] (Reading database ... 2202 files and directories currently installed.)
[ 16.944509]
[ 16.946380] Preparing to unpack .../opt/deb/debconf_1.5.63_all.deb ...
[ 16.946392]
[ 16.969878] Unpacking debconf (1.5.63) over (1.5.59) ...
[ 16.969891]
[ 17.096967] Preparing to unpack .../libtext-charwidth-perl_0.04-7+b7_amd64.deb ...
[ 17.096984]
[ 17.099137] Unpacking libtext-charwidth-perl (0.04-7+b7) over (0.04-7+b4) ...
[ 17.099150]
[ 17.148587] Preparing to unpack .../libtext-iconv-perl_1.7-5+b6_amd64.deb ...
[ 17.148602]
[ 17.150596] Unpacking libtext-iconv-perl (1.7-5+b6) over (1.7-5+b3) ...
[ 17.150609]
[ 17.195434] Preparing to unpack .../perl-base_5.26.0-5_amd64.deb ...
[ 17.195450]
[ 17.197300] Unpacking perl-base (5.26.0-5) over (5.22.2-3) ...
[ 17.197313]
[ 17.665232] Preparing to unpack .../liblocale-gettext-perl_1.07-3+b3_amd64.deb ...
[ 17.665249]
[ 17.668666] Unpacking liblocale-gettext-perl (1.07-3+b3) over (1.07-3) ...
[ 17.668683]
[ 17.727481] Selecting previously unselected package perl-modules-5.26.
[ 17.727497]
[ 17.729452] Preparing to unpack .../perl-modules-5.26_5.26.0-5_all.deb ...
[ 17.729465]
[ 17.731085] Unpacking perl-modules-5.26 (5.26.0-5) ...
[ 17.731098]
[ 18.719487] Selecting previously unselected package libperl5.26:amd64.
[ 18.719501]
[ 18.721561] Preparing to unpack .../libperl5.26_5.26.0-5_amd64.deb ...
[ 18.721572]
[ 18.723106] Unpacking libperl5.26:amd64 (5.26.0-5) ...
[ 18.723118]
[ 19.349916] Selecting previously unselected package perl.
[ 19.349930]
[ 19.353004] Preparing to unpack .../deb/perl_5.26.0-5_amd64.deb ...
[ 19.353017]
[ 19.369584] Unpacking perl (5.26.0-5) ...
[ 19.369597]
[ 19.372034] WARNING: can't dereference iret registers at ffff880037873fe0 for ip entry_SYSCALL_64_fastpath+0x5/0xae
[ 19.436836] Preparing to unpack .../gawk_1%3a4.1.4+dfsg-1_amd64.deb ...
[ 19.436850]
[ 19.438698] Unpacking gawk (1:4.1.4+dfsg-1) over (1:4.1.1+dfsg-1) ...
[ 19.438710]
[ 19.623073] Selecting previously unselected package libssl1.1:amd64.
[ 19.623090]
[ 19.625001] Preparing to unpack .../libssl1.1_1.1.0f-3_amd64.deb ...
[ 19.625013]
[ 19.626456] Unpacking libssl1.1:amd64 (1.1.0f-3) ...
[ 19.626467]
[ 19.772823] Selecting previously unselected package openssl.
[ 19.772838]
[ 19.774585] Preparing to unpack .../deb/openssl_1.1.0f-3_amd64.deb ...
[ 19.774597]
[ 19.775916] Unpacking openssl (1.1.0f-3) ...
[ 19.775928]
[ 19.921300] Selecting previously unselected package ca-certificates.
[ 19.921317]
[ 19.923290] Preparing to unpack .../ca-certificates_20161130+nmu1_all.deb ...
[ 19.923302]
[ 19.925041] Unpacking ca-certificates (20161130+nmu1) ...
[ 19.925053]
[ 20.051524] Setting up perl-base (5.26.0-5) ...
[ 20.051539]
[ 20.053072] Setting up liblocale-gettext-perl (1.07-3+b3) ...
[ 20.053085]
[ 20.057308] Setting up perl-modules-5.26 (5.26.0-5) ...
[ 20.057320]
[ 20.058505] Setting up debconf (1.5.63) ...
[ 20.058516]
[ 20.457624] Setting up libtext-charwidth-perl (0.04-7+b7) ...
[ 20.457641]
[ 20.460251] Setting up libtext-iconv-perl (1.7-5+b6) ...
[ 20.460263]
[ 20.461639] Setting up libssl1.1:amd64 (1.1.0f-3) ...
[ 20.461652]
[ 20.685685] Setting up openssl (1.1.0f-3) ...
[ 20.685702]
[ 20.690953] Setting up ca-certificates (20161130+nmu1) ...
[ 20.690966]
[ 24.448936] rcu-perf: rcu_perf_writer 0 has 100 measurements
[ 24.498036] rcu-perf: rcu_perf_writer 1 has 100 measurements
[ 24.544081] Dumping ftrace buffer:
[ 24.544991] ---------------------------------
[ 24.545419] rb_produ-44 0.... 13965035us : ring_buffer_producer_thread: Starting ring buffer hammer
[ 24.546323] rb_produ-44 1.... 23965045us : ring_buffer_producer_thread: End ring buffer hammer
[ 24.547164] rb_produ-44 1.... 24044689us : ring_buffer_producer_thread: Running Consumer at nice: 19
[ 24.548058] rb_produ-44 1.... 24044690us : ring_buffer_producer_thread: Running Producer at nice: 19
[ 24.549055] rb_produ-44 1.... 24044691us : ring_buffer_producer_thread: WARNING!!! This test is running at lowest priority.
[ 24.550160] rb_produ-44 1.... 24044691us : ring_buffer_producer_thread: Time: 10000004 (usecs)
[ 24.551047] rb_produ-44 1.... 24044692us : ring_buffer_producer_thread: Overruns: 28744873
[ 24.551883] rb_produ-44 1.... 24044692us : ring_buffer_producer_thread: Read: 5930627 (by events)
[ 24.552809] rb_produ-44 1.... 24044693us : ring_buffer_producer_thread: Entries: 0
[ 24.553584] rb_produ-44 1.... 24044693us : ring_buffer_producer_thread: Total: 34675500
[ 24.554444] rb_produ-44 1.... 24044693us : ring_buffer_producer_thread: Missed: 0
[ 24.555219] rb_produ-44 1.... 24044694us : ring_buffer_producer_thread: Hit: 34675500
[ 24.556054] rb_produ-44 1.... 24044694us : ring_buffer_producer_thread: Entries per millisec: 3467
[ 24.556953] rb_produ-44 1.... 24044694us : ring_buffer_producer_thread: 288 ns per entry
[ 24.557767] rb_produ-44 1.... 24044695us : ring_buffer_producer_thread: Sleeping for 10 secs
[ 24.558607] ---------------------------------
[ 24.559179] rcu-perf: Test complete
[ 24.559560] rcu-torture: Stopping rcu_perf_writer task
[ 24.560035] rcu-torture: Stopping rcu_perf_writer
[ 24.560583] rcu-torture: Stopping rcu_perf_writer
[ 24.560591] rcu-perf: writer 0 gps: 106
[ 24.561433] rcu-torture: Stopping rcu_perf_writer task
[ 24.564173] rcu-perf: writer 1 gps: 100
[ 24.564593] rcu-perf: start: 23520118454 end: 24497965373 duration: 977846919 gps: 206 batches: 260
[ 24.565396] rcu-perf: 0 writer-duration: 0 15995759
[ 24.572055] rcu-perf: 0 writer-duration: 1 4462267
[ 24.572608] rcu-perf: 0 writer-duration: 2 11514110
[ 24.573137] rcu-perf: 0 writer-duration: 3 8063697
[ 24.573661] rcu-perf: 0 writer-duration: 4 4167315
[ 24.574184] rcu-perf: 0 writer-duration: 5 17229737
[ 24.574719] rcu-perf: 0 writer-duration: 6 2721184
[ 24.575280] rcu-perf: 0 writer-duration: 7 19042764
[ 24.575811] rcu-perf: 0 writer-duration: 8 9527064
[ 24.576669] rcu-perf: 0 writer-duration: 9 3425386
[ 24.577199] rcu-perf: 0 writer-duration: 10 7972580
[ 24.577721] rcu-perf: 0 writer-duration: 11 11863970
[ 24.578247] rcu-perf: 0 writer-duration: 12 4036289
[ 24.578792] rcu-perf: 0 writer-duration: 13 12086492
[ 24.579320] rcu-perf: 0 writer-duration: 14 10559504
[ 24.579849] rcu-perf: 0 writer-duration: 15 5582621
[ 24.580392] rcu-perf: 0 writer-duration: 16 10732000
[ 24.580926] rcu-perf: 0 writer-duration: 17 10198180
[ 24.581455] rcu-perf: 0 writer-duration: 18 4104338
[ 24.581972] rcu-perf: 0 writer-duration: 19 12919412
[ 24.582509] rcu-perf: 0 writer-duration: 20 7541866
[ 24.583028] rcu-perf: 0 writer-duration: 21 8239377
[ 24.583550] rcu-perf: 0 writer-duration: 22 6053952
[ 24.584097] rcu-perf: 0 writer-duration: 23 4063332
[ 24.584632] rcu-perf: 0 writer-duration: 24 7851038
[ 24.587058] rcu-perf: 0 writer-duration: 25 8998670
[ 24.587605] rcu-perf: 0 writer-duration: 26 7056747
[ 24.588172] rcu-perf: 0 writer-duration: 27 10278130
[ 24.588769] rcu-perf: 0 writer-duration: 28 5720813
[ 24.589290] rcu-perf: 0 writer-duration: 29 14608217
[ 24.589821] rcu-perf: 0 writer-duration: 30 7812547
[ 24.590347] rcu-perf: 0 writer-duration: 31 5685166
[ 24.590865] rcu-perf: 0 writer-duration: 32 16830795
[ 24.591394] rcu-perf: 0 writer-duration: 33 15016923
[ 24.591922] rcu-perf: 0 writer-duration: 34 2461499
[ 24.592465] rcu-perf: 0 writer-duration: 35 13550212
[ 24.592998] rcu-perf: 0 writer-duration: 36 5228438
[ 24.593522] rcu-perf: 0 writer-duration: 37 6765977
[ 24.594040] rcu-perf: 0 writer-duration: 38 15962939
[ 24.594573] rcu-perf: 0 writer-duration: 39 4904832
[ 24.595094] rcu-perf: 0 writer-duration: 40 10199420
[ 24.595624] rcu-perf: 0 writer-duration: 41 1300791
[ 24.596226] rcu-perf: 0 writer-duration: 42 18276234
[ 24.596803] rcu-perf: 0 writer-duration: 43 9333729
[ 24.597323] rcu-perf: 0 writer-duration: 44 11213655
[ 24.597858] rcu-perf: 0 writer-duration: 45 7401981
[ 24.598405] rcu-perf: 0 writer-duration: 46 8020770
[ 24.598921] rcu-perf: 0 writer-duration: 47 5511982
[ 24.599501] rcu-perf: 0 writer-duration: 48 4475692
[ 24.600061] rcu-perf: 0 writer-duration: 49 10120970
[ 24.600625] rcu-perf: 0 writer-duration: 50 10054020
[ 24.601150] rcu-perf: 0 writer-duration: 51 11221659
[ 24.601712] rcu-perf: 0 writer-duration: 52 9404613
[ 24.602229] rcu-perf: 0 writer-duration: 53 10223331
[ 24.602766] rcu-perf: 0 writer-duration: 54 5639148
[ 24.603288] rcu-perf: 0 writer-duration: 55 14764439
[ 24.603811] rcu-perf: 0 writer-duration: 56 14402760
[ 24.604377] rcu-perf: 0 writer-duration: 57 12214249
[ 24.604909] rcu-perf: 0 writer-duration: 58 21352098
[ 24.605441] rcu-perf: 0 writer-duration: 59 5547447
[ 24.605959] rcu-perf: 0 writer-duration: 60 10092273
[ 24.606489] rcu-perf: 0 writer-duration: 61 8344833
[ 24.607004] rcu-perf: 0 writer-duration: 62 12143650
[ 24.607563] rcu-perf: 0 writer-duration: 63 12454451
[ 24.608122] rcu-perf: 0 writer-duration: 64 8231354
[ 24.608672] rcu-perf: 0 writer-duration: 65 7717338
[ 24.609196] rcu-perf: 0 writer-duration: 66 9638216
[ 24.609718] rcu-perf: 0 writer-duration: 67 9719145
[ 24.610235] rcu-perf: 0 writer-duration: 68 9795647
[ 24.610757] rcu-perf: 0 writer-duration: 69 9875112
[ 24.611279] rcu-perf: 0 writer-duration: 70 8433003
[ 24.611795] rcu-perf: 0 writer-duration: 71 4028609
[ 24.612335] rcu-perf: 0 writer-duration: 72 9503266
[ 24.612859] rcu-perf: 0 writer-duration: 73 11349988
[ 24.613392] rcu-perf: 0 writer-duration: 74 11110045
[ 24.613918] rcu-perf: 0 writer-duration: 75 11994918
[ 24.614449] rcu-perf: 0 writer-duration: 76 4090418
[ 24.615030] rcu-perf: 0 writer-duration: 77 8023701
[ 24.615568] rcu-perf: 0 writer-duration: 78 7920557
[ 24.616106] rcu-perf: 0 writer-duration: 79 15952681
[ 24.616638] rcu-perf: 0 writer-duration: 80 8679771
[ 24.617157] rcu-perf: 0 writer-duration: 81 7355197
[ 24.617680] rcu-perf: 0 writer-duration: 82 8073142
[ 24.618196] rcu-perf: 0 writer-duration: 83 8049915
[ 24.618744] rcu-perf: 0 writer-duration: 84 13901797
[ 24.619266] rcu-perf: 0 writer-duration: 85 10014378
[ 24.619822] rcu-perf: 0 writer-duration: 86 15929177
[ 24.620387] rcu-perf: 0 writer-duration: 87 3986100
[ 24.620926] rcu-perf: 0 writer-duration: 88 4002486
[ 24.621451] rcu-perf: 0 writer-duration: 89 8016943
[ 24.621996] rcu-perf: 0 writer-duration: 90 8122712
[ 24.622522] rcu-perf: 0 writer-duration: 91 8549605
[ 24.623042] rcu-perf: 0 writer-duration: 92 8975393
[ 24.623568] rcu-perf: 0 writer-duration: 93 2680113
[ 24.624113] rcu-perf: 0 writer-duration: 94 7636624
[ 24.624667] rcu-perf: 0 writer-duration: 95 8950663
[ 24.625188] rcu-perf: 0 writer-duration: 96 10292913
[ 24.625717] rcu-perf: 0 writer-duration: 97 1162129
[ 24.626233] rcu-perf: 0 writer-duration: 98 9000133
[ 24.626752] rcu-perf: 0 writer-duration: 99 6686802
[ 24.627272] rcu-perf: 0 writer-duration: 100 16450475
[ 24.632039] rcu-perf: 0 writer-duration: 101 8546401
[ 24.632624] rcu-perf: 0 writer-duration: 102 9532378
[ 24.633130] rcu-perf: 0 writer-duration: 103 6490684
[ 24.633640] rcu-perf: 0 writer-duration: 104 12305839
[ 24.634154] rcu-perf: 0 writer-duration: 105 3763150
[ 24.634691] rcu-perf: 0 writer-duration: 106 15905597
[ 24.635218] rcu-perf: 1 writer-duration: 0 19972476
[ 24.640068] rcu-perf: 1 writer-duration: 1 8876887
[ 24.640609] rcu-perf: 1 writer-duration: 2 11111399
[ 24.641139] rcu-perf: 1 writer-duration: 3 9629340
[ 24.641701] rcu-perf: 1 writer-duration: 4 10431651
[ 24.642222] rcu-perf: 1 writer-duration: 5 9328685
[ 24.642748] rcu-perf: 1 writer-duration: 6 12427510
[ 24.643269] rcu-perf: 1 writer-duration: 7 10233591
[ 24.643792] rcu-perf: 1 writer-duration: 8 11431878
[ 24.644412] rcu-perf: 1 writer-duration: 9 9348410
[ 24.644933] rcu-perf: 1 writer-duration: 10 16106216
[ 24.645465] rcu-perf: 1 writer-duration: 11 7157389
[ 24.645984] rcu-perf: 1 writer-duration: 12 9347266
[ 24.646510] rcu-perf: 1 writer-duration: 13 7120464
[ 24.647029] rcu-perf: 1 writer-duration: 14 3562327
[ 24.647553] rcu-perf: 1 writer-duration: 15 7869123
[ 24.648118] rcu-perf: 1 writer-duration: 16 9016742
[ 24.648649] rcu-perf: 1 writer-duration: 17 3147678
[ 24.649172] rcu-perf: 1 writer-duration: 18 10064368
[ 24.649705] rcu-perf: 1 writer-duration: 19 5828011
[ 24.650224] rcu-perf: 1 writer-duration: 20 12165471
[ 24.650758] rcu-perf: 1 writer-duration: 21 9290485
[ 24.651280] rcu-perf: 1 writer-duration: 22 12785336
[ 24.651809] rcu-perf: 1 writer-duration: 23 9689184
[ 24.652466] rcu-perf: 1 writer-duration: 24 8036368
[ 24.652995] rcu-perf: 1 writer-duration: 25 12159043
[ 24.653532] rcu-perf: 1 writer-duration: 26 10440394
[ 24.654062] rcu-perf: 1 writer-duration: 27 5995096
[ 24.654588] rcu-perf: 1 writer-duration: 28 7380491
[ 24.655147] rcu-perf: 1 writer-duration: 29 9981860
[ 24.655671] rcu-perf: 1 writer-duration: 30 7070078
[ 24.656258] rcu-perf: 1 writer-duration: 31 6945000
[ 24.656785] rcu-perf: 1 writer-duration: 32 8500460
[ 24.657310] rcu-perf: 1 writer-duration: 33 3495830
[ 24.657829] rcu-perf: 1 writer-duration: 34 8852966
[ 24.658381] rcu-perf: 1 writer-duration: 35 7353037
[ 24.658901] rcu-perf: 1 writer-duration: 36 8682403
[ 24.659424] rcu-perf: 1 writer-duration: 37 16627958
[ 24.659952] rcu-perf: 1 writer-duration: 38 13561313
[ 24.660558] rcu-perf: 1 writer-duration: 39 8914164
[ 24.661079] rcu-perf: 1 writer-duration: 40 12090694
[ 24.661613] rcu-perf: 1 writer-duration: 41 13403216
[ 24.662144] rcu-perf: 1 writer-duration: 42 13034802
[ 24.662677] rcu-perf: 1 writer-duration: 43 9466367
[ 24.663198] rcu-perf: 1 writer-duration: 44 8053714
[ 24.663722] rcu-perf: 1 writer-duration: 45 3992789
[ 24.664287] rcu-perf: 1 writer-duration: 46 8938594
[ 24.664814] rcu-perf: 1 writer-duration: 47 7101820
[ 24.665363] rcu-perf: 1 writer-duration: 48 11621376
[ 24.665889] rcu-perf: 1 writer-duration: 49 4413139
[ 24.666411] rcu-perf: 1 writer-duration: 50 10967116
[ 24.666937] rcu-perf: 1 writer-duration: 51 2846889
[ 24.667456] rcu-perf: 1 writer-duration: 52 7243966
[ 24.667970] rcu-perf: 1 writer-duration: 53 9833846
[ 24.668544] rcu-perf: 1 writer-duration: 54 5276586
[ 24.669068] rcu-perf: 1 writer-duration: 55 8527485
[ 24.669595] rcu-perf: 1 writer-duration: 56 7411522
[ 24.670114] rcu-perf: 1 writer-duration: 57 11830321
[ 24.670645] rcu-perf: 1 writer-duration: 58 12062809
[ 24.671170] rcu-perf: 1 writer-duration: 59 11886319
[ 24.671701] rcu-perf: 1 writer-duration: 60 6255045
[ 24.672237] rcu-perf: 1 writer-duration: 61 5868874
[ 24.672765] rcu-perf: 1 writer-duration: 62 12872586
[ 24.673293] rcu-perf: 1 writer-duration: 63 18991802
[ 24.673846] rcu-perf: 1 writer-duration: 64 10192457
[ 24.674377] rcu-perf: 1 writer-duration: 65 10047039
[ 24.674905] rcu-perf: 1 writer-duration: 66 11772838
[ 24.675440] rcu-perf: 1 writer-duration: 67 15990568
[ 24.675968] rcu-perf: 1 writer-duration: 68 11076683
[ 24.676526] rcu-perf: 1 writer-duration: 69 9373740
[ 24.677048] rcu-perf: 1 writer-duration: 70 3772734
[ 24.677572] rcu-perf: 1 writer-duration: 71 15769734
[ 24.678099] rcu-perf: 1 writer-duration: 72 6405477
[ 24.678622] rcu-perf: 1 writer-duration: 73 7042460
[ 24.679140] rcu-perf: 1 writer-duration: 74 6534893
[ 24.679661] rcu-perf: 1 writer-duration: 75 9003974
[ 24.680203] rcu-perf: 1 writer-duration: 76 4114489
[ 24.680734] rcu-perf: 1 writer-duration: 77 13566346
[ 24.681274] rcu-perf: 1 writer-duration: 78 10248744
[ 24.681846] rcu-perf: 1 writer-duration: 79 11202144
[ 24.682378] rcu-perf: 1 writer-duration: 80 14522617
[ 24.682904] rcu-perf: 1 writer-duration: 81 9347783
[ 24.683427] rcu-perf: 1 writer-duration: 82 12048736
[ 24.683953] rcu-perf: 1 writer-duration: 83 15968978
[ 24.684548] rcu-perf: 1 writer-duration: 84 9128906
[ 24.685096] rcu-perf: 1 writer-duration: 85 10386890
[ 24.685624] rcu-perf: 1 writer-duration: 86 5310151
[ 24.686141] rcu-perf: 1 writer-duration: 87 13473204
[ 24.686666] rcu-perf: 1 writer-duration: 88 9894193
[ 24.687183] rcu-perf: 1 writer-duration: 89 9732502
[ 24.687702] rcu-perf: 1 writer-duration: 90 12227485
[ 24.688256] rcu-perf: 1 writer-duration: 91 13088007
[ 24.688786] rcu-perf: 1 writer-duration: 92 4723035
[ 24.689315] rcu-perf: 1 writer-duration: 93 8073663
[ 24.689836] rcu-perf: 1 writer-duration: 94 9670400
[ 24.690358] rcu-perf: 1 writer-duration: 95 11581775
[ 24.690884] rcu-perf: 1 writer-duration: 96 7589495
[ 24.691613] rcu-perf: 1 writer-duration: 97 13680809
[ 24.692466] rcu-perf: 1 writer-duration: 98 9561892
[ 24.693192] rcu-perf: 1 writer-duration: 99 7970345
[ 24.693816] rcu-perf: 1 writer-duration: 100 9788023
[ 24.700210] no ifx modem active;
[ 24.701420] ACPI: Preparing to enter system sleep state S5
[ 24.704534] reboot: Power down
[ 24.705645] acpi_power_off called
[ 24.713350] ------------[ cut here ]------------
[ 24.713804] WARNING: CPU: 0 PID: 11 at kernel/sched/core.c:1179 set_task_cpu+0x157/0x285
[ 24.714779] Modules linked in: i2c_piix4 input_leds psmouse i2c_core button
[ 24.715437] CPU: 0 PID: 11 Comm: migration/0 Not tainted 4.14.0-rc6-00077-g11224e1 #1
[ 24.716188] task: ffff880032955000 task.stack: ffff88003294c000
[ 24.716757] RIP: 0010:set_task_cpu+0x157/0x285
[ 24.717193] RSP: 0018:ffff88003294fe08 EFLAGS: 00010002
[ 24.717746] RAX: 0000000000000001 RBX: ffff880031449000 RCX: 0000000000000000
[ 24.718416] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff82e2ad60
[ 24.719091] RBP: ffff88003294fe20 R08: 0000000000000000 R09: 0000000000000000
[ 24.719789] R10: ffff880014483b60 R11: 0000000000000002 R12: 0000000000000001
[ 24.720477] R13: 0000000000000001 R14: ffff88003294fe68 R15: ffff8800347eb300
[ 24.721155] FS: 0000000000000000(0000) GS:ffff880034600000(0000) knlGS:0000000000000000
[ 24.721947] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 24.722479] CR2: 00007f1cdeec8fd0 CR3: 000000003722d000 CR4: 00000000000006b0
[ 24.723144] Call Trace:
[ 24.723388] move_queued_task+0x8c/0x108
[ 24.723758] migration_cpu_stop+0x82/0xb7
[ 24.724131] ? sched_ttwu_pending+0x78/0x78
[ 24.724748] ? sort_range+0x17/0x17
[ 24.725214] cpu_stopper_thread+0x87/0x14c
[ 24.725785] ? sort_range+0x17/0x17
[ 24.726194] smpboot_thread_fn+0x2a9/0x2c0
[ 24.726718] kthread+0xf8/0x100
[ 24.727113] ? kthread_unpark+0x5a/0x5a
[ 24.727532] ret_from_fork+0x25/0x30
[ 24.727856] Code: 0f a3 2d 85 d2 e3 01 41 0f 92 c5 41 83 f5 01 31 c9 31 d2 45 0f b6 ed 48 c7 c7 60 ad e2 82 44 89 ee e8 53 24 08 00 45 85 ed 74 02 <0f> ff 31 c9 31 d2 44 89 ee 48 c7 c7 30 ad e2 82 45 31 ed e8 36
[ 24.729721] ---[ end trace b7ae64ce6fa90a4f ]---
[ 24.730154] sched: Unexpected reschedule of offline CPU#1!
[ 24.730700] ------------[ cut here ]------------
[ 24.731133] WARNING: CPU: 0 PID: 11 at arch/x86/kernel/smp.c:128 native_smp_send_reschedule+0x69/0x9e
[ 24.732162] Modules linked in: i2c_piix4 input_leds psmouse i2c_core button
[ 24.732859] CPU: 0 PID: 11 Comm: migration/0 Tainted: G W 4.14.0-rc6-00077-g11224e1 #1
[ 24.733690] task: ffff880032955000 task.stack: ffff88003294c000
[ 24.734449] RIP: 0010:native_smp_send_reschedule+0x69/0x9e
[ 24.735158] RSP: 0018:ffff88003294fdf8 EFLAGS: 00010092
[ 24.735704] RAX: 000000000000002e RBX: 0000000000000001 RCX: 0000000000000000
[ 24.736382] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff82e32ef0
[ 24.737009] RBP: 0000000000000001 R08: 0000000000000001 R09: 0000000000000000
[ 24.737711] R10: ffff88003294fd30 R11: 0000000000000005 R12: ffff880031449000
[ 24.738370] R13: 0000000000000000 R14: ffff88003294fe68 R15: ffff8800347eb300
[ 24.738997] FS: 0000000000000000(0000) GS:ffff880034600000(0000) knlGS:0000000000000000
[ 24.739754] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 24.740261] CR2: 00007f1cdeec8fd0 CR3: 000000003722d000 CR4: 00000000000006b0
[ 24.740921] Call Trace:
[ 24.741144] check_preempt_curr+0x47/0xc1
[ 24.741534] move_queued_task+0xfa/0x108
[ 24.741886] migration_cpu_stop+0x82/0xb7
[ 24.742240] ? sched_ttwu_pending+0x78/0x78
[ 24.742649] ? sort_range+0x17/0x17
[ 24.742974] cpu_stopper_thread+0x87/0x14c
[ 24.743401] ? sort_range+0x17/0x17
[ 24.743731] smpboot_thread_fn+0x2a9/0x2c0
[ 24.744111] kthread+0xf8/0x100
[ 24.744414] ? kthread_unpark+0x5a/0x5a
[ 24.744769] ret_from_fork+0x25/0x30
[ 24.745116] Code: ed 74 44 b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 50 c4 e1 82 e8 e4 d4 0d 00 89 de 48 c7 c7 76 4c 86 81 31 c0 e8 e1 5e 08 00 <0f> ff 5b 5d b9 01 00 00 00 31 d2 be 01 00 00 00 48 c7 c7 20 c4
[ 24.746913] ---[ end trace b7ae64ce6fa90a50 ]---

Elapsed time: 2040
BUG: kernel hang in test stage

initrds=(
/osimage/debian/debian-x86_64-2016-08-31.cgz
/lkp/scheduled/vm-vp-1G-1/boot-1-debian-x86_64-2016-08-31.cgz-11224e1fc40a6556dc285573ea93dd522f8c563f-20171028-56276-1pkvi7m-3.cgz
/lkp/lkp/lkp-x86_64.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-08-01.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz
/pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/modules.cgz
)

cat "${initrds[@]}" > /fs/sdc1/initrd-vm-vp-1G-1

kvm=(
qemu-system-x86_64
-enable-kvm
-cpu Nehalem
-kernel /pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/vmlinuz-4.14.0-rc6-00077-g11224e1
-initrd /fs/sdc1/initrd-vm-vp-1G-1
-m 1024
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0,hostfwd=tcp::23100-:22
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=/fs/sdc1/disk0-vm-vp-1G-1,media=disk,if=virtio
-drive file=/fs/sdc1/disk1-vm-vp-1G-1,media=disk,if=virtio
-drive file=/fs/sdc1/disk2-vm-vp-1G-1,media=disk,if=virtio
-drive file=/fs/sdc1/disk3-vm-vp-1G-1,media=disk,if=virtio
-drive file=/fs/sdc1/disk4-vm-vp-1G-1,media=disk,if=virtio
-drive file=/fs/sdc1/disk5-vm-vp-1G-1,media=disk,if=virtio
-pidfile /dev/shm/kboot/pid-vm-vp-1G-1
-serial file:/dev/shm/kboot/vm-vp-1G-1/serial
-serial file:/dev/shm/kboot/vm-vp-1G-1/kmsg
-daemonize
-display none
-monitor null
)

append=(
ip=::::vm-vp-1G-1::dhcp
root=/dev/ram0
user=lkp
job=/lkp/scheduled/vm-vp-1G-1/boot-1-debian-x86_64-2016-08-31.cgz-11224e1fc40a6556dc285573ea93dd522f8c563f-20171028-56276-1pkvi7m-3.yaml
ARCH=x86_64
kconfig=x86_64-randconfig-n0-10180128
branch=linux-devel/devel-spot-201710172231
commit=11224e1fc40a6556dc285573ea93dd522f8c563f
BOOT_IMAGE=/pkg/linux/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/vmlinuz-4.14.0-rc6-00077-g11224e1
max_uptime=600
RESULT_ROOT=/result/boot/1/vm-vp-1G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-n0-10180128/gcc-4.8/11224e1fc40a6556dc285573ea93dd522f8c563f/3
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
)

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

Next message: Christoffer Dall: "Re: [PATCH v5 01/26] irqchip/gic-v3-its: Setup VLPI properties at map time"
Previous message: Oleksandr Andrushchenko: "[RFC] ALSA: vsnd: Add Xen para-virtualized frontend driver"
In reply to: Peter Zijlstra: "Re: Linux 4.14-rc6: WARNING: CPU: 9 PID: 5377 at arch/x86/events/intel/core.c:2228 intel_pmu_handle_irq+0x4a8/0x4c0"
Next in thread: Fengguang Wu: "[haswell_crtc_enable] WARNING: CPU: 3 PID: 109 at drivers/gpu/drm/drm_vblank.c:1066 drm_wait_one_vblank+0x18f/0x1a0 [drm]"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]