2022-01-25 13:36:37

by Naresh Kamboju

[permalink] [raw]
Subject: [next] WARNING: CPU: 0 PID: 0 at kernel/trace/trace_events.c:417 trace_event_raw_init

[Please ignore this report if it is already reported]

Following kernel WARNING: reported while boot linux next 20220125 tag
on qemu_arm64.
While testing LTP ftrace the following kernel WARNING: noticed.

[ 0.000000] ------------[ cut here ]------------
[ 0.000000] WARNING: CPU: 0 PID: 0 at
kernel/trace/trace_events.c:417 trace_event_raw_init+0x490/0x4ec
[ 0.000000] Modules linked in:
[ 0.000000] CPU: 0 PID: 0 Comm: swapper/0 Not tainted
5.17.0-rc1-next-20220125 #1
[ 0.000000] Hardware name: linux,dummy-virt (DT)
[ 0.000000] pstate: 600000c5 (nZCv daIF -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[ 0.000000] pc : trace_event_raw_init+0x490/0x4ec
[ 0.000000] lr : trace_event_raw_init+0x250/0x4ec
[ 0.000000] sp : ffffc91900963d30
[ 0.000000] x29: ffffc91900963d30 x28: 0000000000000005 x27: 0000000000000000
[ 0.000000] x26: 0000000000000003 x25: ffffc918ff56c8e8 x24: ffffc918ffc57bf8
[ 0.000000] x23: 0000000000000001 x22: ffffc91900d4bf18 x21: 0000000000000000
[ 0.000000] x20: ffffc91900d44fa8 x19: 0000000000000251 x18: 00000000ffffffff
[ 0.000000] x17: 000000000000003f x16: 0000000000000009 x15: ffffffffffffffff
[ 0.000000] x14: 0000000000000000 x13: ffffffffffffffff x12: 0000000000000028
[ 0.000000] x11: 0101010101010101 x10: 0000000000000403 x9 : ffffc918fe3d2600
[ 0.000000] x8 : 0101010101010101 x7 : 7f7f7f7f7f7f7f7f x6 : fefefefefefeff28
[ 0.000000] x5 : 000000000000003e x4 : 0000000000000008 x3 : 0000000000000000
[ 0.000000] x2 : 000000000000023f x1 : 0000000000000000 x0 : 0000000000000000
[ 0.000000] Call trace:
[ 0.000000] trace_event_raw_init+0x490/0x4ec
[ 0.000000] event_init+0x38/0xb0
[ 0.000000] trace_event_init+0x9c/0x2ac
[ 0.000000] trace_init+0x10/0x1c
[ 0.000000] start_kernel+0x474/0x710
[ 0.000000] __primary_switched+0xc0/0xc8
[ 0.000000] ---[ end trace 0000000000000000 ]---

and
Running tests.......
[ 6.791530] ------------[ ftrace bug ]------------
[ 6.791941] ftrace faulted on modifying
[ 6.791943] [<ffffc918fff403fc>] set_reset_devices+0x8/0x24
[ 6.792681] Setting ftrace call site to call ftrace function
[ 6.793111] ftrace record flags: 80000001
[ 6.793384] (1)
[ 6.793384] expected tramp: ffffc918fe22bd20
[ 6.793868] ------------[ cut here ]------------
[ 6.794212] WARNING: CPU: 1 PID: 468 at kernel/trace/ftrace.c:2068
ftrace_bug+0x280/0x2b0
[ 6.794879] Modules linked in: rfkill crct10dif_ce fuse
[ 6.795300] CPU: 1 PID: 468 Comm: ftrace_regressi Tainted: G
W 5.17.0-rc1-next-20220125 #1
[ 6.796074] Hardware name: linux,dummy-virt (DT)
[ 6.796453] pstate: 60400005 (nZCv daif +PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[ 6.797016] pc : ftrace_bug+0x280/0x2b0
[ 6.797331] lr : ftrace_bug+0x280/0x2b0
[ 6.797649] sp : ffff8000088a3af0
[ 6.797921] x29: ffff8000088a3af0 x28: ffff0000c1b2a000 x27: 0000000000000000
[ 6.798507] x26: 0000000000000000 x25: ffff8000088a3d40 x24: ffffc91900e24000
[ 6.799090] x23: 00000000fffffff2 x22: ffffc918ffc46248 x21: ffffc9190096ecc8
[ 6.799670] x20: ffff0000c0080030 x19: ffffc9190096e000 x18: ffffffffffffffff
[ 6.800242] x17: 0000000000000000 x16: 0000000000000000 x15: ffff8000888a3817
[ 6.800815] x14: 0000000000000000 x13: 3032646232326566 x12: 3831396366666666
[ 6.801398] x11: 203a706d61727420 x10: ffffc91900991ba0 x9 : ffffc918fe31bbc4
[ 6.801979] x8 : 00000000ffffefff x7 : ffffc919009e9ba0 x6 : 0000000000000001
[ 6.802557] x5 : ffffc9190096f000 x4 : ffffc9190096f280 x3 : 0000000000000000
[ 6.803138] x2 : 0000000000000000 x1 : ffff0000c1b2a000 x0 : 0000000000000022
[ 6.803700] Call trace:
[ 6.803899] ftrace_bug+0x280/0x2b0
[ 6.804185] ftrace_replace_code+0x94/0xa0
[ 6.804523] ftrace_modify_all_code+0xe0/0x14c
[ 6.804886] arch_ftrace_update_code+0x14/0x20
[ 6.805255] ftrace_run_update_code+0x24/0x7c
[ 6.805611] ftrace_startup+0xf4/0x1b0
[ 6.805919] register_ftrace_function+0x38/0x90
[ 6.806290] stack_trace_sysctl+0xa0/0x190
[ 6.806630] proc_sys_call_handler+0x18c/0x250
[ 6.806993] proc_sys_write+0x20/0x30
[ 6.807295] new_sync_write+0xf0/0x18c
[ 6.807607] vfs_write+0x244/0x2b0
[ 6.807889] ksys_write+0x74/0x100
[ 6.808168] __arm64_sys_write+0x28/0x3c
[ 6.808491] invoke_syscall+0x50/0x120
[ 6.808800] el0_svc_common.constprop.0+0x104/0x124
[ 6.809427] do_el0_svc+0x34/0x9c
[ 6.809703] el0_svc+0x2c/0x90
[ 6.809961] el0t_64_sync_handler+0xa4/0x130
[ 6.810318] el0t_64_sync+0x1a0/0x1a4
[ 6.810617] ---[ end trace 0000000000000000 ]---

Reported-by: Linux Kernel Functional Testing <[email protected]>

ref:
- https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20220125/testrun/7535055/suite/linux-log-parser/test/check-kernel-warning-4390834/log
- https://lkft.validation.linaro.org/scheduler/job/4390834#L282

metadata:
git branch: master
git repo: https://gitlab.com/Linaro/lkft/mirrors/next/linux-next
git describe: next-20220125
kernel-config: https://builds.tuxbuild.com/24B14LVWVfE8GOgox8KftnlXxPf/config


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