syzbot


INFO: rcu detected stall in call_timer_fn

Status: upstream: reported on 2024/08/02 13:31
Reported-by: syzbot+db3b8560a5b2158bcb07@syzkaller.appspotmail.com
First crash: 56d, last: 56d
Similar bugs (10)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream BUG: soft lockup in call_timer_fn net mm 19 60d 102d 0/28 upstream: reported on 2024/06/17 22:49
linux-4.19 INFO: rcu detected stall in call_timer_fn (2) 16 1423d 1698d 0/1 auto-closed as invalid on 2021/03/04 12:30
linux-4.14 INFO: rcu detected stall in call_timer_fn (2) 1 1486d 1486d 0/1 auto-closed as invalid on 2020/12/31 22:00
upstream INFO: rcu detected stall in call_timer_fn (2) kernel C done error 47 983d 1839d 0/28 closed as invalid on 2022/02/08 10:46
upstream INFO: rcu detected stall in call_timer_fn bpf net 2 2177d 2189d 0/28 closed as dup on 2019/01/02 16:26
linux-4.19 INFO: rcu detected stall in call_timer_fn 1 1837d 1837d 0/1 auto-closed as invalid on 2020/01/15 18:43
linux-4.14 INFO: rcu detected stall in call_timer_fn 5 1611d 1730d 0/1 auto-closed as invalid on 2020/08/29 09:07
upstream INFO: rcu detected stall in call_timer_fn (4) mm 1 241d 241d 0/28 auto-obsoleted due to no activity on 2024/03/31 10:44
upstream INFO: rcu detected stall in call_timer_fn (3) kernel 3 722d 738d 0/28 auto-obsoleted due to no activity on 2023/01/12 18:45
linux-4.19 BUG: soft lockup in call_timer_fn C error 31 573d 1176d 0/1 upstream: reported C repro on 2021/07/10 02:48

Sample crash report:
rcu: INFO: rcu_preempt detected expedited stalls on CPUs/tasks: { 1-.... } 3401 jiffies s: 17901 root: 0x2/.
rcu: blocking rcu_node structures (internal RCU debug):
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 5256 Comm: syz.0.561 Not tainted 6.1.102-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/27/2024
RIP: 0010:__sanitizer_cov_trace_pc+0x0/0x60 kernel/kcov.c:203
Code: e8 25 00 00 00 0f 0b 0f 1f 00 53 48 89 fb e8 17 00 00 00 48 8b 3d 18 24 0f 0d 48 89 de 5b e9 07 b1 56 00 cc cc cc cc cc cc cc <48> 8b 04 24 65 48 8b 0d a4 d3 77 7e 65 8b 15 a5 d3 77 7e f7 c2 00
RSP: 0000:ffffc900001e0598 EFLAGS: 00000002
RAX: 0000000000010102 RBX: 0000000000000001 RCX: ffff88801f9b9dc0
RDX: ffff88801f9b9dc0 RSI: 0000000000000001 RDI: 0000000000000000
RBP: ffffc900001e06e8 R08: ffffffff8179c897 R09: fffffbfff1d337ae
R10: 0000000000000000 R11: dffffc0000000001 R12: ffff8880b992a4c0
R13: ffffffff81b41e80 R14: ffff8880b992a4c0 R15: dffffc0000000000
FS:  00007f022d1b46c0(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f022d1b40f8 CR3: 00000000283bd000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 __hrtimer_run_queues+0x5a0/0xe50 kernel/time/hrtimer.c:1750
 hrtimer_interrupt+0x392/0x980 kernel/time/hrtimer.c:1812
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0x156/0x580 arch/x86/kernel/apic/apic.c:1112
 sysvec_apic_timer_interrupt+0x3e/0xb0 arch/x86/kernel/apic/apic.c:1106
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:653
RIP: 0010:strlen+0x2d/0x60 lib/string.c:495
Code: 56 41 54 53 49 89 fe 48 c7 c0 ff ff ff ff 49 bf 00 00 00 00 00 fc ff df 48 89 fb 49 89 c4 48 89 d8 48 c1 e8 03 42 0f b6 04 38 <84> c0 75 12 48 ff c3 49 8d 44 24 01 43 80 7c 26 01 00 75 dd eb 13
RSP: 0000:ffffc900001e08f8 EFLAGS: 00000a07
RAX: 0000000000000000 RBX: ffffffff8c2ac70e RCX: 00000000001e0903
RDX: ffffffff81797bd2 RSI: ffffc900001e0bc0 RDI: ffffffff8c2ac700
RBP: ffffc900001e0a30 R08: dffffc0000000000 R09: fffffbfff1d337ae
R10: 0000000000000000 R11: dffffc0000000001 R12: 000000000000000d
R13: ffffffff81797bd2 R14: ffffffff8c2ac700 R15: dffffc0000000000
 trace_event_get_offsets_lock include/trace/events/lock.h:50 [inline]
 perf_trace_lock+0x10c/0x440 include/trace/events/lock.h:50
 trace_lock_release include/trace/events/lock.h:69 [inline]
 lock_release+0x995/0xa20 kernel/locking/lockdep.c:5673
 call_timer_fn+0x260/0x6b0 kernel/time/timer.c:1507
 expire_timers kernel/time/timer.c:1549 [inline]
 __run_timers+0x67c/0x890 kernel/time/timer.c:1820
 run_timer_softirq+0x63/0xf0 kernel/time/timer.c:1833
 handle_softirqs+0x2ee/0xa40 kernel/softirq.c:571
 __do_softirq kernel/softirq.c:605 [inline]
 invoke_softirq kernel/softirq.c:445 [inline]
 __irq_exit_rcu+0x157/0x240 kernel/softirq.c:654
 irq_exit_rcu+0x5/0x20 kernel/softirq.c:666
 sysvec_apic_timer_interrupt+0x91/0xb0 arch/x86/kernel/apic/apic.c:1106
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:653
RIP: 0010:preempt_schedule_irq+0xf2/0x1c0 kernel/sched/core.c:6873
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 f9 e1 b0 f6 e8 a4 de e3 f6 fb bf 01 00 00 00 <e8> 79 b4 ff ff 43 80 7c 3d 00 00 74 08 4c 89 f7 e8 39 a4 34 f7 48
RSP: 0000:ffffc900033af7a0 EFLAGS: 00000286
RAX: 293d241cd6b5bf00 RBX: 1ffff92000675efc RCX: ffffffff816ad9fa
RDX: dffffc0000000000 RSI: ffffffff8b0c0240 RDI: 0000000000000001
RBP: ffffc900033af850 R08: dffffc0000000000 R09: fffffbfff20e724b
R10: 0000000000000000 R11: dffffc0000000001 R12: 1ffff92000675ef4
R13: 1ffff92000675ef8 R14: ffffc900033af7c0 R15: dffffc0000000000
 irqentry_exit+0x53/0x80 kernel/entry/common.c:439
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:653
RIP: 0010:lock_is_held_type+0x137/0x180
Code: 75 40 48 c7 04 24 00 00 00 00 9c 8f 04 24 f7 04 24 00 02 00 00 75 46 41 f7 c4 00 02 00 00 74 01 fb 65 48 8b 04 25 28 00 00 00 <48> 3b 44 24 08 75 3c 89 e8 48 83 c4 10 5b 41 5c 41 5d 41 5e 41 5f
RSP: 0000:ffffc900033af918 EFLAGS: 00000206
RAX: 293d241cd6b5bf00 RBX: 0000000000000001 RCX: ffff88801f9b9dc0
RDX: 0000000000000000 RSI: ffffffff8b0c13c0 RDI: ffffffff8b5d5ba0
RBP: 0000000000000000 R08: dffffc0000000000 R09: fffff94000397d91
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000000000246
R13: ffff88801f9b9dc0 R14: 00000000ffffffff R15: ffffffff8d32acc0
 lock_is_held include/linux/lockdep.h:283 [inline]
 __might_resched+0xf0/0x780 kernel/sched/core.c:9907
 mmu_notifier_invalidate_range_start include/linux/mmu_notifier.h:454 [inline]
 wp_page_copy+0x625/0x18c0 mm/memory.c:3167
 handle_pte_fault mm/memory.c:5031 [inline]
 __handle_mm_fault mm/memory.c:5155 [inline]
 handle_mm_fault+0x2525/0x5340 mm/memory.c:5276
 do_user_addr_fault arch/x86/mm/fault.c:1340 [inline]
 handle_page_fault arch/x86/mm/fault.c:1431 [inline]
 exc_page_fault+0x26f/0x620 arch/x86/mm/fault.c:1487
 asm_exc_page_fault+0x22/0x30 arch/x86/include/asm/idtentry.h:570
RIP: 0033:0x7f022c247c11
Code: 00 45 31 c0 31 c9 ba 80 00 00 00 48 89 de bf ca 00 00 00 31 c0 e8 9f f7 12 00 8b 03 85 c0 74 e1 c7 45 08 00 00 00 00 48 89 ef <e8> fa f8 ff ff 8b 45 0c 85 c0 75 39 b9 40 42 0f 00 ba 81 00 00 00
RSP: 002b:00007f022d1b4100 EFLAGS: 00010202
RAX: 0000000000000001 RBX: 00007f022c505f88 RCX: 00007f022c3773b9
RDX: 0000000000000000 RSI: 0000000000000080 RDI: 00007f022c505f80
RBP: 00007f022c505f80 R08: 00007f022d1b46c0 R09: 00007f022d1b46c0
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f022c505f8c
R13: 000000000000000b R14: 00007ffe08696e60 R15: 00007ffe08696f48
 </TASK>

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/08/02 13:30 linux-6.1.y c1cec4dad96b 1e9c4cf3 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-6-1-kasan-perf INFO: rcu detected stall in call_timer_fn
* Struck through repros no longer work on HEAD.