2024-02-29 14:39:32

by Oliver Sang

[permalink] [raw]
Subject: [mhiramat:topic/fprobe-on-fgraph] [function_graph] ab712273e5: WARNING:at_kernel/trace/trace.c:#run_tracer_selftest



Hello,

kernel test robot noticed "WARNING:at_kernel/trace/trace.c:#run_tracer_selftest" on:

commit: ab712273e5b93b2162bd25823c50afa067c971d7 ("function_graph: Pass ftrace_regs to entryfunc")
https://git.kernel.org/cgit/linux/kernel/git/mhiramat/linux.git topic/fprobe-on-fgraph

in testcase: boot

compiler: gcc-11
test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 4G

(please refer to attached dmesg/kmsg for entire log/backtrace)


+-------------------------------------------------------------+------------+------------+
| | 18e3163a72 | ab712273e5 |
+-------------------------------------------------------------+------------+------------+
| WARNING:at_kernel/trace/trace.c:#run_tracer_selftest | 0 | 17 |
| EIP:run_tracer_selftest | 0 | 17 |
+-------------------------------------------------------------+------------+------------+


If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <[email protected]>
| Closes: https://lore.kernel.org/oe-lkp/[email protected]


[ 9.224340][ T1] ------------[ cut here ]------------
[ 9.225209][ T1] WARNING: CPU: 1 PID: 1 at kernel/trace/trace.c:2053 run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.226920][ T1] Modules linked in:
[ 9.227542][ T1] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 6.8.0-rc4-00021-gab712273e5b9 #1 b22c8cda139bb32aef1f09ba4b94f28d718abc25
[ 9.229490][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 9.230911][ T1] EIP: run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.231772][ T1] Code: 00 00 00 00 8b 15 5c 14 27 c3 b9 ff ff ff ff a1 a8 14 27 c3 e8 23 60 ff ff e9 8c fe ff ff c7 04 24 c3 18 ca c2 e8 ea 9d f4 ff <0f> 0b be ff ff ff ff e9 60 ff ff ff be f4 ff ff ff e9 56 ff ff ff
All code
========
0: 00 00 add %al,(%rax)
2: 00 00 add %al,(%rax)
4: 8b 15 5c 14 27 c3 mov -0x3cd8eba4(%rip),%edx # 0xffffffffc3271466
a: b9 ff ff ff ff mov $0xffffffff,%ecx
f: a1 a8 14 27 c3 e8 23 movabs 0xff6023e8c32714a8,%eax
16: 60 ff
18: ff (bad)
19: e9 8c fe ff ff jmp 0xfffffffffffffeaa
1e: c7 04 24 c3 18 ca c2 movl $0xc2ca18c3,(%rsp)
25: e8 ea 9d f4 ff call 0xfffffffffff49e14
2a:* 0f 0b ud2 <-- trapping instruction
2c: be ff ff ff ff mov $0xffffffff,%esi
31: e9 60 ff ff ff jmp 0xffffffffffffff96
36: be f4 ff ff ff mov $0xfffffff4,%esi
3b: e9 56 ff ff ff jmp 0xffffffffffffff96

Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: be ff ff ff ff mov $0xffffffff,%esi
7: e9 60 ff ff ff jmp 0xffffffffffffff6c
c: be f4 ff ff ff mov $0xfffffff4,%esi
11: e9 56 ff ff ff jmp 0xffffffffffffff6c
[ 9.234972][ T1] EAX: 00000007 EBX: c34453a0 ECX: 00000000 EDX: 00000000
[ 9.234972][ T1] ESI: ffffffff EDI: c3479760 EBP: c4c47e84 ESP: c4c47e70
[ 9.236035][ T1] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00010246
[ 9.237240][ T1] CR0: 80050033 CR2: 00000000 CR3: 03739000 CR4: 000406d0
[ 9.238361][ T1] Call Trace:
[ 9.238877][ T1] ? show_regs (arch/x86/kernel/dumpstack.c:479)
[ 9.238912][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.239756][ T1] ? __warn (kernel/panic.c:677)
[ 9.240397][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.241233][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.242070][ T1] ? report_bug (lib/bug.c:201 lib/bug.c:219)
[ 9.242782][ T1] ? exc_overflow (arch/x86/kernel/traps.c:251)
[ 9.242911][ T1] ? handle_bug (arch/x86/kernel/traps.c:238)
[ 9.243607][ T1] ? exc_invalid_op (arch/x86/kernel/traps.c:259 (discriminator 1))
[ 9.244341][ T1] ? __wake_up_klogd+0x49/0x70
[ 9.245189][ T1] ? preempt_count_sub (kernel/sched/core.c:5881 kernel/sched/core.c:5877 kernel/sched/core.c:5899)
[ 9.245969][ T1] ? handle_exception (arch/x86/entry/entry_32.S:1056)
[ 9.246767][ T1] ? exc_overflow (arch/x86/kernel/traps.c:251)
[ 9.250912][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.251749][ T1] ? exc_overflow (arch/x86/kernel/traps.c:251)
[ 9.252458][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2053 (discriminator 1))
[ 9.253288][ T1] ? graph_depth_read (kernel/trace/trace_functions_graph.c:1349)
[ 9.254057][ T1] register_tracer (kernel/trace/trace.c:2086 kernel/trace/trace.c:2210)
[ 9.254800][ T1] ? register_trace_event (kernel/trace/trace_output.c:783)
[ 9.254910][ T1] ? init_graph_tracefs (kernel/trace/trace_functions_graph.c:1449)
[ 9.255706][ T1] init_graph_trace (kernel/trace/trace_functions_graph.c:1463)
[ 9.256448][ T1] do_one_initcall (init/main.c:1236)
[ 9.257185][ T1] ? parse_args (kernel/params.c:183)
[ 9.257891][ T1] ? rdinit_setup (init/main.c:1282)
[ 9.258602][ T1] ? rdinit_setup (init/main.c:1282)
[ 9.258918][ T1] do_initcalls (init/main.c:1297 init/main.c:1314)
[ 9.259617][ T1] ? rdinit_setup (init/main.c:1282)
[ 9.260332][ T1] kernel_init_freeable (init/main.c:1555)
[ 9.261149][ T1] ? kernel_init (init/main.c:1443)
[ 9.261870][ T1] ? rest_init (init/main.c:1433)
[ 9.262567][ T1] kernel_init (init/main.c:1443)
[ 9.262911][ T1] ? schedule_tail (kernel/sched/core.c:5338)
[ 9.263644][ T1] ret_from_fork (arch/x86/kernel/process.c:153)
[ 9.264345][ T1] ? rest_init (init/main.c:1433)
[ 9.265046][ T1] ret_from_fork_asm (arch/x86/entry/entry_32.S:741)
[ 9.265798][ T1] entry_INT80_32 (arch/x86/entry/entry_32.S:947)
[ 9.266571][ T1] irq event stamp: 5037117
[ 9.266911][ T1] hardirqs last enabled at (5037127): console_unlock (kernel/printk/printk.c:341 kernel/printk/printk.c:2706 kernel/printk/printk.c:3038)
[ 9.268327][ T1] hardirqs last disabled at (5037134): console_unlock (kernel/printk/printk.c:339 kernel/printk/printk.c:2706 kernel/printk/printk.c:3038)
[ 9.269742][ T1] softirqs last enabled at (5036722): __do_softirq (arch/x86/include/asm/preempt.h:26 kernel/softirq.c:400 kernel/softirq.c:582)
[ 9.270910][ T1] softirqs last disabled at (5036717): do_softirq_own_stack (arch/x86/kernel/irq_32.c:57 arch/x86/kernel/irq_32.c:147)
[ 9.272383][ T1] ---[ end trace 0000000000000000 ]---
[ 9.273787][ T1] pinctrl core: initialized pinctrl subsystem
[ 9.275831][ T1] workqueue: round-robin CPU selection forced, expect performance impact
[ 9.277324][ T1]


The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20240229/[email protected]



--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki