[mhiramat:topic/fprobe-on-fgraph] [function_graph] b92a5e78c3: WARNING:at_kernel/trace/trace.c:#run_tracer_selftest

From: kernel test robot
Date: Mon Dec 18 2023 - 02:05:07 EST




Hello,

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

commit: b92a5e78c35fde3c1be7263b39724388482a4bd9 ("function_graph: Add a new entry handler with parent_ip and ftrace_regs")
https://git.kernel.org/cgit/linux/kernel/git/mhiramat/linux.git topic/fprobe-on-fgraph

in testcase: trinity
version: trinity-i386-abe9de86-1_20230429
with following parameters:

runtime: 600s

test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/


compiler: gcc-12
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G

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


+----------------------------------------------------------------------------------+------------+------------+
| | 26fcffc6d7 | b92a5e78c3 |
+----------------------------------------------------------------------------------+------------+------------+
| WARNING:at_kernel/trace/trace.c:#run_tracer_selftest | 0 | 8 |
| EIP:run_tracer_selftest | 0 | 8 |
+----------------------------------------------------------------------------------+------------+------------+


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 <oliver.sang@xxxxxxxxx>
| Closes: https://lore.kernel.org/oe-lkp/202312181100.508f8f48-oliver.sang@xxxxxxxxx


[ 5.502226][ T1] ------------[ cut here ]------------
[ 5.502226][ T1] WARNING: CPU: 1 PID: 1 at kernel/trace/trace.c:2031 run_tracer_selftest (kernel/trace/trace.c:2031 (discriminator 1))
[ 5.502899][ T1] Modules linked in:
[ 5.503348][ T1] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 6.7.0-rc2-00034-gb92a5e78c35f #1
[ 5.504358][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 5.505125][ T1] EIP: run_tracer_selftest (kernel/trace/trace.c:2031 (discriminator 1))
[ 5.505764][ T1] Code: ff ff a3 18 30 4b c2 89 f8 89 15 1c 30 4b c2 e8 cb b1 ff ff 89 f8 e8 58 52 ff ff e9 c1 fe ff ff 68 50 d6 f9 c1 e8 2d 61 f7 ff <0f> 0b b8 ff ff ff ff 5a e9 22 ff ff ff b8 f4 ff ff ff e9 18 ff ff
All code
========
0: ff (bad)
1: ff a3 18 30 4b c2 jmp *-0x3db4cfe8(%rbx)
7: 89 f8 mov %edi,%eax
9: 89 15 1c 30 4b c2 mov %edx,-0x3db4cfe4(%rip) # 0xffffffffc24b302b
f: e8 cb b1 ff ff call 0xffffffffffffb1df
14: 89 f8 mov %edi,%eax
16: e8 58 52 ff ff call 0xffffffffffff5273
1b: e9 c1 fe ff ff jmp 0xfffffffffffffee1
20: 68 50 d6 f9 c1 push $0xffffffffc1f9d650
25: e8 2d 61 f7 ff call 0xfffffffffff76157
2a:* 0f 0b ud2 <-- trapping instruction
2c: b8 ff ff ff ff mov $0xffffffff,%eax
31: 5a pop %rdx
32: e9 22 ff ff ff jmp 0xffffffffffffff59
37: b8 f4 ff ff ff mov $0xfffffff4,%eax
3c: e9 .byte 0xe9
3d: 18 ff sbb %bh,%bh
3f: ff .byte 0xff

Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: b8 ff ff ff ff mov $0xffffffff,%eax
7: 5a pop %rdx
8: e9 22 ff ff ff jmp 0xffffffffffffff2f
d: b8 f4 ff ff ff mov $0xfffffff4,%eax
12: e9 .byte 0xe9
13: 18 ff sbb %bh,%bh
15: ff .byte 0xff
[ 5.508031][ T1] EAX: 00000007 EBX: c1f9fa5b ECX: 00000000 EDX: 00000000
[ 5.508856][ T1] ESI: c25e18e0 EDI: c0109500 EBP: c031bed4 ESP: c031bec4
[ 5.513135][ T1] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068 EFLAGS: 00010286
[ 5.514023][ T1] CR0: 80050033 CR2: 00000000 CR3: 02763000 CR4: 000406d0
[ 5.514855][ T1] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
[ 5.515688][ T1] DR6: fffe0ff0 DR7: 00000400
[ 5.516235][ T1] Call Trace:
[ 5.516632][ T1] ? show_regs (arch/x86/kernel/dumpstack.c:479)
[ 5.517125][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031 (discriminator 1))
[ 5.517722][ T1] ? __warn (kernel/panic.c:677)
[ 5.518185][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031 (discriminator 1))
[ 5.518797][ T1] ? report_bug (lib/bug.c:201 lib/bug.c:219)
[ 5.519323][ T1] ? exc_overflow (arch/x86/kernel/traps.c:250)
[ 5.519844][ T1] ? handle_bug (arch/x86/kernel/traps.c:216)
[ 5.520351][ T1] ? exc_invalid_op (arch/x86/kernel/traps.c:258 (discriminator 1))
[ 5.521153][ T1] ? handle_exception (arch/x86/entry/entry_32.S:1049)
[ 5.521153][ T1] ? exc_overflow (arch/x86/kernel/traps.c:250)
[ 5.521642][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031 (discriminator 1))
[ 5.522253][ T1] ? exc_overflow (arch/x86/kernel/traps.c:250)
[ 5.522776][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031 (discriminator 1))
[ 5.523386][ T1] register_tracer (kernel/trace/trace.c:2065 kernel/trace/trace.c:2188)
[ 5.523932][ T1] ? init_graph_tracefs (kernel/trace/trace_functions_graph.c:1448)
[ 5.525126][ T1] init_graph_trace (kernel/trace/trace_functions_graph.c:1462)
[ 5.525126][ T1] do_one_initcall (init/main.c:1236)
[ 5.525132][ T1] do_initcalls (init/main.c:1297 init/main.c:1314)
[ 5.525660][ T1] kernel_init_freeable (init/main.c:1553)
[ 5.526271][ T1] ? rest_init (init/main.c:1433)
[ 5.526789][ T1] kernel_init (init/main.c:1443)
[ 5.527291][ T1] ret_from_fork (arch/x86/kernel/process.c:153)
[ 5.527828][ T1] ? rest_init (init/main.c:1433)
[ 5.529154][ T1] ret_from_fork_asm (arch/x86/entry/entry_32.S:741)
[ 5.529154][ T1] entry_INT80_32 (arch/x86/entry/entry_32.S:947)
[ 5.529154][ T1] irq event stamp: 2126727
[ 5.529642][ T1] hardirqs last enabled at (2126735): console_unlock (arch/x86/include/asm/irqflags.h:26 arch/x86/include/asm/irqflags.h:67 arch/x86/include/asm/irqflags.h:127 kernel/printk/printk.c:341 kernel/printk/printk.c:2706 kernel/printk/printk.c:3038)
[ 5.530695][ T1] hardirqs last disabled at (2126742): console_unlock (kernel/printk/printk.c:339 kernel/printk/printk.c:2706 kernel/printk/printk.c:3038)
[ 5.533126][ T1] softirqs last enabled at (2126476): __do_softirq (arch/x86/include/asm/preempt.h:27 kernel/softirq.c:400 kernel/softirq.c:582)
[ 5.533126][ T1] softirqs last disabled at (2126471): do_softirq_own_stack (arch/x86/kernel/irq_32.c:57 arch/x86/kernel/irq_32.c:147)
[ 5.533126][ T1] ---[ end trace 0000000000000000 ]---
[ 5.534553][ T1] prandom: seed boundary self test passed
[ 5.537152][ T1] prandom: 100 self tests passed
[ 5.537152][ T1] pinctrl core: initialized pinctrl subsystem



The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20231218/202312181100.508f8f48-oliver.sang@xxxxxxxxx



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