Qemu-arm64: kvm-unit-tests: WARNING: at kernel/events/core.c:244 event_function

From: Naresh Kamboju
Date: Tue Oct 17 2023 - 04:58:19 EST


Following kernel warning noticed while running kvm-unit-tests on qemu-arm64
running stable-rc 6.1.56 kernel.

This kernel warning occurs intermittently on qemu-arm64 with kernel command
line option "kvm-arm.mode=protected".

Boot log:
[ 0.000000] Linux version 6.1.56 (tuxmake@tuxmake)
(aarch64-linux-gnu-gcc (Debian 13.2.0-2) 13.2.0, GNU ld (GNU Binutils
for Debian) 2.41) #1 SMP PREEMPT @1696601727
[ 0.000000] random: crng init done
[ 0.000000] Machine model: linux,dummy-virt
...
<6>[ 0.204459] CPU features: detected: Protected KVM
...
<6>[ 1.439501] kvm [1]: IPA Size Limit: 48 bits
<6>[ 1.620529] kvm [1]: GICv3: no GICV resource entry
<6>[ 1.623060] kvm [1]: disabling GICv2 emulation
<6>[ 1.624247] kvm [1]: GIC system register CPU interface enabled
<6>[ 1.626934] kvm [1]: vgic interrupt IRQ9
<6>[ 1.632519] kvm [1]: Protected nVHE mode initialized successfully


Test log:
---------
+ ./run_tests.sh -a -v
+ tee -a /lava-1/0/tests/0_kvm-unit-tests/automated/linux/kvm-unit-tests/output/result_log.txt
<31>[ 68.843857] systemd[1]: systemd-udevd.service: Got notification
message from PID 226 (WATCHDOG=1)
<31>[ 97.299940] systemd[1]: Received SIGCHLD from PID 351 (bash).
<31>[ 97.320211] systemd[1]: Child 351 (bash) died (code=exited,
status=0/SUCCESS)
<31>[ 97.352577] systemd[1]: Child 352 (grep) died (code=exited,
status=1/FAILURE)
<4>[ 100.375043] ------------[ cut here ]------------
<4>[ 100.376812] WARNING: CPU: 0 PID: 356 at kernel/events/core.c:244
event_function (kernel/events/core.c:244 (discriminator 1))
<4>[ 100.378656] Modules linked in: fuse drm dm_mod ip_tables x_tables
<4>[ 100.381530] CPU: 0 PID: 356 Comm: qemu-system-aar Not tainted 6.1.56 #1
<4>[ 100.382823] Hardware name: linux,dummy-virt (DT)
<4>[ 100.384931] pstate: 624000c5 (nZCv daIF +PAN -UAO +TCO -DIT
-SSBS BTYPE=--)
<4>[ 100.386199] pc : event_function (kernel/events/core.c:244
(discriminator 1))
<4>[ 100.387840] lr : event_function (kernel/events/core.c:168
kernel/events/core.c:226)
<4>[ 100.388527] sp : ffff80000858b750
<4>[ 100.389629] x29: ffff80000858b750 x28: 0000000000000000 x27:
ffffad552ce81c00
<4>[ 100.391419] x26: ffff0000c4f90000 x25: ffff80000858b8d0 x24:
ffff0000c4f90000
<4>[ 100.392473] x23: ffff0000ff7ba9e8 x22: 0000000000000000 x21:
ffff0000c3ddca00
<4>[ 100.393558] x20: ffff80000858b8d0 x19: ffff0000ff7ba9e0 x18:
0000000000000000
<4>[ 100.394601] x17: 0000000000000000 x16: 0000000000000000 x15:
0000000000000000
<4>[ 100.395660] x14: 0000000000000000 x13: ffff0000c03a0031 x12:
3430376239633038
<4>[ 100.396686] x11: 0000002042c35bc1 x10: aff705512bd33e45 x9 :
ffffad552df9bb24
<4>[ 100.397603] x8 : ffff80000858b708 x7 : 0000000000000000 x6 :
0000000000000001
<4>[ 100.398525] x5 : ffff80000858c000 x4 : ffff800008588000 x3 :
0000000000000000
<4>[ 100.399502] x2 : 0000000000000001 x1 : ffff0000c4d21080 x0 :
0000000000000000
<4>[ 100.400742] Call trace:
<4>[ 100.401070] event_function (kernel/events/core.c:244 (discriminator 1))
<4>[ 100.401781] remote_function (kernel/events/core.c:92
(discriminator 1) kernel/events/core.c:72 (discriminator 1))
<4>[ 100.402355] generic_exec_single
(arch/arm64/include/asm/irqflags.h:85 kernel/smp.c:522)
<4>[ 100.403777] smp_call_function_single (kernel/smp.c:773)
<4>[ 100.404283] event_function_call (kernel/events/core.c:123
kernel/events/core.c:289)
<4>[ 100.405433] perf_event_disable (kernel/events/core.c:1321
kernel/events/core.c:2499)
<4>[ 100.405816] kvm_pmu_probe_armpmu (arch/arm64/kvm/pmu-emul.c:711)
<4>[ 100.406563] kvm_arm_pmu_v3_set_attr
(arch/arm64/kvm/pmu-emul.c:890 (discriminator 1))
<4>[ 100.407333] kvm_arm_vcpu_arch_set_attr (arch/arm64/kvm/guest.c:955)
<4>[ 100.407774] kvm_arch_vcpu_ioctl (arch/arm64/kvm/arm.c:1386
(discriminator 1))
<4>[ 100.408360] kvm_vcpu_ioctl
(arch/arm64/kvm/../../../virt/kvm/kvm_main.c:4081)
<4>[ 100.409176] __arm64_sys_ioctl (fs/ioctl.c:52 fs/ioctl.c:870
fs/ioctl.c:856 fs/ioctl.c:856)
<4>[ 100.409796] invoke_syscall (arch/arm64/include/asm/current.h:19
arch/arm64/kernel/syscall.c:57)
<4>[ 100.410395] el0_svc_common.constprop.0
(arch/arm64/include/asm/daifflags.h:28
arch/arm64/kernel/syscall.c:150)
<4>[ 100.411070] do_el0_svc (arch/arm64/kernel/syscall.c:207)
<4>[ 100.411603] el0_svc (arch/arm64/include/asm/daifflags.h:28
arch/arm64/kernel/entry-common.c:133
arch/arm64/kernel/entry-common.c:142
arch/arm64/kernel/entry-common.c:638)
<4>[ 100.412158] el0t_64_sync_handler (arch/arm64/kernel/entry-common.c:656)
<4>[ 100.412574] el0t_64_sync (arch/arm64/kernel/entry.S:581)
<4>[ 100.413766] ---[ end trace 0000000000000000 ]---
<4>[ 100.414762] ------------[ cut here ]------------
<4>[ 100.415317] WARNING: CPU: 0 PID: 356 at kernel/events/core.c:249
event_function (kernel/events/core.c:249 (discriminator 1))
<4>[ 100.416404] Modules linked in: fuse drm dm_mod ip_tables x_tables
<4>[ 100.417743] CPU: 0 PID: 356 Comm: qemu-system-aar Tainted: G
W 6.1.56 #1
<4>[ 100.418745] Hardware name: linux,dummy-virt (DT)
<4>[ 100.419503] pstate: a24000c5 (NzCv daIF +PAN -UAO +TCO -DIT
-SSBS BTYPE=--)
<4>[ 100.420538] pc : event_function (kernel/events/core.c:249
(discriminator 1))
<4>[ 100.421156] lr : event_function (kernel/events/core.c:168
kernel/events/core.c:226)
<4>[ 100.422364] sp : ffff80000858b750
<4>[ 100.422967] x29: ffff80000858b750 x28: 0000000000000000 x27:
ffffad552ce81c00
<4>[ 100.424161] x26: ffff0000c4f90000 x25: ffff80000858b8d0 x24:
ffff0000c4f90000
<4>[ 100.424839] x23: ffff0000ff7ba9e8 x22: 0000000000000000 x21:
ffff0000c3ddca00
<4>[ 100.425502] x20: ffff80000858b8d0 x19: ffff0000ff7ba9e0 x18:
0000000000000000
<4>[ 100.426185] x17: 0000000000000000 x16: 0000000000000000 x15:
0000000000000000
<4>[ 100.428958] x14: 0000000000000000 x13: ffff0000c03a0031 x12:
3430376239633038
<4>[ 100.429930] x11: 0000002042c35bc1 x10: aff705512bd33e45 x9 :
ffffad552df9bb24
<4>[ 100.430938] x8 : ffff80000858b708 x7 : 0000000000000000 x6 :
0000000000000001
<4>[ 100.431829] x5 : ffff80000858c000 x4 : ffff800008588000 x3 :
0000000000000000
<4>[ 100.432767] x2 : 0000000000000001 x1 : ffff0000c4d21080 x0 :
0000000000000000
<4>[ 100.433677] Call trace:
<4>[ 100.434167] event_function (kernel/events/core.c:249 (discriminator 1))
<4>[ 100.435066] remote_function (kernel/events/core.c:92
(discriminator 1) kernel/events/core.c:72 (discriminator 1))
<4>[ 100.435497] generic_exec_single
(arch/arm64/include/asm/irqflags.h:85 kernel/smp.c:522)
<4>[ 100.435875] smp_call_function_single (kernel/smp.c:773)
<4>[ 100.436758] event_function_call (kernel/events/core.c:123
kernel/events/core.c:289)
<4>[ 100.437374] perf_event_disable (kernel/events/core.c:1321
kernel/events/core.c:2499)
<4>[ 100.438024] kvm_pmu_probe_armpmu (arch/arm64/kvm/pmu-emul.c:711)
<4>[ 100.438834] kvm_arm_pmu_v3_set_attr
(arch/arm64/kvm/pmu-emul.c:890 (discriminator 1))
<4>[ 100.439329] kvm_arm_vcpu_arch_set_attr (arch/arm64/kvm/guest.c:955)
<4>[ 100.439981] kvm_arch_vcpu_ioctl (arch/arm64/kvm/arm.c:1386
(discriminator 1))
<4>[ 100.440393] kvm_vcpu_ioctl
(arch/arm64/kvm/../../../virt/kvm/kvm_main.c:4081)
<4>[ 100.441150] __arm64_sys_ioctl (fs/ioctl.c:52 fs/ioctl.c:870
fs/ioctl.c:856 fs/ioctl.c:856)
<4>[ 100.441766] invoke_syscall (arch/arm64/include/asm/current.h:19
arch/arm64/kernel/syscall.c:57)
<4>[ 100.442312] el0_svc_common.constprop.0
(arch/arm64/include/asm/daifflags.h:28
arch/arm64/kernel/syscall.c:150)
<4>[ 100.442801] do_el0_svc (arch/arm64/kernel/syscall.c:207)
<4>[ 100.443590] el0_svc (arch/arm64/include/asm/daifflags.h:28
arch/arm64/kernel/entry-common.c:133
arch/arm64/kernel/entry-common.c:142
arch/arm64/kernel/entry-common.c:638)
<4>[ 100.444125] el0t_64_sync_handler (arch/arm64/kernel/entry-common.c:656)
<4>[ 100.444779] el0t_64_sync (arch/arm64/kernel/entry.S:581)
<4>[ 100.445155] ---[ end trace 0000000000000000 ]---


Links:
- https://tuxapi.tuxsuite.com/v1/groups/linaro/projects/lkft/tests/2WOQiJhgccWJ1UTjge0VQWINEgG
- https://qa-reports.linaro.org/lkft/linux-stable-rc-linux-6.1.y/build/v6.1.56/testrun/20294213/suite/log-parser-test/tests/
- https://qa-reports.linaro.org/lkft/linux-stable-rc-linux-6.1.y/build/v6.1.56/testrun/20294213/suite/log-parser-test/test/check-kernel-exception-1c1a9bb72134660eb4c5af3558fbbda6c3a8e1827dcf791ce1cc0e2e5aa9aaba/log

Buid:
- https://storage.tuxsuite.com/public/linaro/lkft/builds/2WOQgZEtR7bb2LJfBNhOZzbaZZY/


--
Linaro LKFT
https://lkft.linaro.org