Bluetooth: hci8 command 0x041b tx timeout Bluetooth: hci8 command 0x040f tx timeout Bluetooth: hci6 command 0x0406 tx timeout Bluetooth: hci8 command 0x0419 tx timeout INFO: task kworker/0:1:10207 blocked for more than 140 seconds. Not tainted 4.14.291-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:1 D28440 10207 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 usb_kill_urb.part.0+0x125/0x190 drivers/usb/core/urb.c:697 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:686 usb_start_wait_urb+0x209/0x440 drivers/usb/core/message.c:62 usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x302/0x450 drivers/usb/core/message.c:151 hub_port_init+0xf27/0x28b0 drivers/usb/core/hub.c:4602 hub_port_connect drivers/usb/core/hub.c:4957 [inline] hub_port_connect_change drivers/usb/core/hub.c:5147 [inline] port_event drivers/usb/core/hub.c:5253 [inline] hub_event+0x1948/0x3f60 drivers/usb/core/hub.c:5333 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task syz-executor.0:7587 blocked for more than 140 seconds. Not tainted 4.14.291-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D28912 7587 8000 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 device_lock include/linux/device.h:1082 [inline] usbdev_release+0x73/0x380 drivers/usb/core/devio.c:1066 __fput+0x25f/0x7a0 fs/file_table.c:210 task_work_run+0x11f/0x190 kernel/task_work.c:113 tracehook_notify_resume include/linux/tracehook.h:191 [inline] exit_to_usermode_loop+0x1ad/0x200 arch/x86/entry/common.c:164 prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline] syscall_return_slowpath arch/x86/entry/common.c:270 [inline] do_syscall_64+0x4a3/0x640 arch/x86/entry/common.c:297 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f0e05577ebb RSP: 002b:00007fffc34fd5e0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000004 RCX: 00007f0e05577ebb RDX: 0000000000000000 RSI: 0000001b2f121838 RDI: 0000000000000003 RBP: 00007f0e056d9980 R08: 0000000000000000 R09: 000000005290041d R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000106185 R13: 00007fffc34fd6e0 R14: 00007f0e056d8050 R15: 0000000000000032 INFO: task syz-executor.0:7920 blocked for more than 140 seconds. Not tainted 4.14.291-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D29776 7920 7672 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 device_lock include/linux/device.h:1082 [inline] usbdev_open+0x142/0x790 drivers/usb/core/devio.c:1023 chrdev_open+0x23c/0x6d0 fs/char_dev.c:423 do_dentry_open+0x44b/0xec0 fs/open.c:777 vfs_open+0x105/0x220 fs/open.c:888 do_last fs/namei.c:3428 [inline] path_openat+0x628/0x2970 fs/namei.c:3571 do_filp_open+0x179/0x3c0 fs/namei.c:3605 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7fa3f2470194 RSP: 002b:00007fa3f0e31ca0 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 00007fa3f2470194 RDX: 0000000000000000 RSI: 00007fa3f0e31d40 RDI: 00000000ffffff9c RBP: 00007fa3f0e31d40 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 R13: 00007fff3086b1df R14: 00007fa3f0e32300 R15: 0000000000022000 Showing all locks held in the system: 1 lock held by khungtaskd/1534: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 5 locks held by kworker/0:1/10207: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5279 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2939 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4923 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5147 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5253 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5333 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4924 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5147 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5253 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5333 1 lock held by syz-executor.0/7587: #0: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #0: (&dev->mutex){....}, at: [] usbdev_release+0x73/0x380 drivers/usb/core/devio.c:1066 1 lock held by syz-executor.0/7920: #0: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #0: (&dev->mutex){....}, at: [] usbdev_open+0x142/0x790 drivers/usb/core/devio.c:1023 1 lock held by syz-executor.0/8243: #0: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #0: (&dev->mutex){....}, at: [] usbdev_open+0x142/0x790 drivers/usb/core/devio.c:1023 1 lock held by syz-executor.0/8605: #0: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #0: (&dev->mutex){....}, at: [] usbdev_open+0x142/0x790 drivers/usb/core/devio.c:1023 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1534 Comm: khungtaskd Not tainted 4.14.291-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x180 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline] watchdog+0x5b9/0xb40 kernel/hung_task.c:274 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 9 Comm: rcu_preempt Not tainted 4.14.291-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 task: ffff8880b5420240 task.stack: ffff8880b5428000 RIP: 0010:__read_once_size include/linux/compiler.h:185 [inline] RIP: 0010:lookup_chain_cache kernel/locking/lockdep.c:2361 [inline] RIP: 0010:lookup_chain_cache_add kernel/locking/lockdep.c:2381 [inline] RIP: 0010:validate_chain kernel/locking/lockdep.c:2435 [inline] RIP: 0010:__lock_acquire+0xaab/0x3f20 kernel/locking/lockdep.c:3491 RSP: 0018:ffff8880b542f8c0 EFLAGS: 00000097 RAX: 1ffffffff16754ff RBX: ffffffff8b3aa7e0 RCX: 0000000000007be6 RDX: 1ffffffff173bf46 RSI: 00000000ca87c5c9 RDI: ffffffff8b3aa7f8 RBP: dffffc0000000000 R08: ffffffff8b9dfa30 R09: 00000000000c0001 R10: ffff8880b5420af0 R11: ffff8880b5420240 R12: ffff8880b5420b08 R13: 0000000000000000 R14: 29e9fb408bdc854a R15: ffffffff8becddc0 FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f023f1a1000 CR3: 00000000a226c000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x8c/0xc0 kernel/locking/spinlock.c:160 debug_object_deactivate lib/debugobjects.c:502 [inline] debug_object_deactivate+0xdf/0x2e0 lib/debugobjects.c:491 debug_timer_deactivate kernel/time/timer.c:698 [inline] debug_deactivate kernel/time/timer.c:750 [inline] detach_timer kernel/time/timer.c:790 [inline] detach_if_pending+0x82/0x430 kernel/time/timer.c:809 try_to_del_timer_sync+0x9a/0xe0 kernel/time/timer.c:1181 del_timer_sync+0x17c/0x240 kernel/time/timer.c:1246 schedule_timeout+0x4b7/0xe90 kernel/time/timer.c:1748 rcu_gp_kthread+0xc0a/0x1e60 kernel/rcu/tree.c:2255 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Code: 00 fc ff df eb 06 48 83 eb 08 74 3d 48 8d 7b 18 48 89 f8 48 c1 e8 03 80 3c 28 00 0f 85 24 1f 00 00 4c 3b 73 18 0f 84 81 fd ff ff <48> 8d 7b 08 48 89 f8 48 c1 e8 03 80 3c 28 00 0f 85 9c 1e 00 00