Bluetooth: hci7: link tx timeout
Bluetooth: hci7: killing stalled connection 11:aa:aa:aa:aa:aa
BUG: sleeping function called from invalid context at kernel/locking/mutex.c:580
in_atomic(): 0, irqs_disabled(): 0, non_block: 0, pid: 840, name: kworker/u5:1
preempt_count: 0, expected: 0
RCU nest depth: 1, expected: 0
3 locks held by kworker/u5:1/840:
#0: ffff888017a7a938 ((wq_completion)hci7#2){+.+.}-{0:0}, at: process_one_work+0x99d/0x1770
#1: ffff8880368c7db0 ((work_completion)(&hdev->tx_work)){+.+.}-{0:0}, at: process_one_work+0x9d0/0x1770
#2: ffffffff8560b6c0 (rcu_read_lock){....}-{1:2}, at: __check_timeout+0x171/0x480
CPU: 1 PID: 840 Comm: kworker/u5:1 Not tainted 6.4.0-next-20230704 #1
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
Workqueue: hci7 hci_tx_work
Call Trace:
dump_stack_lvl+0xc1/0xf0
__might_resched+0x2f9/0x510
__mutex_lock+0x9e/0x1a30
hci_cmd_sync_submit+0x3b/0x2d0
hci_cmd_sync_queue+0x7b/0xb0
hci_disconnect+0x1c2/0x450
__check_timeout+0x2cc/0x480
hci_tx_work+0x7cb/0x1a70
process_one_work+0xabf/0x1770
worker_thread+0x64f/0x12a0
kthread+0x33f/0x440
ret_from_fork+0x2c/0x50
=============================
[ BUG: Invalid wait context ]
6.4.0-next-20230704 #1 Tainted: G W
-----------------------------
kworker/u5:1/840 is trying to lock:
ffff88801663c9b0 (&hdev->unregister_lock){+.+.}-{3:3}, at: hci_cmd_sync_submit+0x3b/0x2d0
other info that might help us debug this:
context-{4:4}
3 locks held by kworker/u5:1/840:
#0: ffff888017a7a938 ((wq_completion)hci7#2){+.+.}-{0:0}, at: process_one_work+0x99d/0x1770
#1: ffff8880368c7db0 ((work_completion)(&hdev->tx_work)){+.+.}-{0:0}, at: process_one_work+0x9d0/0x1770
#2: ffffffff8560b6c0 (rcu_read_lock){....}-{1:2}, at: __check_timeout+0x171/0x480
stack backtrace:
CPU: 1 PID: 840 Comm: kworker/u5:1 Tainted: G W 6.4.0-next-20230704 #1
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
Workqueue: hci7 hci_tx_work
Call Trace:
dump_stack_lvl+0x91/0xf0
__lock_acquire+0x1605/0x6340
lock_acquire+0x19a/0x4c0
__mutex_lock+0x12a/0x1a30
hci_cmd_sync_submit+0x3b/0x2d0
hci_cmd_sync_queue+0x7b/0xb0
hci_disconnect+0x1c2/0x450
__check_timeout+0x2cc/0x480
hci_tx_work+0x7cb/0x1a70
process_one_work+0xabf/0x1770
worker_thread+0x64f/0x12a0
kthread+0x33f/0x440
ret_from_fork+0x2c/0x50
Bluetooth: hci7: link tx timeout
Bluetooth: hci7: killing stalled connection 11:aa:aa:aa:aa:aa
Bluetooth: hci7: command 0x0406 tx timeout
__nla_validate_parse: 8 callbacks suppressed
netlink: 164 bytes leftover after parsing attributes in process `syz-executor.2'.
loop0: detected capacity change from 0 to 16
EXT4-fs (loop0): couldn't mount RDWR because of unsupported optional features (ac0000)
loop0: detected capacity change from 0 to 16
EXT4-fs (loop0): couldn't mount RDWR because of unsupported optional features (ac0000)
netlink: 164 bytes leftover after parsing attributes in process `syz-executor.2'.
loop0: detected capacity change from 0 to 16
EXT4-fs (loop0): couldn't mount RDWR because of unsupported optional features (ac0000)
loop0: detected capacity change from 0 to 16
netlink: 164 bytes leftover after parsing attributes in process `syz-executor.2'.
EXT4-fs (loop0): couldn't mount RDWR because of unsupported optional features (ac0000)
lo: entered promiscuous mode
lo: entered allmulticast mode
netlink: 164 bytes leftover after parsing attributes in process `syz-executor.2'.
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
lo: left promiscuous mode
lo: left allmulticast mode
lo: entered promiscuous mode
lo: entered allmulticast mode
loop5: detected capacity change from 0 to 10
loop5: detected capacity change from 0 to 10
loop5: detected capacity change from 0 to 10
loop0: detected capacity change from 0 to 10
loop1: detected capacity change from 0 to 10
loop0: detected capacity change from 0 to 10
loop5: detected capacity change from 0 to 10
loop1: detected capacity change from 0 to 10
loop0: detected capacity change from 0 to 10
loop1: detected capacity change from 0 to 10
program syz-executor.7 is using a deprecated SCSI ioctl, please convert it to SG_IO
program syz-executor.7 is using a deprecated SCSI ioctl, please convert it to SG_IO
program syz-executor.1 is using a deprecated SCSI ioctl, please convert it to SG_IO
program syz-executor.7 is using a deprecated SCSI ioctl, please convert it to SG_IO
program syz-executor.1 is using a deprecated SCSI ioctl, please convert it to SG_IO
program syz-executor.7 is using a deprecated SCSI ioctl, please convert it to SG_IO
program syz-executor.1 is using a deprecated SCSI ioctl, please convert it to SG_IO
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
nfs: Unknown parameter '^'
Bluetooth: hci4: unexpected event for opcode 0x0804
Bluetooth: hci4: unexpected event for opcode 0x0413
loop2: detected capacity change from 0 to 512
Bluetooth: hci4: unexpected event for opcode 0x0804
Bluetooth: hci4: unexpected event for opcode 0x0413
EXT4-fs (loop2): mounted filesystem 00000000-0000-0000-0000-000000000000 r/w without journal. Quota mode: none.
audit: type=1400 audit(1688468078.564:16): avc: denied { watch_reads } for pid=10033 comm="syz-executor.6" path="/syzkaller-testdir202308766/syzkaller.ntsTsN/309/file0" dev="sda" ino=16036 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:object_r:unlabeled_t:s0 tclass=dir permissive=1
Bluetooth: hci4: unexpected event for opcode 0x0804
Bluetooth: hci4: unexpected event for opcode 0x0413
EXT4-fs (loop2): unmounting filesystem 00000000-0000-0000-0000-000000000000.
loop2: detected capacity change from 0 to 512
EXT4-fs (loop2): mounted filesystem 00000000-0000-0000-0000-000000000000 r/w without journal. Quota mode: none.
EXT4-fs (loop2): unmounting filesystem 00000000-0000-0000-0000-000000000000.
Bluetooth: hci4: unexpected event for opcode 0x0804
Bluetooth: hci4: unexpected event for opcode 0x0413
loop2: detected capacity change from 0 to 512
EXT4-fs (loop2): mounted filesystem 00000000-0000-0000-0000-000000000000 r/w without journal. Quota mode: none.
EXT4-fs (loop2): unmounting filesystem 00000000-0000-0000-0000-000000000000.
loop2: detected capacity change from 0 to 512
EXT4-fs (loop2): mounted filesystem 00000000-0000-0000-0000-000000000000 r/w without journal. Quota mode: none.
EXT4-fs (loop2): unmounting filesystem 00000000-0000-0000-0000-000000000000.