BUG: soft lockup in mgmt_set_connectable_complete

4 views
Skip to first unread message

syzbot

unread,
Apr 20, 2021, 2:43:23 PM4/20/21
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: cf256fbc Linux 4.14.231
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=12699e05d00000
kernel config: https://syzkaller.appspot.com/x/.config?x=403e68efdb1dcca6
dashboard link: https://syzkaller.appspot.com/bug?extid=11cf9d6a727031a7a6f5

Unfortunately, I don't have any reproducer for this issue yet.

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+11cf9d...@syzkaller.appspotmail.com

RDX: 0000000000000007 RSI: 0000000020000040 RDI: 0000000000000004
RBP: 00007fe6e6b8a1d0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000001
R13: 00007ffc6d9ecb2f R14: 00007fe6e6b8a300 R15: 0000000000022000
watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/u5:1:8007]
Modules linked in:
irq event stamp: 24672
hardirqs last enabled at (24671): [<ffffffff8122f7db>] kvm_wait arch/x86/kernel/kvm.c:600 [inline]
hardirqs last enabled at (24671): [<ffffffff8122f7db>] kvm_wait+0x14b/0x240 arch/x86/kernel/kvm.c:580
hardirqs last disabled at (24672): [<ffffffff874018ae>] apic_timer_interrupt+0x8e/0xa0 arch/x86/entry/entry_64.S:793
softirqs last enabled at (14948): [<ffffffff8760068b>] __do_softirq+0x68b/0x9ff kernel/softirq.c:314
softirqs last disabled at (14907): [<ffffffff81320c83>] invoke_softirq kernel/softirq.c:368 [inline]
softirqs last disabled at (14907): [<ffffffff81320c83>] irq_exit+0x193/0x240 kernel/softirq.c:409
CPU: 0 PID: 8007 Comm: kworker/u5:1 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: ffff888056e802c0 task.stack: ffff888056e88000
RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61
RSP: 0018:ffff888056e8fab0 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10
RAX: 1ffffffff11e125c RBX: ffffffff89dd442c RCX: 1ffff1100add0181
RDX: dffffc0000000000 RSI: ffff888056e80be8 RDI: ffff888056e80b44
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: 00000000024a9708 CR3: 00000000939cb000 CR4: 00000000001426f0
DR0: 0000000020000280 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000090602
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
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 20401 Comm: syz-executor.5 Not tainted 4.14.231-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff88809f916580 task.stack: ffff8881c55c8000
RIP: 0010:__read_once_size include/linux/compiler.h:183 [inline]
RIP: 0010:atomic_read arch/x86/include/asm/atomic.h:27 [inline]
RIP: 0010:queued_write_lock_slowpath+0xd5/0x1d0 kernel/locking/qrwlock.c:139
RSP: 0018:ffff8881c55cfd38 EFLAGS: 00000206
RAX: 0000000000000000 RBX: ffffffff89dd4428 RCX: 00000000000024f1
RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff89dd4428
RBP: ffffffff89dd442c R08: ffffffff8b9a2248 R09: 00000000000405a2
R10: ffff88809f916e30 R11: ffff88809f916580 R12: 0000000000000003
R13: fffffbfff13ba885 R14: 00000000000000ff R15: ffffffff8863a320
FS: 00007f31864d0700(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000000051a5d0 CR3: 000000009db96000 CR4: 00000000001426e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
queued_write_lock include/asm-generic/qrwlock.h:134 [inline]
do_raw_write_lock+0xc2/0x1d0 kernel/locking/spinlock_debug.c:203
bt_sock_link+0x1f/0x120 net/bluetooth/af_bluetooth.c:143
hci_sock_create+0x15c/0x1d0 net/bluetooth/hci_sock.c:2017
bt_sock_create+0x13b/0x280 net/bluetooth/af_bluetooth.c:130
__sock_create+0x303/0x620 net/socket.c:1275
sock_create net/socket.c:1315 [inline]
SYSC_socket net/socket.c:1345 [inline]
SyS_socket+0xd1/0x1b0 net/socket.c:1325
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x466459
RSP: 002b:00007f31864d0128 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 000000000056bf60 RCX: 0000000000466459
RDX: 0000000000000001 RSI: 0000000000000003 RDI: 000000000000001f
RBP: 0000000000000003 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60
R13: 00007fffc4d04edf R14: 00007f31864d0300 R15: 0000000000022000
Code: ff 00 00 00 49 c1 ed 03 41 83 e4 07 48 b8 00 00 00 00 00 fc ff df 49 01 c5 41 83 c4 03 eb 02 f3 90 41 0f b6 45 00 41 38 c4 7c 08 <84> c0 0f 85 9e 00 00 00 8b 03 83 f8 01 75 e5 f0 44 0f b1 33 83


---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

syzbot

unread,
Apr 20, 2021, 4:43:19 PM4/20/21
to syzkaller...@googlegroups.com
syzbot has found a reproducer for the following issue on:

HEAD commit: cf256fbc Linux 4.14.231
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=1776fb91d00000
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=16b20471d00000
C reproducer: https://syzkaller.appspot.com/x/repro.c?x=13e01c6dd00000

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+11cf9d...@syzkaller.appspotmail.com

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:
Reply all
Reply to author
Forward
0 new messages