syzbot


INFO: rcu detected stall in mrp_periodic_timer (3)

Status: closed as invalid on 2022/02/08 10:10
Reported-by: syzbot+@syzkaller.appspotmail.com
First crash: 336d, last: 336d
similar bugs (6):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.14 INFO: rcu detected stall in mrp_periodic_timer 2 1009d 1046d 0/1 auto-closed as invalid on 2020/07/01 22:48
upstream INFO: rcu detected stall in mrp_periodic_timer (2) 1 834d 834d 0/24 auto-closed as invalid on 2020/11/23 04:34
upstream INFO: rcu detected stall in mrp_periodic_timer 1 1037d 1037d 0/24 auto-closed as invalid on 2020/05/04 02:54
linux-4.19 INFO: rcu detected stall in mrp_periodic_timer 3 982d 1032d 0/1 auto-closed as invalid on 2020/07/28 00:22
linux-4.19 BUG: soft lockup in mrp_periodic_timer (2) 3 187d 244d 0/1 auto-obsoleted due to no activity on 2022/10/01 18:22
linux-4.19 BUG: soft lockup in mrp_periodic_timer 1 374d 374d 0/1 auto-closed as invalid on 2022/03/28 02:00

Sample crash report:
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 	0-....: (1 GPs behind) idle=161/1/0x4000000000000000 softirq=124932/124933 fqs=5140 
	(t=10500 jiffies g=226997 q=871)
NMI backtrace for cpu 0
CPU: 0 PID: 11090 Comm: syz-executor.2 Not tainted 5.16.0-rc8-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
 nmi_cpu_backtrace.cold+0x47/0x144 lib/nmi_backtrace.c:111
 nmi_trigger_cpumask_backtrace+0x1b3/0x230 lib/nmi_backtrace.c:62
 trigger_single_cpu_backtrace include/linux/nmi.h:164 [inline]
 rcu_dump_cpu_stacks+0x25e/0x3f0 kernel/rcu/tree_stall.h:343
 print_cpu_stall kernel/rcu/tree_stall.h:627 [inline]
 check_cpu_stall kernel/rcu/tree_stall.h:711 [inline]
 rcu_pending kernel/rcu/tree.c:3878 [inline]
 rcu_sched_clock_irq.cold+0x5c/0x759 kernel/rcu/tree.c:2597
 update_process_times+0x16d/0x200 kernel/time/timer.c:1785
 tick_sched_handle+0x9b/0x180 kernel/time/tick-sched.c:226
 tick_sched_timer+0x1b0/0x2d0 kernel/time/tick-sched.c:1428
 __run_hrtimer kernel/time/hrtimer.c:1685 [inline]
 __hrtimer_run_queues+0x1c0/0xe50 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x31c/0x790 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1086 [inline]
 __sysvec_apic_timer_interrupt+0x146/0x530 arch/x86/kernel/apic/apic.c:1103
 sysvec_apic_timer_interrupt+0x40/0xc0 arch/x86/kernel/apic/apic.c:1097
 asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:check_kcov_mode kernel/kcov.c:166 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0xd/0x60 kernel/kcov.c:200
Code: 00 00 e9 46 90 63 02 66 0f 1f 44 00 00 48 8b be b0 01 00 00 e8 b4 ff ff ff 31 c0 c3 90 65 8b 05 c9 dd 8a 7e 89 c1 48 8b 34 24 <81> e1 00 01 00 00 65 48 8b 14 25 40 70 02 00 a9 00 01 ff 00 74 0e
RSP: 0018:ffffc90000007838 EFLAGS: 00000246
RAX: 0000000000000102 RBX: ffff888071506fa0 RCX: 0000000000000102
RDX: 0000000000000000 RSI: ffffffff8196b9d9 RDI: 0000000000000003
RBP: ffffc90000007ab0 R08: 0000000000000000 R09: 0000000000000020
R10: ffffffff8196b9cb R11: 0000000000000000 R12: dffffc0000000000
R13: ffff8880b9c34908 R14: 0000000000000001 R15: 0000000000000000
 perf_tp_event_match kernel/events/core.c:9692 [inline]
 perf_tp_event+0x209/0xbc0 kernel/events/core.c:9738
 perf_trace_run_bpf_submit+0x11c/0x210 kernel/events/core.c:9713
 perf_trace_lock+0x2ef/0x4d0 include/trace/events/lock.h:39
 trace_lock_release include/trace/events/lock.h:58 [inline]
 lock_release+0x4a8/0x720 kernel/locking/lockdep.c:5648
 __raw_spin_unlock include/linux/spinlock_api_smp.h:141 [inline]
 _raw_spin_unlock+0x12/0x40 kernel/locking/spinlock.c:186
 spin_unlock include/linux/spinlock.h:389 [inline]
 mrp_periodic_timer+0xfc/0x190 net/802/mrp.c:625
 call_timer_fn+0x1a5/0x6b0 kernel/time/timer.c:1421
 expire_timers kernel/time/timer.c:1466 [inline]
 __run_timers.part.0+0x67c/0xa30 kernel/time/timer.c:1734
 __run_timers kernel/time/timer.c:1715 [inline]
 run_timer_softirq+0xb3/0x1d0 kernel/time/timer.c:1747
 __do_softirq+0x29b/0x9c2 kernel/softirq.c:558
 invoke_softirq kernel/softirq.c:432 [inline]
 __irq_exit_rcu+0x123/0x180 kernel/softirq.c:637
 irq_exit_rcu+0x5/0x20 kernel/softirq.c:649
 sysvec_apic_timer_interrupt+0x93/0xc0 arch/x86/kernel/apic/apic.c:1097
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x12/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:perf_trace_init+0xd5/0x2f0 kernel/trace/trace_event_perf.c:225
Code: 84 74 01 00 00 e8 fb 11 f8 ff 48 8d 7d 40 48 89 f8 48 c1 e8 03 42 0f b6 04 20 84 c0 74 08 3c 03 0f 8e b0 01 00 00 48 63 5d 40 <4c> 89 ee 48 89 df e8 40 15 f8 ff 4c 39 eb 75 a3 e8 c6 11 f8 ff 48
RSP: 0018:ffffc9001051fc40 EFLAGS: 00000246
RAX: 0000000000000000 RBX: 0000000000000185 RCX: 0000000000000000
RDX: ffff88814fcbba00 RSI: ffffffff817f8035 RDI: ffffffff8bc9bb60
RBP: ffffffff8bc9bb20 R08: 0000000000000186 R09: ffffc9001051fb7f
R10: ffffffff817f8060 R11: 0000000000000001 R12: dffffc0000000000
R13: 00000000000000de R14: ffff888025e3e9c0 R15: ffffffff8bc50f40
 perf_tp_event_init+0xa2/0x120 kernel/events/core.c:9794
 perf_try_init_event+0x12a/0x560 kernel/events/core.c:11252
 perf_init_event kernel/events/core.c:11316 [inline]
 perf_event_alloc.part.0+0xf21/0x3b70 kernel/events/core.c:11607
 perf_event_alloc kernel/events/core.c:12030 [inline]
 __do_sys_perf_event_open+0x4ae/0x3130 kernel/events/core.c:12128
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f2bac772e99
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 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 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f2bab0e8168 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007f2bac885f60 RCX: 00007f2bac772e99
RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 00000000200003c0
RBP: 00007f2bac7ccff1 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000
R13: 00007f2bacdb9b1f R14: 00007f2bab0e8300 R15: 0000000000022000
 </TASK>
----------------
Code disassembly (best guess):
   0:	00 00                	add    %al,(%rax)
   2:	e9 46 90 63 02       	jmpq   0x263904d
   7:	66 0f 1f 44 00 00    	nopw   0x0(%rax,%rax,1)
   d:	48 8b be b0 01 00 00 	mov    0x1b0(%rsi),%rdi
  14:	e8 b4 ff ff ff       	callq  0xffffffcd
  19:	31 c0                	xor    %eax,%eax
  1b:	c3                   	retq
  1c:	90                   	nop
  1d:	65 8b 05 c9 dd 8a 7e 	mov    %gs:0x7e8addc9(%rip),%eax        # 0x7e8added
  24:	89 c1                	mov    %eax,%ecx
  26:	48 8b 34 24          	mov    (%rsp),%rsi
* 2a:	81 e1 00 01 00 00    	and    $0x100,%ecx <-- trapping instruction
  30:	65 48 8b 14 25 40 70 	mov    %gs:0x27040,%rdx
  37:	02 00
  39:	a9 00 01 ff 00       	test   $0xff0100,%eax
  3e:	74 0e                	je     0x4e

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce 2022/01/05 06:49 upstream c9e6606c7fe9 0a2584dd .config log report info INFO: rcu detected stall in mrp_periodic_timer
* Struck through repros no longer work on HEAD.