syzbot


BUG: soft lockup in bpf_prog_free_deferred

Status: auto-obsoleted due to no activity on 2024/11/12 14:27
Bug presence: origin:downstream
[Documentation on labels]
Reported-by: syzbot+cb1a5c14e7e68140e68d@syzkaller.appspotmail.com
First crash: 223d, last: 110d
Bug presence (3)
Date Name Commit Repro Result
2024/04/28 android13-5.15-lts (ToT) 5d96939590c0 C [report] BUG: soft lockup in smp_call_function
2024/04/28 lts (merge base) 458ce51d0356 C Didn't crash
2024/04/28 upstream (ToT) 2c8159388952 C Didn't crash
Similar bugs (1)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in bpf_prog_free_deferred bpf 1 471d 471d 0/28 auto-obsoleted due to no activity on 2023/11/07 19:00
Last patch testing requests (2)
Created Duration User Patch Repo Result
2024/09/15 10:42 13m retest repro android13-5.15-lts OK log
2024/07/07 10:25 10m retest repro android13-5.15-lts report log

Sample crash report:
watchdog: BUG: soft lockup - CPU#1 stuck for 246s! [kworker/1:0:26]
Modules linked in:
CPU: 1 PID: 26 Comm: kworker/1:0 Tainted: G        W         5.15.152-syzkaller-00143-g70e1a731d986 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/27/2024
Workqueue: events bpf_prog_free_deferred
RIP: 0010:__sanitizer_cov_trace_pc+0x1/0x60 kernel/kcov.c:190
Code: 00 00 0f 0b 0f 1f 44 00 00 55 48 89 e5 53 48 89 fb e8 13 00 00 00 48 8b 3d a4 1d b9 05 48 89 de e8 44 77 41 00 5b 5d c3 cc 55 <48> 89 e5 48 8b 45 08 65 48 8b 0d 00 2b 92 7e 65 8b 15 01 2b 92 7e
RSP: 0018:ffffc900001b7870 EFLAGS: 00000202
RAX: 0000000000000000 RBX: 1ffff1103ee07af1 RCX: ffff88810034e2c0
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: ffffc900001b7988 R08: ffffffff81659daa R09: ffffc900001b77c0
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000000000000
R13: ffff8881f703d788 R14: ffff8881f7138e80 R15: dffffc0000000000
FS:  0000000000000000(0000) GS:ffff8881f7100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000000 CR3: 0000000127ecf000 CR4: 00000000003506a0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 </IRQ>
 <TASK>
 on_each_cpu_cond_mask+0x40/0x80 kernel/smp.c:1135
 on_each_cpu include/linux/smp.h:71 [inline]
 flush_tlb_kernel_range+0x49/0x210 arch/x86/mm/tlb.c:1026
 __purge_vmap_area_lazy+0x230/0x1690 mm/vmalloc.c:1694
 _vm_unmap_aliases+0x339/0x3b0 mm/vmalloc.c:2118
 vm_remove_mappings mm/vmalloc.c:2611 [inline]
 __vunmap+0x617/0x8f0 mm/vmalloc.c:2642
 __vfree mm/vmalloc.c:2700 [inline]
 vfree+0x7f/0xb0 mm/vmalloc.c:2731
 module_memfree+0x17/0x30 kernel/module.c:2150
 bpf_jit_free_exec+0x15/0x20 kernel/bpf/core.c:867
 bpf_jit_binary_free kernel/bpf/core.c:914 [inline]
 bpf_jit_free+0x98/0x240 kernel/bpf/core.c:927
 bpf_prog_free_deferred+0x61e/0x730 kernel/bpf/core.c:2296
 process_one_work+0x6bb/0xc10 kernel/workqueue.c:2325
 worker_thread+0xad5/0x12a0 kernel/workqueue.c:2472
 kthread+0x421/0x510 kernel/kthread.c:337
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:298
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 933 Comm: syz-executor Tainted: G        W         5.15.152-syzkaller-00143-g70e1a731d986 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/27/2024
RIP: 0010:native_halt arch/x86/include/asm/irqflags.h:57 [inline]
RIP: 0010:halt arch/x86/include/asm/irqflags.h:98 [inline]
RIP: 0010:kvm_wait+0x117/0x180 arch/x86/kernel/kvm.c:912
Code: 48 c1 e8 03 42 0f b6 04 20 84 c0 44 8b 74 24 1c 75 53 41 0f b6 45 00 44 38 f0 0f 85 63 ff ff ff 66 90 0f 00 2d 5a 02 13 04 f4 <e9> 54 ff ff ff fa 4c 89 e8 48 c1 e8 03 42 0f b6 04 20 84 c0 44 8b
RSP: 0018:ffffc90000007ac0 EFLAGS: 00000046
RAX: 0000000000000003 RBX: 1ffff92000000f5c RCX: ffffffff8155068f
RDX: dffffc0000000000 RSI: 0000000000000003 RDI: ffff8881f7029a00
RBP: ffffc90000007b70 R08: dffffc0000000000 R09: ffffed103ee05341
R10: 0000000000000000 R11: dffffc0000000001 R12: dffffc0000000000
R13: ffff8881f7029a00 R14: 0000000000000003 R15: 1ffff92000000f60
FS:  000055555588c500(0000) GS:ffff8881f7000000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000055e7176fce78 CR3: 000000011e55b000 CR4: 00000000003506b0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 pv_wait arch/x86/include/asm/paravirt.h:597 [inline]
 pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:470 [inline]
 __pv_queued_spin_lock_slowpath+0x6bc/0xc40 kernel/locking/qspinlock.c:508
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:585 [inline]
 queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:51 [inline]
 queued_spin_lock include/asm-generic/qspinlock.h:85 [inline]
 do_raw_spin_lock_flags include/linux/spinlock.h:199 [inline]
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:119 [inline]
 _raw_spin_lock_irqsave+0x1a0/0x210 kernel/locking/spinlock.c:162
 hrtimer_interrupt+0x111/0xaa0 kernel/time/hrtimer.c:1792
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1085 [inline]
 __sysvec_apic_timer_interrupt+0xfd/0x3c0 arch/x86/kernel/apic/apic.c:1102
 sysvec_apic_timer_interrupt+0x44/0xc0 arch/x86/kernel/apic/apic.c:1096
 asm_sysvec_apic_timer_interrupt+0x1b/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:ffs arch/x86/include/asm/bitops.h:297 [inline]
RIP: 0010:__do_softirq+0x10e/0x5bf kernel/softirq.c:553
Code: a0 21 48 85 e8 83 3d bf ff 65 66 c7 05 09 6f e3 7a 00 00 48 c7 c7 a0 21 48 85 e8 6d 3d bf ff 65 89 1d ee 70 e2 7a fb 89 5d b8 <b8> ff ff ff ff 0f bc 45 b8 41 89 c4 41 ff c4 0f 85 c5 00 00 00 48
RSP: 0018:ffffc90000007f70 EFLAGS: 00000286
RAX: 0000000000000000 RBX: 0000000000000200 RCX: ffff88810bd22780
RDX: 1ffffffff0e98a00 RSI: ffffffff854821a0 RDI: ffffffff85a312a0
RBP: ffffc90000007fe8 R08: ffffffff874c5000 R09: ffffffff874c5008
R10: ffffffff874c5018 R11: ffffffff874c5010 R12: ffff88810bd22780
R13: dffffc0000000000 R14: 0000000000400140 R15: dffffc0000000000
 do_softirq+0xf6/0x150 kernel/softirq.c:452
 </IRQ>
 <TASK>
 __local_bh_enable_ip+0x75/0x80 kernel/softirq.c:379
 __raw_spin_unlock_bh include/linux/spinlock_api_smp.h:176 [inline]
 _raw_spin_unlock_bh+0x51/0x60 kernel/locking/spinlock.c:210
 sock_hash_delete_elem+0x2a2/0x2f0 net/core/sock_map.c:945
 bpf_prog_586ab708469db861+0x41/0x4dc
 bpf_dispatcher_nop_func include/linux/bpf.h:785 [inline]
 __bpf_prog_run include/linux/filter.h:625 [inline]
 bpf_prog_run include/linux/filter.h:632 [inline]
 __bpf_trace_run kernel/trace/bpf_trace.c:1880 [inline]
 bpf_trace_run2+0xec/0x210 kernel/trace/bpf_trace.c:1917
 __bpf_trace_hrtimer_start+0x23/0x30 include/trace/events/timer.h:199
 trace_hrtimer_start include/trace/events/timer.h:199 [inline]
 debug_activate kernel/time/hrtimer.c:476 [inline]
 enqueue_hrtimer+0x1e7/0x240 kernel/time/hrtimer.c:1084
 __hrtimer_start_range_ns kernel/time/hrtimer.c:1258 [inline]
 hrtimer_start_range_ns+0x9f4/0xb80 kernel/time/hrtimer.c:1298
 hrtimer_start_expires include/linux/hrtimer.h:435 [inline]
 hrtimer_sleeper_start_expires kernel/time/hrtimer.c:1965 [inline]
 do_nanosleep+0x12d/0x6a0 kernel/time/hrtimer.c:2041
 hrtimer_nanosleep+0x1c5/0x3f0 kernel/time/hrtimer.c:2097
 common_nsleep+0x91/0xb0 kernel/time/posix-timers.c:1267
 __do_sys_clock_nanosleep kernel/time/posix-timers.c:1308 [inline]
 __se_sys_clock_nanosleep+0x323/0x3b0 kernel/time/posix-timers.c:1285
 __x64_sys_clock_nanosleep+0x9b/0xb0 kernel/time/posix-timers.c:1285
 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:0x7fcb7deab9a3
Code: 1f 84 00 00 00 00 00 83 ff 03 74 7b 83 ff 02 b8 fa ff ff ff 49 89 ca 0f 44 f8 80 3d 9e db 12 00 00 74 14 b8 e6 00 00 00 0f 05 <f7> d8 c3 66 2e 0f 1f 84 00 00 00 00 00 48 83 ec 28 48 89 54 24 10
RSP: 002b:00007ffd0a149658 EFLAGS: 00000202 ORIG_RAX: 00000000000000e6
RAX: ffffffffffffffda RBX: 000000000000015c RCX: 00007fcb7deab9a3
RDX: 00007ffd0a149670 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007ffd0a1496cc R08: 00007ffd0a15a080 R09: 00007ffd0a15a0b0
R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000032
R13: 000000000000ee51 R14: 000000000000ee40 R15: 00007ffd0a149720
 </TASK>

Crashes (7):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/08/04 14:27 android13-5.15-lts 70e1a731d986 1786a2a8 .config console log report info [disk image] [vmlinux] [kernel image] ci2-android-5-15-perf BUG: soft lockup in bpf_prog_free_deferred
2024/08/02 12:15 android13-5.15-lts 70e1a731d986 1e9c4cf3 .config console log report info [disk image] [vmlinux] [kernel image] ci2-android-5-15-perf BUG: soft lockup in bpf_prog_free_deferred
2024/06/10 10:15 android13-5.15-lts 79bd336c7a94 82c05ab8 .config console log report info [disk image] [vmlinux] [kernel image] ci2-android-5-15-perf BUG: soft lockup in bpf_prog_free_deferred
2024/05/22 02:00 android13-5.15-lts 5d96939590c0 1014eca7 .config console log report info [disk image] [vmlinux] [kernel image] ci2-android-5-15 BUG: soft lockup in bpf_prog_free_deferred
2024/05/18 22:09 android13-5.15-lts 5d96939590c0 c0f1611a .config console log report info [disk image] [vmlinux] [kernel image] ci2-android-5-15 BUG: soft lockup in bpf_prog_free_deferred
2024/04/26 18:52 android13-5.15-lts 5d96939590c0 059e9963 .config console log report syz C [disk image] [vmlinux] [kernel image] ci2-android-5-15-perf BUG: soft lockup in bpf_prog_free_deferred
2024/04/13 18:49 android13-5.15-lts 993bed180178 c8349e48 .config console log report info [disk image] [vmlinux] [kernel image] ci2-android-5-15 BUG: soft lockup in bpf_prog_free_deferred
* Struck through repros no longer work on HEAD.