[peterz-queue:x86/kvm] [objtool] a150ba8e3e: WARNING:at_kernel/trace/trace.c:#run_tracer_selftest

From: kernel test robot
Date: Thu Dec 07 2023 - 02:15:01 EST



Hello,

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

commit: a150ba8e3e8f68327fb4d6a2ea801313e653ac63 ("objtool: Convert ANNOTATE_INTRA_FUNCTION_CALLS to ANNOTATE")
https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git x86/kvm

in testcase: boot

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

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


+-----------------------------------------------------------------------------+------------+------------+
| | c0b9ba21ec | a150ba8e3e |
+-----------------------------------------------------------------------------+------------+------------+
| WARNING:at_kernel/trace/trace.c:#run_tracer_selftest | 0 | 37 |
| RIP:run_tracer_selftest | 0 | 37 |
| WARNING:at_kernel/trace/trace.c:#init_trace_selftests | 0 | 37 |
| RIP:init_trace_selftests | 0 | 37 |
| WARNING:at_kernel/trace/trace_events.c:#event_trace_self_test_with_function | 0 | 37 |
| RIP:event_trace_self_test_with_function | 0 | 37 |
+-----------------------------------------------------------------------------+------------+------------+


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/202312071255.667f7028-oliver.sang@xxxxxxxxx


[ 4.298418][ T1] ------------[ cut here ]------------
[ 4.300350][ T1] WARNING: CPU: 0 PID: 1 at kernel/trace/trace.c:2031 run_tracer_selftest (kernel/trace/trace.c:2031)
[ 4.303679][ T1] Modules linked in:
[ 4.305714][ T1] CPU: 0 PID: 1 Comm: swapper Tainted: G T 6.7.0-rc3-00007-ga150ba8e3e8f #1 1842589a931247defa08c7633f42656bec93c944
[ 4.309165][ T1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 4.312930][ T1] RIP: 0010:run_tracer_selftest (kernel/trace/trace.c:2031)
[ 4.315589][ T1] Code: 00 00 00 ba ff ff ff ff e8 82 ea fd ff 48 c7 c7 e0 73 91 97 e8 06 21 5d 03 e9 9b fe ff ff 48 c7 c7 60 73 91 97 e8 f5 20 5d 03 <0f> 0b bb ff ff ff ff e9 83 fe ff ff 4c 89 ff 48 c7 c6 20 fe 1e 99
All code
========
0: 00 00 add %al,(%rax)
2: 00 ba ff ff ff ff add %bh,-0x1(%rdx)
8: e8 82 ea fd ff call 0xfffffffffffdea8f
d: 48 c7 c7 e0 73 91 97 mov $0xffffffff979173e0,%rdi
14: e8 06 21 5d 03 call 0x35d211f
19: e9 9b fe ff ff jmp 0xfffffffffffffeb9
1e: 48 c7 c7 60 73 91 97 mov $0xffffffff97917360,%rdi
25: e8 f5 20 5d 03 call 0x35d211f
2a:* 0f 0b ud2 <-- trapping instruction
2c: bb ff ff ff ff mov $0xffffffff,%ebx
31: e9 83 fe ff ff jmp 0xfffffffffffffeb9
36: 4c 89 ff mov %r15,%rdi
39: 48 c7 c6 20 fe 1e 99 mov $0xffffffff991efe20,%rsi

Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: bb ff ff ff ff mov $0xffffffff,%ebx
7: e9 83 fe ff ff jmp 0xfffffffffffffe8f
c: 4c 89 ff mov %r15,%rdi
f: 48 c7 c6 20 fe 1e 99 mov $0xffffffff991efe20,%rsi
[ 4.321810][ T1] RSP: 0000:ffff888105277ac8 EFLAGS: 00010282
[ 4.327570][ T1] RAX: 0000000000000007 RBX: 0000000000000000 RCX: ffffffff98d93260
[ 4.331405][ T1] RDX: 0000000000000001 RSI: 0000000000000008 RDI: ffff8881052778d8
[ 4.332658][ T1] RBP: ffffffff99db94c8 R08: ffff8881052778df R09: 1ffff11020a4ef1b
[ 4.335563][ T1] R10: dffffc0000000000 R11: ffffed1020a4ef1c R12: ffffffff99d88780
[ 4.339230][ T1] R13: dffffc0000000000 R14: ffffffff99d88720 R15: 1ffffffff33b10f0
[ 4.340714][ T1] FS: 0000000000000000(0000) GS:ffffffff98cee000(0000) knlGS:0000000000000000
[ 4.344752][ T1] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 4.347562][ T1] CR2: ffff88843ffff000 CR3: 00000003ce2ca000 CR4: 00000000000406f0
[ 4.351396][ T1] Call Trace:
[ 4.352146][ T1] <TASK>
[ 4.353634][ T1] ? __warn (kernel/panic.c:236 kernel/panic.c:677)
[ 4.355563][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031)
[ 4.357961][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031)
[ 4.360316][ T1] ? report_bug (lib/bug.c:?)
[ 4.362560][ T1] ? handle_bug (arch/x86/kernel/traps.c:237)
[ 4.364289][ T1] ? exc_invalid_op (arch/x86/kernel/traps.c:258)
[ 4.366537][ T1] ? asm_exc_invalid_op (arch/x86/include/asm/idtentry.h:568)
[ 4.368449][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031)
[ 4.370964][ T1] ? run_tracer_selftest (kernel/trace/trace.c:2031)
[ 4.372372][ T1] ? init_trace_selftests (kernel/trace/trace.c:2093)
[ 4.374883][ T1] ? do_one_initcall (init/main.c:1236)
[ 4.376326][ T1] ? latency_fsnotify_init (kernel/trace/trace.c:2076)
[ 4.378988][ T1] ? do_initcall_level (init/main.c:1297)
[ 4.380357][ T1] ? do_initcalls (init/main.c:1311)
[ 4.382585][ T1] ? kernel_init_freeable (init/main.c:1555)
[ 4.384377][ T1] ? rest_init (init/main.c:1433)
[ 4.386655][ T1] ? kernel_init (init/main.c:1443)
[ 4.388330][ T1] ? rest_init (init/main.c:1433)
[ 4.390632][ T1] ? ret_from_fork (arch/x86/kernel/process.c:153)
[ 4.392335][ T1] ? rest_init (init/main.c:1433)
[ 4.394615][ T1] ? ret_from_fork_asm (arch/x86/entry/entry_64.S:250)
[ 4.396345][ T1] </TASK>
[ 4.397946][ T1] irq event stamp: 4047
[ 4.399559][ T1] hardirqs last enabled at (4059): console_unlock (arch/x86/include/asm/irqflags.h:42 arch/x86/include/asm/irqflags.h:77 arch/x86/include/asm/irqflags.h:135 kernel/printk/printk.c:341 kernel/printk/printk.c:2706 kernel/printk/printk.c:3038)
[ 4.403563][ T1] hardirqs last disabled at (4070): console_unlock (kernel/printk/printk.c:339)
[ 4.407564][ T1] softirqs last enabled at (3878): irq_exit_rcu (kernel/softirq.c:646)
[ 4.411562][ T1] softirqs last disabled at (3861): irq_exit_rcu (kernel/softirq.c:646)
[ 4.415563][ T1] ---[ end trace 0000000000000000 ]---


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



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