Hello,
kernel test robot noticed "WARNING:at_include/trace/events/printk.h:#printk_sprint" on:
commit: e8639b7ef0f871753b4262ec0eacd3da29eebcee ("modpost: Allow extended modversions without basic MODVERSIONS")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
[test failed on linux-next/master 5ffa57f6eecefababb8cbe327222ef171943b183]
in testcase: trinity
version: trinity-i386-abe9de86-1_20230429
with following parameters:
runtime: 300s
group: group-03
nr_groups: 5
config: x86_64-randconfig-071-20250125
compiler: clang-19
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G
(please refer to attached dmesg/kmsg for entire log/backtrace)
we found various random issues on this commit, but clean on parent.
fc7d5e3210ae083a e8639b7ef0f871753b4262ec0ea
---------------- ---------------------------
fail:runs %reproduction fail:runs
| | |
:200 29% 58:200 dmesg.RIP:lock_acquire
:200 29% 58:200 dmesg.RIP:lock_acquired
:200 29% 58:200 dmesg.RIP:lock_release
:200 29% 58:200 dmesg.RIP:printk_sprint
:200 29% 58:200 dmesg.WARNING:at_include/trace/events/lock.h:#lock_acquire
:200 29% 58:200 dmesg.WARNING:at_include/trace/events/lock.h:#lock_acquired
:200 29% 58:200 dmesg.WARNING:at_include/trace/events/lock.h:#lock_release
:200 29% 58:200 dmesg.WARNING:at_include/trace/events/printk.h:#printk_sprint
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/202501272252.4f499056-lkp@xxxxxxxxx
[ 279.276312][ T4440] ------------[ cut here ]------------
[ 279.276316][ T4440] ------------[ cut here ]------------
[ 279.276319][ T4440] RCU not watching for tracepoint
[ 279.276350][ T4440] WARNING: CPU: 1 PID: 4440 at include/trace/events/printk.h:33 printk_sprint (ld-temp.o:?)
[ 279.276360][ T4440] Modules linked in: intel_rapl_msr(F) intel_rapl_common(F) crc32_pclmul(F) intel_agp(F) parport_pc(F) intel_gtt(F) parport(F) agpgart(F) autofs4(F)
[ 279.276374][ T4440] Unloaded tainted modules: intel_cstate(F):1
[ 279.276378][ T4440] CPU: 1 UID: 65534 PID: 4440 Comm: trinity-c3 Tainted: GF W T 6.13.0-rc6-00039-ge8639b7ef0f8 #1
[ 279.276383][ T4440] Tainted: [F]=FORCED_MODULE, [W]=WARN, [T]=RANDSTRUCT
[ 279.276385][ T4440] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
[ 279.276386][ T4440] RIP: 0010:printk_sprint (ld-temp.o:?)
[ 279.276390][ T4440] Code: ed 75 a0 e9 4c ff ff ff 0f 0b eb af 41 ff cf 44 89 f8 e9 25 ff ff ff 48 c7 c7 da ce 8b 82 c6 05 2a 30 24 02 01 e8 9b c0 f2 ff <0f> 0b eb c4 e8 92 9e d4 ff a8 04 74 b2 eb b9 65 8b 05 21 9b f3 7e
All code
========
0: ed in (%dx),%eax
1: 75 a0 jne 0xffffffffffffffa3
3: e9 4c ff ff ff jmp 0xffffffffffffff54
8: 0f 0b ud2
a: eb af jmp 0xffffffffffffffbb
c: 41 ff cf dec %r15d
f: 44 89 f8 mov %r15d,%eax
12: e9 25 ff ff ff jmp 0xffffffffffffff3c
17: 48 c7 c7 da ce 8b 82 mov $0xffffffff828bceda,%rdi
1e: c6 05 2a 30 24 02 01 movb $0x1,0x224302a(%rip) # 0x224304f
25: e8 9b c0 f2 ff call 0xfffffffffff2c0c5
2a:* 0f 0b ud2 <-- trapping instruction
2c: eb c4 jmp 0xfffffffffffffff2
2e: e8 92 9e d4 ff call 0xffffffffffd49ec5
33: a8 04 test $0x4,%al
35: 74 b2 je 0xffffffffffffffe9
37: eb b9 jmp 0xfffffffffffffff2
39: 65 8b 05 21 9b f3 7e mov %gs:0x7ef39b21(%rip),%eax # 0x7ef39b61
Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: eb c4 jmp 0xffffffffffffffc8
4: e8 92 9e d4 ff call 0xffffffffffd49e9b
9: a8 04 test $0x4,%al
b: 74 b2 je 0xffffffffffffffbf
d: eb b9 jmp 0xffffffffffffffc8
f: 65 8b 05 21 9b f3 7e mov %gs:0x7ef39b21(%rip),%eax # 0x7ef39b37
[ 279.276393][ T4440] RSP: 0018:ffffc90000c0f9f8 EFLAGS: 00010086
[ 279.276395][ T4440] RAX: 0000000080000006 RBX: ffffffff84b5ce40 RCX: ffff88842fc00000
[ 279.276397][ T4440] RDX: ffff88842fc00000 RSI: 0000000000000027 RDI: ffff88842fddcb88
[ 279.276399][ T4440] RBP: 0000000000000024 R08: aaaaaaaaaaaaaaaa R09: 3fffffffffffffff
[ 279.276401][ T4440] R10: 00000000ffffffff R11: 00000000ffff86b8 R12: 0000000000000082
[ 279.276402][ T4440] R13: ffff88842fddcb81 R14: 0000000000000024 R15: 0000000000000028
[ 279.276404][ T4440] FS: 0000000000000000(0000) GS:ffff88842fc00000(0063) knlGS:00000000f7f5a280
[ 279.276407][ T4440] CS: 0010 DS: 002b ES: 002b CR0: 0000000080050033
[ 279.276408][ T4440] CR2: 00000000f72cf000 CR3: 00000001b4b14000 CR4: 00000000000406b0
[ 279.276413][ T4440] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 279.276414][ T4440] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 279.276416][ T4440] Call Trace:
[ 279.276418][ T4440] <TASK>
[ 279.276420][ T4440] ? __warn (ld-temp.o:?)
[ 279.276424][ T4440] ? printk_sprint (ld-temp.o:?)
[ 279.276427][ T4440] ? report_bug (ld-temp.o:?)
[ 279.276430][ T4440] ? printk_sprint (ld-temp.o:?)
[ 279.276434][ T4440] ? handle_bug (ld-temp.o:?)
[ 279.276437][ T4440] ? exc_invalid_op (ld-temp.o:?)
[ 279.276440][ T4440] ? asm_exc_invalid_op (arch/x86/include/asm/idtentry.h:621)
[ 279.276444][ T4440] ? printk_sprint (ld-temp.o:?)
[ 279.276447][ T4440] ? printk_sprint (ld-temp.o:?)
[ 279.276450][ T4440] vprintk_store (ld-temp.o:?)
[ 279.276456][ T4440] vprintk_emit (ld-temp.o:?)
[ 279.276460][ T4440] _printk (ld-temp.o:?)
[ 279.276463][ T4440] handle_overflow (ld-temp.o:?)
[ 279.276468][ T4440] ct_kernel_exit (ld-temp.o:?)
[ 279.276473][ T4440] __ct_user_enter (ld-temp.o:?)
[ 279.276476][ T4440] syscall_exit_to_user_mode (ld-temp.o:?)
[ 279.276479][ T4440] __do_fast_syscall_32 (ld-temp.o:?)
[ 279.276482][ T4440] ? __task_pid_nr_ns (ld-temp.o:?)
[ 279.276487][ T4440] ? __task_pid_nr_ns (ld-temp.o:?)
[ 279.276489][ T4440] ? __task_pid_nr_ns (ld-temp.o:?)
[ 279.276493][ T4440] ? __task_pid_nr_ns (ld-temp.o:?)
[ 279.276496][ T4440] ? __task_pid_nr_ns (ld-temp.o:?)
[ 279.276499][ T4440] ? __ct_user_enter (ld-temp.o:?)
[ 279.276502][ T4440] ? vtime_user_enter (ld-temp.o:?)
[ 279.276505][ T4440] ? ct_kernel_exit (ld-temp.o:?)
[ 279.276508][ T4440] ? __ct_user_enter (ld-temp.o:?)
[ 279.276512][ T4440] ? syscall_exit_to_user_mode (ld-temp.o:?)
[ 279.276515][ T4440] ? __do_fast_syscall_32 (ld-temp.o:?)
[ 279.276518][ T4440] ? __do_fast_syscall_32 (ld-temp.o:?)
[ 279.276520][ T4440] ? syscall_exit_to_user_mode (ld-temp.o:?)
[ 279.276523][ T4440] ? __do_fast_syscall_32 (ld-temp.o:?)
[ 279.276526][ T4440] ? syscall_exit_to_user_mode (ld-temp.o:?)
[ 279.276529][ T4440] ? __do_fast_syscall_32 (ld-temp.o:?)
[ 279.276531][ T4440] ? vtime_user_enter (ld-temp.o:?)
[ 279.276533][ T4440] ? ct_kernel_exit (ld-temp.o:?)
[ 279.276536][ T4440] do_fast_syscall_32 (ld-temp.o:?)
[ 279.276538][ T4440] entry_SYSENTER_compat_after_hwframe (arch/x86/entry/entry_64_compat.S:127)
[ 279.276542][ T4440] RIP: 0023:0xf7f5f539
[ 279.276544][ T4440] Code: 03 74 b4 01 10 07 03 74 b0 01 10 08 03 74 d8 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 51 52 55 89 e5 0f 34 cd 80 <5d> 5a 59 c3 cc 90 90 90 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00
All code
========
0: 03 74 b4 01 add 0x1(%rsp,%rsi,4),%esi
4: 10 07 adc %al,(%rdi)
6: 03 74 b0 01 add 0x1(%rax,%rsi,4),%esi
a: 10 08 adc %cl,(%rax)
c: 03 74 d8 01 add 0x1(%rax,%rbx,8),%esi
...
20: 00 51 52 add %dl,0x52(%rcx)
23: 55 push %rbp
24:* 89 e5 mov %esp,%ebp <-- trapping instruction
26: 0f 34 sysenter
28: cd 80 int $0x80
2a: 5d pop %rbp
2b: 5a pop %rdx
2c: 59 pop %rcx
2d: c3 ret
2e: cc int3
2f: 90 nop
30: 90 nop
31: 90 nop
32: 66 2e 0f 1f 84 00 00 cs nopw 0x0(%rax,%rax,1)
39: 00 00 00
3c: 0f .byte 0xf
3d: 1f (bad)
3e: 44 rex.R
...
Code starting with the faulting instruction
===========================================
0: 5d pop %rbp
1: 5a pop %rdx
2: 59 pop %rcx
3: c3 ret
4: cc int3
5: 90 nop
6: 90 nop
7: 90 nop
8: 66 2e 0f 1f 84 00 00 cs nopw 0x0(%rax,%rax,1)
f: 00 00 00
12: 0f .byte 0xf
13: 1f (bad)
14: 44 rex.R
The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20250127/202501272252.4f499056-lkp@xxxxxxxxx
--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki
