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:8085 blocked for more than 140 seconds. Not tainted 4.19.155-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:1 D25144 8085 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 vhci_hcd vhci_hcd.0: port 1 already used schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 vhci_hcd: connection closed usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device 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 vhci_hcd vhci_hcd.0: port 1 already used process_one_work+0x864/0x1570 kernel/workqueue.c:2155 worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/0:0:8093 blocked for more than 140 seconds. Not tainted 4.19.155-syzkaller #0 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:0 D25272 8093 2 0x80000000 vhci_hcd: release socket vhci_hcd: disconnect device Workqueue: usb_hub_wq hub_event vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) 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 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: disconnect device worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/0:3:9345 blocked for more than 140 seconds. Not tainted 4.19.155-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) kworker/0:3 D26576 9345 2 0x80000000 vhci_hcd: connection closed vhci_hcd: stop threads Workqueue: usb_hub_wq hub_event vhci_hcd: release socket Call Trace: vhci_hcd: disconnect device 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 usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 1 already used 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 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: connection closed vhci_hcd: stop threads kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd: release socket vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/1:3:9346 blocked for more than 140 seconds. vhci_hcd: connection closed vhci_hcd: stop threads Not tainted 4.19.155-syzkaller #0 vhci_hcd: release socket "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:3 D26296 9346 2 0x80000000 vhci_hcd: disconnect device Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 vhci_hcd vhci_hcd.0: port 1 already used schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd vhci_hcd.0: port 1 already used usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: disconnect device 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 vhci_hcd: connection closed hub_port_init+0x12d9/0x2fa0 drivers/usb/core/hub.c:4654 vhci_hcd: stop threads 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 vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: release socket process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd: disconnect device worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/0:4:9347 blocked for more than 140 seconds. Not tainted 4.19.155-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:4 D25160 9347 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 vhci_hcd vhci_hcd.0: port 1 already used 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: pdev(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: release socket usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 1 already used 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 vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/1:4:9571 blocked for more than 140 seconds. vhci_hcd: connection closed Not tainted 4.19.155-syzkaller #0 vhci_hcd: stop threads "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device kworker/1:4 D26296 9571 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 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 vhci_hcd: disconnect device usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd: connection closed vhci_hcd: stop threads usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 vhci_hcd: release socket vhci_hcd: disconnect device 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 vhci_hcd vhci_hcd.0: port 1 already used Showing all locks held in the system: 1 lock held by khungtaskd/1567: #0: 00000000a7cd9ca3 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440 1 lock held by in:imklog/7841: #0: 00000000a11990b7 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 5 locks held by kworker/0:1/8085: vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) #0: 00000000258b0526 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) #1: 000000005fc6ca47 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: connection closed vhci_hcd: stop threads #2: 00000000444b6550 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000444b6550 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd: release socket #3: 0000000061b3f101 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 0000000061b3f101 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 0000000061b3f101 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 0000000061b3f101 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 0000000061b3f101 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 vhci_hcd: disconnect device #4: 00000000c4b12685 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 5 locks held by kworker/0:0/8093: #0: 00000000258b0526 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 000000009be7ad59 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 00000000b4980ff2 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000b4980ff2 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 000000003355069e (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 000000003355069e (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 000000003355069e (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 000000003355069e (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 000000003355069e (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 vhci_hcd vhci_hcd.0: port 1 already used #4: 00000000a64ba926 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 3 locks held by kworker/u4:4/8682: #0: 0000000087ba189e (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline] #0: 0000000087ba189e (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) #1: 00000000a7cd9ca3 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000a7cd9ca3 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd vhci_hcd.0: port 1 already used #2: 00000000a7cd9ca3 (rcu_read_lock){....}, at: batadv_nc_purge_orig_hash net/batman-adv/network-coding.c:417 [inline] #2: 00000000a7cd9ca3 (rcu_read_lock){....}, at: batadv_nc_worker+0xf3/0xd50 net/batman-adv/network-coding.c:730 vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) 3 locks held by kworker/u4:5/8839: vhci_hcd: connection closed vhci_hcd: stop threads #0: 0000000087ba189e (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline] #0: 0000000087ba189e (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000a7cd9ca3 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000a7cd9ca3 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: disconnect device #2: 00000000e97ff40e (&obj_hash[i].lock){-.-.}, at: __debug_check_no_obj_freed lib/debugobjects.c:776 [inline] #2: 00000000e97ff40e (&obj_hash[i].lock){-.-.}, at: debug_check_no_obj_freed+0xb5/0x482 lib/debugobjects.c:817 vhci_hcd: stop threads 5 locks held by kworker/0:3/9345: vhci_hcd: release socket #0: 00000000258b0526 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 000000003aead86a ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: disconnect device #2: 000000004b636ff1 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 000000004b636ff1 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) #3: 00000000e1868f8d (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000e1868f8d (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000e1868f8d (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000e1868f8d (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000e1868f8d (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) #4: 0000000091e33546 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket 5 locks held by kworker/1:3/9346: vhci_hcd: disconnect device #0: 00000000258b0526 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket #1: 0000000088290c69 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) #2: 0000000093f476a6 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 0000000093f476a6 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd: connection closed vhci_hcd: stop threads #3: 00000000c6005a5b (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000c6005a5b (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000c6005a5b (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000c6005a5b (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000c6005a5b (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 vhci_hcd: release socket #4: 00000000036d6b76 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 vhci_hcd: disconnect device 5 locks held by kworker/0:4/9347: #0: 00000000258b0526 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 00000000ea15e564 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd vhci_hcd.0: port 1 already used #2: 000000005543bfca (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 000000005543bfca (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000bbec8f4c (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000bbec8f4c (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000bbec8f4c (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000bbec8f4c (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000bbec8f4c (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 0000000000ad19d3 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(3) speed_str(high-speed) 5 locks held by kworker/1:4/9571: vhci_hcd: connection closed #0: vhci_hcd: stop threads vhci_hcd: release socket 00000000258b0526 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd: disconnect device #1: 0000000082617e86 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 000000000e9af5e7 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 000000000e9af5e7 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000c795f206 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000c795f206 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000c795f206 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000c795f206 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000c795f206 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 000000004c31d5dc (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1567 Comm: khungtaskd Not tainted 4.19.155-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 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 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 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 9435 Comm: kworker/u4:6 Not tainted 4.19.155-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: phy4 ieee80211_iface_work RIP: 0010:__lock_release kernel/locking/lockdep.c:3703 [inline] RIP: 0010:lock_release+0x310/0x8b0 kernel/locking/lockdep.c:3926 Code: 68 22 10 66 f7 40 22 f0 ff 0f 85 bd 00 00 00 48 ba 00 00 00 00 00 fc ff df 48 8b 4c 24 08 8b 6c 24 38 48 c1 e9 03 0f b6 14 11 <84> d2 74 09 80 fa 03 0f 8e 1c 05 00 00 48 89 c1 41 89 ae 80 08 00 RSP: 0018:ffff88809595fc80 EFLAGS: 00000806 RAX: ffff88809e296b08 RBX: ffffffff8d23a720 RCX: 1ffff11013c52d60 RDX: 0000000000000000 RSI: ffff88813bfc2958 RDI: ffff88809e296b2a RBP: 0000000000000000 R08: 0000000000000001 R09: 0000000000000001 R10: 0000000000000000 R11: 0000000000000000 R12: 1ffff11012b2bf93 R13: ffffffff813f527b R14: ffff88809e296280 R15: 0000000000000001 FS: 0000000000000000(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f4d50ee0380 CR3: 00000000a11af000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: __raw_spin_unlock_irq include/linux/spinlock_api_smp.h:166 [inline] _raw_spin_unlock_irq+0x17/0x80 kernel/locking/spinlock.c:192 spin_unlock_irq include/linux/spinlock.h:379 [inline] process_one_work+0x77b/0x1570 kernel/workqueue.c:2128 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 vhci_hcd vhci_hcd.0: port 1 already used