syzbot


BUG: soft lockup in sys_perf_event_open (2)

Status: moderation: reported on 2024/10/03 01:56
Subsystems: kvm
[Documentation on labels]
Reported-by: syzbot+d50d0015274d31ba1907@syzkaller.appspotmail.com
First crash: 6d16h, last: 6d16h
Similar bugs (6)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
android-5-15 BUG: soft lockup in sys_perf_event_open 1 152d 152d 0/2 auto-obsoleted due to no activity on 2024/08/04 07:07
upstream BUG: soft lockup in sys_perf_event_open perf 1 127d 123d 0/28 auto-obsoleted due to no activity on 2024/08/29 01:52
linux-5.15 INFO: rcu detected stall in sys_perf_event_open (2) origin:lts-only C error 2 3d14h 270d 0/3 upstream: reported C repro on 2024/01/09 18:19
linux-6.1 INFO: rcu detected stall in sys_perf_event_open (2) 1 12d 12d 0/3 upstream: reported on 2024/09/23 05:55
linux-6.1 INFO: rcu detected stall in sys_perf_event_open 1 116d 116d 0/3 auto-obsoleted due to no activity on 2024/09/19 01:45
linux-5.15 INFO: rcu detected stall in sys_perf_event_open 1 535d 535d 0/3 auto-obsoleted due to no activity on 2023/08/17 04:33

Sample crash report:
watchdog: BUG: soft lockup - CPU#0 stuck for 143s! [syz.4.469:6535]
Modules linked in:
irq event stamp: 634350
hardirqs last  enabled at (634349): [<ffffffff8140771b>] kvm_wait+0x23b/0x2c0
hardirqs last disabled at (634350): [<ffffffff8bc430de>] sysvec_apic_timer_interrupt+0xe/0xc0 arch/x86/kernel/apic/apic.c:1037
softirqs last  enabled at (634342): [<ffffffff81576384>] __do_softirq kernel/softirq.c:588 [inline]
softirqs last  enabled at (634342): [<ffffffff81576384>] invoke_softirq kernel/softirq.c:428 [inline]
softirqs last  enabled at (634342): [<ffffffff81576384>] __irq_exit_rcu+0xf4/0x1c0 kernel/softirq.c:637
softirqs last disabled at (634169): [<ffffffff81576384>] __do_softirq kernel/softirq.c:588 [inline]
softirqs last disabled at (634169): [<ffffffff81576384>] invoke_softirq kernel/softirq.c:428 [inline]
softirqs last disabled at (634169): [<ffffffff81576384>] __irq_exit_rcu+0xf4/0x1c0 kernel/softirq.c:637
CPU: 0 UID: 0 PID: 6535 Comm: syz.4.469 Not tainted 6.11.0-syzkaller-10550-g99a648c951ba #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
RIP: 0010:native_safe_halt arch/x86/include/asm/irqflags.h:48 [inline]
RIP: 0010:arch_safe_halt arch/x86/include/asm/irqflags.h:106 [inline]
RIP: 0010:kvm_wait+0x250/0x2c0 arch/x86/kernel/kvm.c:1060
Code: 3b 45 0f b6 f6 44 89 ff 44 89 f6 e8 aa 3e 54 00 e8 c5 fd 5b 00 45 38 f7 75 15 66 90 e8 19 3e 54 00 0f 00 2d 52 bc c7 0a fb f4 <e9> 50 fe ff ff e8 06 3e 54 00 fb e9 45 fe ff ff 89 d9 80 e1 07 38
RSP: 0018:ffffc9000daa7880 EFLAGS: 00000246
RAX: ffffffff81407727 RBX: ffff888148cbf7d8 RCX: 0000000000040000
RDX: ffffc9000a7f4000 RSI: 000000000003ffff RDI: 0000000000040000
RBP: ffffc9000daa7950 R08: ffffffff942687ff R09: 1ffffffff284d0ff
R10: dffffc0000000000 R11: fffffbfff284d100 R12: 1ffff92001b54f14
R13: dffffc0000000000 R14: 0000000000000003 R15: 0000000000000003
FS:  00007f1fc57b46c0(0000) GS:ffff8880b8600000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f1fc57b3f98 CR3: 00000000202f8000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
Call Trace:
 <IRQ>
 </IRQ>
 <TASK>
 pv_wait arch/x86/include/asm/paravirt.h:596 [inline]
 pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:466 [inline]
 __pv_queued_spin_lock_slowpath+0x8d0/0xdb0 kernel/locking/qspinlock.c:508
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:584 [inline]
 queued_spin_lock_slowpath+0x42/0x50 arch/x86/include/asm/qspinlock.h:51
 queued_spin_lock include/asm-generic/qspinlock.h:114 [inline]
 do_raw_spin_lock+0x272/0x370 kernel/locking/spinlock_debug.c:116
 spin_lock include/linux/spinlock.h:351 [inline]
 d_instantiate+0x64/0xa0 fs/dcache.c:1893
 alloc_path_pseudo fs/file_table.c:341 [inline]
 alloc_file_pseudo+0x19e/0x290 fs/file_table.c:353
 __anon_inode_getfile fs/anon_inodes.c:109 [inline]
 anon_inode_getfile+0xc8/0x180 fs/anon_inodes.c:147
 __do_sys_perf_event_open kernel/events/core.c:12946 [inline]
 __se_sys_perf_event_open+0x2553/0x3870 kernel/events/core.c:12658
 do_syscall_x64 arch/x86/entry/common.c:52 [inline]
 do_syscall_64+0xf3/0x230 arch/x86/entry/common.c:83
 entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f1fc497dff9
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 a8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f1fc57b4038 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007f1fc4b36058 RCX: 00007f1fc497dff9
RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 0000000020000480
RBP: 00007f1fc49f0296 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000
R13: 0000000000000001 R14: 00007f1fc4b36058 R15: 00007ffc27d272b8
 </TASK>
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 UID: 0 PID: 6522 Comm: syz.3.465 Not tainted 6.11.0-syzkaller-10550-g99a648c951ba #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
RIP: 0010:lockdep_recursion_finish kernel/locking/lockdep.c:467 [inline]
RIP: 0010:lockdep_hardirqs_on_prepare+0x454/0x780 kernel/locking/lockdep.c:4404
Code: ff df e9 41 ff ff ff 48 c7 c7 c0 db 0a 8c e8 b3 86 54 0a 48 ba 00 00 00 00 00 fc ff df b8 ff ff ff ff 65 0f c1 05 fc ac 92 7e <83> f8 01 4c 8b 7c 24 18 75 38 48 c7 44 24 20 0e 36 e0 45 4a c7 04
RSP: 0018:ffffc90000a18cc0 EFLAGS: 00000057
RAX: 0000000000000001 RBX: ffff88801f3ba8c0 RCX: ffffffff8170892a
RDX: dffffc0000000000 RSI: ffffffff8c0adbc0 RDI: ffffffff8c6100a0
RBP: ffffc90000a18d78 R08: ffffffff942687ff R09: 1ffffffff284d0ff
R10: dffffc0000000000 R11: fffffbfff284d100 R12: ffff88801f3ba950
R13: 0000000000000002 R14: ffff88801f3ba8d8 R15: 1ffff11003e7751b
FS:  0000555581f08500(0000) GS:ffff8880b8700000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f1fc57d4f98 CR3: 00000000273d0000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 trace_hardirqs_on+0x28/0x40 kernel/trace/trace_preemptirq.c:61
 irqentry_exit+0x63/0x90 kernel/entry/common.c:357
 asm_sysvec_irq_work+0x1a/0x20 arch/x86/include/asm/idtentry.h:738
RIP: 0010:variable_ffs arch/x86/include/asm/bitops.h:321 [inline]
RIP: 0010:handle_softirqs+0x1e3/0x980 kernel/softirq.c:542
Code: 7c 24 70 45 0f b7 e4 48 c7 c7 60 bf 09 8c e8 a4 0a 6d 0a 65 66 c7 05 62 7e ac 7e 00 00 e8 55 1b 45 00 fb 49 c7 c6 c0 a0 60 8e <b8> ff ff ff ff 41 0f bc c4 41 89 c7 41 ff c7 0f 84 eb 03 00 00 44
RSP: 0018:ffffc90000a18e40 EFLAGS: 00000286
RAX: 7477772dfdad4600 RBX: ffffc90000a18ee0 RCX: ffffffff8170892a
RDX: dffffc0000000000 RSI: ffffffff8c0aca40 RDI: ffffffff8c6100a0
RBP: ffffc90000a18f50 R08: ffffffff942687ff R09: 1ffffffff284d0ff
R10: dffffc0000000000 R11: fffffbfff284d100 R12: 0000000000000200
R13: 0000000000000000 R14: ffffffff8e60a0c0 R15: 1ffff11003e773c0
 __do_softirq kernel/softirq.c:588 [inline]
 invoke_softirq kernel/softirq.c:428 [inline]
 __irq_exit_rcu+0xf4/0x1c0 kernel/softirq.c:637
 irq_exit_rcu+0x9/0x30 kernel/softirq.c:649
 instr_sysvec_irq_work arch/x86/kernel/irq_work.c:17 [inline]
 sysvec_irq_work+0xa3/0xc0 arch/x86/kernel/irq_work.c:17
 </IRQ>
 <TASK>
 asm_sysvec_irq_work+0x1a/0x20 arch/x86/include/asm/idtentry.h:738
RIP: 0010:rcu_read_unlock_special+0x88/0x550 kernel/rcu/tree_plugin.h:693
Code: f1 f1 f1 00 f2 f2 f2 49 89 04 17 66 41 c7 44 17 09 f3 f3 41 c6 44 17 0b f3 65 44 8b 25 59 60 88 7e 41 f7 c4 00 00 f0 00 74 49 <48> c7 44 24 20 0e 36 e0 45 4a c7 04 3a 00 00 00 00 66 42 c7 44 3a
RSP: 0018:ffffc9001605fc20 EFLAGS: 00000206
RAX: 7477772dfdad4600 RBX: 1ffff92002c0bf8c RCX: ffffffff8170892a
RDX: dffffc0000000000 RSI: ffffffff8c0aca40 RDI: ffffffff8c6100a0
RBP: ffffc9001605fd00 R08: ffffffff942687ff R09: 1ffffffff284d0ff
R10: dffffc0000000000 R11: fffffbfff284d100 R12: 0000000000000000
R13: ffff88801f3ba260 R14: ffffc9001605fc60 R15: 1ffff92002c0bf88
 __rcu_read_unlock+0xa1/0x110 kernel/rcu/tree_plugin.h:437
 dput+0x197/0x2b0 fs/dcache.c:856
 __fput+0x5d2/0x880 fs/file_table.c:439
 task_work_run+0x24f/0x310 kernel/task_work.c:228
 resume_user_mode_work include/linux/resume_user_mode.h:50 [inline]
 exit_to_user_mode_loop kernel/entry/common.c:114 [inline]
 exit_to_user_mode_prepare include/linux/entry-common.h:328 [inline]
 __syscall_exit_to_user_mode_work kernel/entry/common.c:207 [inline]
 syscall_exit_to_user_mode+0x168/0x370 kernel/entry/common.c:218
 do_syscall_64+0x100/0x230 arch/x86/entry/common.c:89
 entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f9a9d17dff9
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 a8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007ffeef942718 EFLAGS: 00000246 ORIG_RAX: 00000000000001b4
RAX: 0000000000000000 RBX: 000000000001a096 RCX: 00007f9a9d17dff9
RDX: 0000000000000000 RSI: 000000000000001e RDI: 0000000000000003
RBP: 00007f9a9d337a80 R08: 0000000000000001 R09: 00007ffeef942a0f
R10: 00007f9a9d000000 R11: 0000000000000246 R12: 000000000001a143
R13: 00007ffeef942820 R14: 0000000000000032 R15: ffffffffffffffff
 </TASK>

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/09/29 01:51 bpf 99a648c951ba ba29ff75 .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-bpf-kasan-gce BUG: soft lockup in sys_perf_event_open
* Struck through repros no longer work on HEAD.