syzbot


INFO: rcu detected stall in snd_timer_user_release (2)

Status: fixed on 2024/04/22 10:23
Subsystems: sound
[Documentation on labels]
Reported-by: syzbot+3e603db0222a2db35117@syzkaller.appspotmail.com
Fix commit: 587d67fd929a ALSA: timer: Fix missing irq-disable at closing
First crash: 191d, last: 174d
Discussions (1)
Title Replies (including bot) Last reply
[syzbot] [sound?] INFO: rcu detected stall in snd_timer_user_release (2) 1 (2) 2024/04/18 07:37
Similar bugs (2)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in snd_timer_user_release (3) sound C inconclusive 3 157d 166d 25/28 fixed on 2024/06/11 03:41
upstream INFO: rcu detected stall in snd_timer_user_release sound 1 352d 352d 0/28 auto-obsoleted due to no activity on 2024/01/24 03:14

Sample crash report:
rcu: INFO: rcu_preempt detected expedited stalls on CPUs/tasks: { 0-...D } 2638 jiffies s: 357 root: 0x1/.
rcu: blocking rcu_node structures (internal RCU debug):
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 5091 Comm: syz-executor303 Not tainted 6.9.0-rc4-syzkaller-00274-g3b68086599f8 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
RIP: 0010:arch_atomic_read arch/x86/include/asm/atomic.h:23 [inline]
RIP: 0010:raw_atomic_read include/linux/atomic/atomic-arch-fallback.h:457 [inline]
RIP: 0010:rcu_dynticks_curr_cpu_in_eqs include/linux/context_tracking.h:122 [inline]
RIP: 0010:rcu_is_watching+0x5a/0xb0 kernel/rcu/tree.c:700
Code: f0 48 c1 e8 03 42 80 3c 38 00 74 08 4c 89 f7 e8 bc 11 7e 00 48 c7 c3 c8 7c 03 00 49 03 1e 48 89 d8 48 c1 e8 03 42 0f b6 04 38 <84> c0 75 22 8b 03 65 ff 0d b1 81 87 7e 74 10 83 e0 04 c1 e8 02 5b
RSP: 0018:ffffc90000007d08 EFLAGS: 00000806
RAX: 0000000000000000 RBX: ffff8880b9437cc8 RCX: ffffffff8183b0c6
RDX: ffff888022d6bc00 RSI: ffffffff8c1f8540 RDI: ffffffff8c1f8500
RBP: 0000000000000001 R08: ffffffff8183b0ec R09: 1ffffffff1f51c6d
R10: dffffc0000000000 R11: fffffbfff1f51c6e R12: dffffc0000000000
R13: ffff8880b942c900 R14: ffffffff8dd4e9e0 R15: dffffc0000000000
FS:  0000000000000000(0000) GS:ffff8880b9400000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f5b937d9130 CR3: 000000000e134000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 trace_hrtimer_start include/trace/events/timer.h:222 [inline]
 debug_activate kernel/time/hrtimer.c:479 [inline]
 enqueue_hrtimer+0xa5/0x3c0 kernel/time/hrtimer.c:1090
 __run_hrtimer kernel/time/hrtimer.c:1709 [inline]
 __hrtimer_run_queues+0x6d5/0xd50 kernel/time/hrtimer.c:1756
 hrtimer_interrupt+0x396/0x990 kernel/time/hrtimer.c:1818
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1032 [inline]
 __sysvec_apic_timer_interrupt+0x110/0x3f0 arch/x86/kernel/apic/apic.c:1049
 instr_sysvec_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1043 [inline]
 sysvec_apic_timer_interrupt+0xa1/0xc0 arch/x86/kernel/apic/apic.c:1043
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:702
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0xd8/0x140 kernel/locking/spinlock.c:194
Code: 9c 8f 44 24 20 42 80 3c 23 00 74 08 4c 89 f7 e8 0e eb 6f f6 f6 44 24 21 02 75 52 41 f7 c7 00 02 00 00 74 01 fb bf 01 00 00 00 <e8> 93 2e db f5 65 8b 05 04 5b 79 74 85 c0 74 43 48 c7 04 24 0e 36
RSP: 0018:ffffc900034c7940 EFLAGS: 00000206
RAX: 2189abd430449d00 RBX: 1ffff92000698f2c RCX: ffffffff8172edda
RDX: dffffc0000000000 RSI: ffffffff8bcab8a0 RDI: 0000000000000001
RBP: ffffc900034c79d0 R08: ffffffff92f32587 R09: 1ffffffff25e64b0
R10: dffffc0000000000 R11: fffffbfff25e64b1 R12: dffffc0000000000
R13: 1ffff92000698f28 R14: ffffc900034c7960 R15: 0000000000000246
 spin_unlock_irqrestore include/linux/spinlock.h:406 [inline]
 class_spinlock_irqsave_destructor include/linux/spinlock.h:574 [inline]
 snd_timer_stop1+0x7e6/0x950 sound/core/timer.c:634
 snd_timer_stop sound/core/timer.c:679 [inline]
 snd_timer_close_locked+0x223/0x8d0 sound/core/timer.c:423
 snd_timer_close sound/core/timer.c:464 [inline]
 snd_timer_user_release+0x138/0x2e0 sound/core/timer.c:1468
 __fput+0x429/0x8a0 fs/file_table.c:422
 task_work_run+0x24f/0x310 kernel/task_work.c:180
 exit_task_work include/linux/task_work.h:38 [inline]
 do_exit+0xa1b/0x27e0 kernel/exit.c:878
 do_group_exit+0x207/0x2c0 kernel/exit.c:1027
 __do_sys_exit_group kernel/exit.c:1038 [inline]
 __se_sys_exit_group kernel/exit.c:1036 [inline]
 __x64_sys_exit_group+0x3f/0x40 kernel/exit.c:1036
 do_syscall_x64 arch/x86/entry/common.c:52 [inline]
 do_syscall_64+0xf5/0x240 arch/x86/entry/common.c:83
 entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f5b9375ddb9
Code: Unable to access opcode bytes at 0x7f5b9375dd8f.
RSP: 002b:00007fff939bee08 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f5b9375ddb9
RDX: 000000000000003c RSI: 00000000000000e7 RDI: 0000000000000000
RBP: 00007f5b937d82b0 R08: ffffffffffffffb8 R09: 00000000000000a0
R10: 00000000000000a0 R11: 0000000000000246 R12: 00007f5b937d82b0
R13: 0000000000000000 R14: 00007f5b937d8d20 R15: 00007f5b9372ef50
 </TASK>
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 2.146 msecs

Crashes (7):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/04/21 22:50 upstream 3b68086599f8 af24b050 .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
2024/04/20 14:55 upstream 13a2e429f644 af24b050 .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
2024/04/20 06:11 upstream dbe0a7be2838 af24b050 .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
2024/04/08 20:58 upstream 480e035fc4c7 53df08b6 .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
2024/04/06 22:50 upstream 480e035fc4c7 ca620dd8 .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
2024/04/06 04:20 upstream 480e035fc4c7 18ea8213 .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
2024/04/05 00:14 upstream 480e035fc4c7 0ee3535e .config strace log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in snd_timer_user_release
* Struck through repros no longer work on HEAD.