vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device INFO: task kworker/0:1:14 blocked for more than 140 seconds. Not tainted 4.19.163-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:1 D26008 14 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 vhci_hcd vhci_hcd.0: port 1 already used usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 hub_port_init+0x12d9/0x2fa0 drivers/usb/core/hub.c:4654 hub_port_connect drivers/usb/core/hub.c:5020 [inline] hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] port_event drivers/usb/core/hub.c:5311 [inline] hub_event+0x1cf8/0x43f0 drivers/usb/core/hub.c:5391 process_one_work+0x864/0x1570 kernel/workqueue.c:2155 worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Showing all locks held in the system: 5 locks held by kworker/0:1/14: #0: 0000000096aee4a4 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 00000000a6f95f95 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(4) #2: 0000000062f6d009 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 0000000062f6d009 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) #3: 00000000262f60ff (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000262f60ff (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000262f60ff (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000262f60ff (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000262f60ff (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 000000003ffdc1d2 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 1 lock held by khungtaskd/1566: #0: 0000000052e94dc8 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440 3 locks held by kworker/u4:4/2919: #0: 0000000024d6ab9b (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline] #0: 0000000024d6ab9b (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 0000000052e94dc8 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 0000000052e94dc8 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 #2: 00000000bdd78ad8 (key#13){+...}, at: spin_lock_bh include/linux/spinlock.h:334 [inline] #2: 00000000bdd78ad8 (key#13){+...}, at: batadv_nc_purge_paths+0xdc/0x310 net/batman-adv/network-coding.c:453 1 lock held by in:imklog/7807: 2 locks held by kworker/1:0/8091: #0: 0000000066db20b6 ((wq_completion)"rcu_gp"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 0000000056db2895 ((work_completion)(&rew.rew_work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 3 locks held by syz-executor.0/10214: #0: 00000000a1dc7ce7 (&sb->s_type->i_mutex_key#13){+.+.}, at: inode_lock include/linux/fs.h:748 [inline] #0: 00000000a1dc7ce7 (&sb->s_type->i_mutex_key#13){+.+.}, at: __sock_release+0x86/0x2a0 net/socket.c:578 #1: 000000009c38adfd (sk_lock-AF_PHONET){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #1: 000000009c38adfd (sk_lock-AF_PHONET){+.+.}, at: pep_sock_unhash+0x18/0x310 net/phonet/pep.c:1312 #2: 0000000008c5c5f7 (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:297 [inline] #2: 0000000008c5c5f7 (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x4dc/0x6f0 kernel/rcu/tree_exp.h:667 vhci_hcd: connection closed ============================================= vhci_hcd: stop threads NMI backtrace for cpu 0 CPU: 0 PID: 1566 Comm: khungtaskd Not tainted 4.19.163-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 vhci_hcd: release socket Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2fe lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101 vhci_hcd: disconnect device nmi_trigger_cpumask_backtrace+0x1a6/0x1eb lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline] watchdog+0x991/0xe60 kernel/hung_task.c:287 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 skipped: idling at native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:60