[v5.15] INFO: rcu detected stall in sys_perf_event_open

[syzbot]

Hello,

syzbot found the following issue on:

HEAD commit: 4fdad925aa1a Linux 5.15.107
git tree: linux-5.15.y
console output: https://syzkaller.appspot.com/x/log.txt?x=176e49c7c80000
kernel config: https://syzkaller.appspot.com/x/.config?x=d9bc1b227ee6c412
dashboard link: https://syzkaller.appspot.com/bug?extid=9c4bd6b4999eca7912ed
compiler: Debian clang version 15.0.7, GNU ld (GNU Binutils for Debian) 2.35.2

Unfortunately, I don't have any reproducer for this issue yet.

Downloadable assets:
disk image: https://storage.googleapis.com/syzbot-assets/30e8a5e334a8/disk-4fdad925.raw.xz
vmlinux: https://storage.googleapis.com/syzbot-assets/48dbfcad2132/vmlinux-4fdad925.xz
kernel image: https://storage.googleapis.com/syzbot-assets/bef168dd6dfc/bzImage-4fdad925.xz

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+9c4bd6...@syzkaller.appspotmail.com

rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 0-....: (1 GPs behind) idle=731/1/0x4000000000000000 softirq=160110/160115 fqs=5239
(detected by 1, t=10502 jiffies, g=148045, q=10764)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 8772 Comm: syz-executor.2 Not tainted 5.15.107-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/30/2023
RIP: 0010:hlock_id kernel/locking/lockdep.c:399 [inline]
RIP: 0010:__lock_acquire+0xe1d/0x1ff0 kernel/locking/lockdep.c:4999
Code: 44 24 20 33 18 f7 c3 00 60 00 00 74 0f c7 44 24 2c 01 00 00 00 49 c7 c7 ff ff ff ff 4c 89 74 24 60 48 8b 44 24 38 0f b6 04 38 <84> c0 0f 85 03 0b 00 00 48 8b 44 24 20 8b 28 48 83 bc 24 c0 00 00
RSP: 0018:ffffc90000007120 EFLAGS: 00000046
RAX: 0000000000000000 RBX: 0000000000080000 RCX: ffff88801e006120
RDX: 0000000000000000 RSI: 0000000000000008 RDI: dffffc0000000000
RBP: ffff88801e006110 R08: dffffc0000000000 R09: fffffbfff1f76e15
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000000000004
R13: ffff88801e006118 R14: 1ffff11003c00c22 R15: 3080082190aefba4
FS: 00007f35f3994700(0000) GS:ffff8880b9a00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fe07ec2bf84 CR3: 000000001b93b000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<IRQ>
lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5622
rcu_lock_acquire+0x2a/0x30 include/linux/rcupdate.h:269
rcu_read_lock include/linux/rcupdate.h:696 [inline]
__perf_event_output kernel/events/core.c:7644 [inline]
perf_event_output_forward+0xda/0x310 kernel/events/core.c:7666
__perf_event_overflow+0x35d/0x530 kernel/events/core.c:9361
perf_tp_event+0x363/0xc40 kernel/events/core.c:9893
perf_trace_run_bpf_submit+0xf7/0x1d0 kernel/events/core.c:9867
perf_trace_preemptirq_template+0x31d/0x430 include/trace/events/preemptirq.h:14
trace_irq_enable_rcuidle+0x10e/0x190 include/trace/events/preemptirq.h:40
trace_hardirqs_on+0x57/0x80 kernel/trace/trace_preemptirq.c:44
asm_sysvec_irq_work+0x16/0x20 arch/x86/include/asm/idtentry.h:664
RIP: 0010:rcu_read_unlock_special+0x84/0x520 kernel/rcu/tree_plugin.h:671
Code: f1 f1 f1 00 f2 f2 f2 49 89 04 17 66 41 c7 44 17 09 f3 f3 41 c6 44 17 0b f3 65 44 8b 25 8d 18 97 7e 41 f7 c4 00 00 f0 00 74 45 <48> c7 44 24 20 0e 36 e0 45 4a c7 04 3a 00 00 00 00 66 42 c7 44 3a
RSP: 0018:ffffc900000079e0 EFLAGS: 00000206
RAX: 5d67502a2ea28100 RBX: 1ffff92000000f44 RCX: ffffffff8162a4d8
RDX: dffffc0000000000 RSI: ffffffff8a8af0e0 RDI: ffffffff8ad85240
RBP: ffffc90000007ab8 R08: dffffc0000000000 R09: fffffbfff1f76e33
R10: 0000000000000000 R11: dffffc0000000001 R12: ffffffff8c91f800
R13: ffff88801e005b18 R14: ffffc90000007a20 R15: 1ffff92000000f40
__rcu_read_unlock+0x92/0x100 kernel/rcu/tree_plugin.h:422
rcu_read_unlock include/linux/rcupdate.h:728 [inline]
hsr_announce+0x2a9/0x300 net/hsr/hsr_device.c:387
call_timer_fn+0x16d/0x560 kernel/time/timer.c:1421
expire_timers kernel/time/timer.c:1466 [inline]
__run_timers+0x67c/0x890 kernel/time/timer.c:1737
run_timer_softirq+0x63/0xf0 kernel/time/timer.c:1750
__do_softirq+0x3b3/0x93a kernel/softirq.c:558
invoke_softirq kernel/softirq.c:432 [inline]
__irq_exit_rcu+0x155/0x240 kernel/softirq.c:636
irq_exit_rcu+0x5/0x20 kernel/softirq.c:648
sysvec_apic_timer_interrupt+0x91/0xb0 arch/x86/kernel/apic/apic.c:1097
</IRQ>
<TASK>
asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:preempt_schedule_irq+0xf2/0x1c0 kernel/sched/core.c:6776
Code: 89 f5 49 c1 ed 03 eb 0d 48 f7 03 08 00 00 00 0f 84 97 00 00 00 bf 01 00 00 00 e8 29 a5 37 f7 e8 44 e0 66 f7 fb bf 01 00 00 00 <e8> c9 b3 ff ff 43 80 7c 3d 00 00 74 08 4c 89 f7 e8 09 c8 a9 f7 48
RSP: 0018:ffffc900032af9e0 EFLAGS: 00000282
RAX: 5d67502a2ea28100 RBX: 1ffff92000655f44 RCX: ffffffff8162a4d8
RDX: dffffc0000000000 RSI: ffffffff8a8af0e0 RDI: 0000000000000001
RBP: ffffc900032afaa0 R08: dffffc0000000000 R09: fffffbfff1f76e1c
R10: 0000000000000000 R11: dffffc0000000001 R12: 1ffff92000655f3c
R13: 1ffff92000655f40 R14: ffffc900032afa00 R15: dffffc0000000000
irqentry_exit+0x53/0x80 kernel/entry/common.c:426
asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:perf_trace_init+0x9b/0x2d0 kernel/trace/trace_event_perf.c:223
Code: 4d 41 00 48 8b 1d 85 cc 0e 0b 48 39 eb 0f 84 78 01 00 00 49 c7 c6 60 90 96 8c 48 8d 6b 40 48 89 e8 48 c1 e8 03 42 0f b6 04 28 <84> c0 0f 85 17 01 00 00 48 63 6d 00 4c 89 ff 48 89 ee e8 7e f8 f7
RSP: 0018:ffffc900032afb68 EFLAGS: 00000a06
RAX: 0000000000000000 RBX: ffffffff8d912e60 RCX: ffff88801e005700
RDX: 0000000000000000 RSI: 0000000000000a2e RDI: 0000000000000116
RBP: ffffffff8d912ea0 R08: ffffffff8187c412 R09: fffff52000655f2d
R10: 0000000000000000 R11: dffffc0000000001 R12: ffffffff8c9a2aa0
R13: dffffc0000000000 R14: ffffffff8c969060 R15: 0000000000000116
perf_tp_event_init+0x89/0x110 kernel/events/core.c:9948
perf_try_init_event+0x135/0x3e0 kernel/events/core.c:11411
perf_init_event kernel/events/core.c:11475 [inline]
perf_event_alloc+0x1150/0x2150 kernel/events/core.c:11766
__do_sys_perf_event_open kernel/events/core.c:12304 [inline]
__se_sys_perf_event_open+0xb0c/0x4560 kernel/events/core.c:12196
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f35f5443169
Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 f1 19 00 00 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 b8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f35f3994168 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007f35f5563050 RCX: 00007f35f5443169
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000200
RBP: 00007f35f549eca1 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffd4ee15e2f R14: 00007f35f3994300 R15: 0000000000022000
</TASK>


---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

[syzbot]

Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.