BUG: soft lockup in mgmt_set_connectable_complete

Status: upstream: reported C repro on 2021/04/20 18:43
Reported-by: syzbot+11cf9d6a727031a7a6f5@syzkaller.appspotmail.com
First crash: 1952d, last: 1450d
Fix bisection: failed (error log, bisect log)
  
▶ ▼ Fix bisection attempts (16)
Created Duration User Patch Repo Result
2022/10/13 02:17 0m bisect fix linux-4.14.y error job log
2022/09/04 04:08 22m bisect fix linux-4.14.y OK (0) job log log
2022/08/05 03:26 22m bisect fix linux-4.14.y OK (0) job log log
2022/07/06 03:06 20m bisect fix linux-4.14.y OK (0) job log log
2022/06/06 02:42 24m bisect fix linux-4.14.y OK (0) job log log
2022/04/10 18:08 26m bisect fix linux-4.14.y OK (0) job log log
2022/03/11 17:16 24m bisect fix linux-4.14.y OK (0) job log log
2022/02/09 16:04 26m bisect fix linux-4.14.y OK (0) job log log
2022/01/10 15:37 26m bisect fix linux-4.14.y OK (0) job log log
2021/12/11 15:10 26m bisect fix linux-4.14.y OK (0) job log log
2021/11/11 14:48 22m bisect fix linux-4.14.y OK (0) job log log
2021/10/12 14:22 25m bisect fix linux-4.14.y OK (0) job log log
2021/09/12 07:56 25m bisect fix linux-4.14.y OK (0) job log log
2021/08/13 07:32 24m bisect fix linux-4.14.y OK (0) job log log
2021/07/14 07:10 22m bisect fix linux-4.14.y OK (0) job log log
2021/06/14 03:26 27m bisect fix linux-4.14.y OK (0) job log log

Sample crash report:
Bluetooth: hci3 command 0x041b tx timeout
Bluetooth: hci1 command 0x041b tx timeout
Bluetooth: hci0 command 0x041b tx timeout
Bluetooth: hci5 command 0x041b tx timeout
Bluetooth: hci4 command 0x041b tx timeout
watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u5:3:8015]
Modules linked in:
irq event stamp: 196410
hardirqs last  enabled at (196409): [<ffffffff8122f7db>] kvm_wait arch/x86/kernel/kvm.c:600 [inline]
hardirqs last  enabled at (196409): [<ffffffff8122f7db>] kvm_wait+0x14b/0x240 arch/x86/kernel/kvm.c:580
hardirqs last disabled at (196410): [<ffffffff874018ae>] apic_timer_interrupt+0x8e/0xa0 arch/x86/entry/entry_64.S:793
softirqs last  enabled at (196406): [<ffffffff8760068b>] __do_softirq+0x68b/0x9ff kernel/softirq.c:314
softirqs last disabled at (196395): [<ffffffff81320c83>] invoke_softirq kernel/softirq.c:368 [inline]
softirqs last disabled at (196395): [<ffffffff81320c83>] irq_exit+0x193/0x240 kernel/softirq.c:409
CPU: 0 PID: 8015 Comm: kworker/u5:3 Not tainted 4.14.231-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: hci4 connectable_update_work
task: ffff8880b2f90200 task.stack: ffff8880b2c78000
RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61
RSP: 0000:ffff8880b2c7fab0 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10
RAX: 1ffffffff11e125c RBX: ffffffff89dd442c RCX: 1ffff110165f2169
RDX: dffffc0000000000 RSI: ffff8880b2f90b28 RDI: ffff8880b2f90a84
RBP: 0000000000000003 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000286
R13: dffffc0000000000 R14: ffffffff89dd442d R15: ffffed10174855d8
FS:  0000000000000000(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000020000000 CR3: 000000009a580000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 arch_safe_halt arch/x86/include/asm/paravirt.h:94 [inline]
 kvm_wait arch/x86/kernel/kvm.c:600 [inline]
 kvm_wait+0x179/0x240 arch/x86/kernel/kvm.c:580
 pv_wait arch/x86/include/asm/paravirt.h:679 [inline]
 pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:447 [inline]
 __pv_queued_spin_lock_slowpath+0x722/0x910 kernel/locking/qspinlock.c:465
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:669 [inline]
 queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:52 [inline]
 queued_spin_lock include/asm-generic/qspinlock.h:90 [inline]
 queued_read_lock_slowpath+0x150/0x190 kernel/locking/qrwlock.c:90
 hci_send_to_channel+0x3c/0x200 net/bluetooth/hci_sock.c:261
 hci_send_monitor_ctrl_event+0x3dd/0x500 net/bluetooth/hci_sock.c:391
 mgmt_send_event+0x2b3/0x330 net/bluetooth/mgmt_util.c:86
 mgmt_limited_event net/bluetooth/mgmt.c:260 [inline]
 new_settings net/bluetooth/mgmt.c:1089 [inline]
 mgmt_set_connectable_complete+0x299/0x310 net/bluetooth/mgmt.c:1352
 connectable_update_work+0xbf/0xf0 net/bluetooth/hci_request.c:1597
 process_one_work+0x793/0x14a0 kernel/workqueue.c:2116
 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Code: 2a 5c fa e9 4f ff ff ff 48 89 ef e8 8d 2a 5c fa eb 93 90 90 90 90 90 90 90 90 90 90 90 e9 07 00 00 00 0f 00 2d 94 e4 64 00 fb f4 <c3> 90 e9 07 00 00 00 0f 00 2d 84 e4 64 00 f4 c3 90 90 41 55 41

Crashes (6):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/04/20 20:42 linux-4.14.y cf256fbcbe34 c0ced557 .config console log report syz C ci2-linux-4-14 BUG: soft lockup in mgmt_set_connectable_complete
2022/05/07 02:42 linux-4.14.y e3a56aaade89 e60b1103 .config console log report info ci2-linux-4-14 BUG: soft lockup in mgmt_set_connectable_complete
2021/05/15 03:26 linux-4.14.y 7d7d1c0ab3eb 8bdd5343 .config console log report info ci2-linux-4-14 BUG: soft lockup in mgmt_set_connectable_complete
2021/04/22 18:09 linux-4.14.y cf256fbcbe34 33c28d03 .config console log report info ci2-linux-4-14 BUG: soft lockup in mgmt_set_connectable_complete
2021/04/21 13:55 linux-4.14.y cf256fbcbe34 95777977 .config console log report info ci2-linux-4-14 BUG: soft lockup in mgmt_set_connectable_complete
2021/04/20 18:42 linux-4.14.y cf256fbcbe34 c0ced557 .config console log report info ci2-linux-4-14 BUG: soft lockup in mgmt_set_connectable_complete
* Struck through repros no longer work on HEAD.