syzbot


BUG: soft lockup in mrp_periodic_timer (2)

Status: auto-obsoleted due to no activity on 2022/10/01 18:22
Reported-by: syzbot+41f80ff40309c454d901@syzkaller.appspotmail.com
First crash: 748d, last: 691d
Similar bugs (3)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 BUG: soft lockup in mrp_periodic_timer 1 878d 878d 0/1 auto-closed as invalid on 2022/03/28 02:00
upstream INFO: rcu detected stall in mrp_periodic_timer (4) kernel 1 453d 453d 0/26 auto-obsoleted due to no activity on 2023/04/26 22:06
upstream INFO: rcu detected stall in mrp_periodic_timer (3) perf 1 840d 840d 0/26 closed as invalid on 2022/02/08 10:10

Sample crash report:
watchdog: BUG: soft lockup - CPU#1 stuck for 22s! [syz-executor.3:9634]
Modules linked in:
irq event stamp: 4508937
hardirqs last  enabled at (4508936): [<ffffffff81003ce4>] trace_hardirqs_on_thunk+0x1a/0x1c
hardirqs last disabled at (4508937): [<ffffffff81003d00>] trace_hardirqs_off_thunk+0x1a/0x1c
softirqs last  enabled at (4461136): [<ffffffff88400678>] __do_softirq+0x678/0x980 kernel/softirq.c:318
softirqs last disabled at (4461139): [<ffffffff813927d5>] invoke_softirq kernel/softirq.c:372 [inline]
softirqs last disabled at (4461139): [<ffffffff813927d5>] irq_exit+0x215/0x260 kernel/softirq.c:412
CPU: 1 PID: 9634 Comm: syz-executor.3 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3c0 kernel/locking/lockdep.c:3911
Code: 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 b7 01 00 00 48 83 3d 09 2e a6 08 00 0f 84 2a 01 00 00 48 8b 7c 24 08 57 9d <0f> 1f 44 00 00 48 83 c4 18 5b 5d 41 5c 41 5d 41 5e 41 5f c3 65 8b
RSP: 0018:ffff8880ba107c80 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff13e3051 RBX: ffff88801c280680 RCX: 0000000000004f6e
RDX: dffffc0000000000 RSI: 0000000007aaf49f RDI: 0000000000000286
RBP: ffff88809b1288b8 R08: ffffffff8cd3f0b0 R09: 0000000000000001
R10: ffff88801c280f30 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000001
FS:  00007f446a493700(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f446a472718 CR3: 000000004b041000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline]
 _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:144
 spin_lock include/linux/spinlock.h:329 [inline]
 mrp_periodic_timer+0x34/0x170 net/802/mrp.c:625
 call_timer_fn+0x177/0x700 kernel/time/timer.c:1338
 expire_timers+0x243/0x4e0 kernel/time/timer.c:1375
 __run_timers kernel/time/timer.c:1696 [inline]
 run_timer_softirq+0x21c/0x670 kernel/time/timer.c:1709
 __do_softirq+0x265/0x980 kernel/softirq.c:292
 invoke_softirq kernel/softirq.c:372 [inline]
 irq_exit+0x215/0x260 kernel/softirq.c:412
 exiting_irq arch/x86/include/asm/apic.h:536 [inline]
 smp_apic_timer_interrupt+0x136/0x550 arch/x86/kernel/apic/apic.c:1098
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
 </IRQ>
RIP: 0010:debug_lockdep_rcu_enabled kernel/rcu/update.c:254 [inline]
RIP: 0010:debug_lockdep_rcu_enabled+0x87/0xe0 kernel/rcu/update.c:251
Code: 7c 04 84 d2 75 64 8b 0d 07 53 f5 08 85 c9 74 45 65 48 8b 1c 25 c0 df 01 00 48 8d bb 84 08 00 00 48 b8 00 00 00 00 00 fc ff df <48> 89 fa 48 c1 ea 03 0f b6 14 02 48 89 f8 83 e0 07 83 c0 03 38 d0
RSP: 0018:ffff88821fa87af8 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff13
RAX: dffffc0000000000 RBX: ffff88801c280680 RCX: 0000000000000001
RDX: 0000000000000004 RSI: 0000000000000002 RDI: ffff88801c280f04
RBP: ffff88821fa87bd0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000005 R11: 0000000000000000 R12: ffff88801c280680
R13: 000000000002b0c0 R14: ffff88821fa80000 R15: ffff8880ba12b0c0
 schedule_debug kernel/sched/core.c:3337 [inline]
 __schedule+0x13d/0x2040 kernel/sched/core.c:3439
 preempt_schedule_common+0x45/0xc0 kernel/sched/core.c:3641
ieee802154 phy0 wpan0: encryption failed: -22
 ___preempt_schedule+0x16/0x18
ieee802154 phy1 wpan1: encryption failed: -22
 bpf_test_run_one net/bpf/test_run.c:24 [inline]
 bpf_test_run+0x367/0x440 net/bpf/test_run.c:43
 bpf_prog_test_run_skb+0x579/0x9c0 net/bpf/test_run.c:147
 bpf_prog_test_run kernel/bpf/syscall.c:1796 [inline]
 __do_sys_bpf kernel/bpf/syscall.c:2441 [inline]
 __se_sys_bpf+0xc9e/0x3a20 kernel/bpf/syscall.c:2384
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f446bb3f109
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 b8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f446a493168 EFLAGS: 00000246 ORIG_RAX: 0000000000000141
RAX: ffffffffffffffda RBX: 00007f446bc52030 RCX: 00007f446bb3f109
RDX: 0000000000000028 RSI: 0000000020000080 RDI: 000000000000000a
RBP: 00007f446bb9908d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffc52c007cf R14: 00007f446a493300 R15: 0000000000022000
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 9719 Comm: syz-executor.2 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__sanitizer_cov_trace_pc+0x27/0x50 kernel/kcov.c:104
Code: 90 90 90 48 8b 34 24 65 48 8b 04 25 c0 df 01 00 65 8b 15 cc 59 9f 7e 81 e2 00 01 1f 00 75 2b 8b 90 60 13 00 00 83 fa 02 75 20 <48> 8b 88 68 13 00 00 8b 80 64 13 00 00 48 8b 11 48 83 c2 01 48 39
RSP: 0018:ffff8880ba007f58 EFLAGS: 00000046
RAX: ffff888041f7c280 RBX: 0000000000000000 RCX: ffffffff8167afb6
RDX: 0000000000000002 RSI: ffffffff8167afbf RDI: 0000000000000005
RBP: ffffffff88200958 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000005 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
FS:  00007fd83e256700(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f14a99f0000 CR3: 0000000018253000 CR4: 00000000003406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 trace_hardirqs_off_caller kernel/trace/trace_preemptirq.c:68 [inline]
 trace_hardirqs_off_caller+0x5f/0x210 kernel/trace/trace_preemptirq.c:60
 trace_hardirqs_off_thunk+0x1a/0x1c
 interrupt_entry+0xb8/0xb9 arch/x86/entry/entry_64.S:642
 </IRQ>
RIP: 0010:unwind_next_frame+0xf4f/0x1400 arch/x86/kernel/unwind_orc.c:555
Code: 44 0f b6 04 30 49 8d 47 03 49 89 c1 49 c1 e9 03 41 0f b6 34 31 49 89 f9 41 83 e1 07 45 38 c8 41 0f 9e c1 45 84 c0 41 0f 95 c0 <45> 84 c1 0f 85 b3 02 00 00 83 e0 07 40 38 c6 41 0f 9e c0 40 84 f6
RSP: 0018:ffff88821dc0f2b8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: ffffffff8b9099eb RBX: 1ffff11043b81e60 RCX: ffffffff8b9099ed
RDX: ffff88821dc0f4e0 RSI: 0000000000000000 RDI: ffffffff8b9099ea
RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000001
R10: ffff88821dc0f4ff R11: 0000000000074071 R12: ffff88821dc0f490
R13: ffff88821dc0f4d5 R14: ffff88821dc0f4a0 R15: ffffffff8b9099e8
 __unwind_start+0x5b8/0x960 arch/x86/kernel/unwind_orc.c:667
 unwind_start arch/x86/include/asm/unwind.h:60 [inline]
 __save_stack_trace+0x72/0x190 arch/x86/kernel/stacktrace.c:43
 save_stack mm/kasan/kasan.c:448 [inline]
 set_track mm/kasan/kasan.c:460 [inline]
 kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:553
 __do_kmalloc_node mm/slab.c:3689 [inline]
 __kmalloc_node_track_caller+0x4c/0x70 mm/slab.c:3703
 __kmalloc_reserve net/core/skbuff.c:137 [inline]
 pskb_expand_head+0x159/0xf20 net/core/skbuff.c:1473
 skb_ensure_writable+0x2c6/0x470 net/core/skbuff.c:5196
 __bpf_try_make_writable net/core/filter.c:1633 [inline]
 bpf_try_make_writable net/core/filter.c:1639 [inline]
 bpf_try_make_head_writable net/core/filter.c:1647 [inline]
 ____bpf_clone_redirect net/core/filter.c:2088 [inline]
 bpf_clone_redirect+0x131/0x430 net/core/filter.c:2066
 ___bpf_prog_run+0x1bfa/0x4e80 kernel/bpf/core.c:1184
----------------
Code disassembly (best guess):
   0:	08 00                	or     %al,(%rax)
   2:	00 00                	add    %al,(%rax)
   4:	00 00                	add    %al,(%rax)
   6:	00 48 c1             	add    %cl,-0x3f(%rax)
   9:	e8 03 80 3c 10       	callq  0x103c8011
   e:	00 0f                	add    %cl,(%rdi)
  10:	85 b7 01 00 00 48    	test   %esi,0x48000001(%rdi)
  16:	83 3d 09 2e a6 08 00 	cmpl   $0x0,0x8a62e09(%rip)        # 0x8a62e26
  1d:	0f 84 2a 01 00 00    	je     0x14d
  23:	48 8b 7c 24 08       	mov    0x8(%rsp),%rdi
  28:	57                   	push   %rdi
  29:	9d                   	popfq
* 2a:	0f 1f 44 00 00       	nopl   0x0(%rax,%rax,1) <-- trapping instruction
  2f:	48 83 c4 18          	add    $0x18,%rsp
  33:	5b                   	pop    %rbx
  34:	5d                   	pop    %rbp
  35:	41 5c                	pop    %r12
  37:	41 5d                	pop    %r13
  39:	41 5e                	pop    %r14
  3b:	41 5f                	pop    %r15
  3d:	c3                   	retq
  3e:	65                   	gs
  3f:	8b                   	.byte 0x8b

Crashes (3):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2022/06/03 18:21 linux-4.19.y 3f8a27f9e27b eee80d3c .config console log report info ci2-linux-4-19 BUG: soft lockup in mrp_periodic_timer
2022/04/13 18:47 linux-4.19.y 3f8a27f9e27b b17b2923 .config console log report info ci2-linux-4-19 BUG: soft lockup in mrp_periodic_timer
2022/04/07 02:58 linux-4.19.y 3f8a27f9e27b 97582466 .config console log report info ci2-linux-4-19 BUG: soft lockup in mrp_periodic_timer
* Struck through repros no longer work on HEAD.