vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: stop threads INFO: task kworker/1:0:19 blocked for more than 140 seconds. vhci_hcd: release socket Not tainted 4.19.154-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: disconnect device kworker/1:0 D25816 19 2 0x80000000 vhci_hcd: connection closed vhci_hcd: stop threads Workqueue: usb_hub_wq hub_event vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device 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 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 vhci_hcd vhci_hcd.0: port 1 already used 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 vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: stop threads hub_port_init+0x12d9/0x2fa0 drivers/usb/core/hub.c:4654 vhci_hcd: release socket 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: disconnect device vhci_hcd: connection closed vhci_hcd: stop threads process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device 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 INFO: task kworker/1:1:25 blocked for more than 140 seconds. Not tainted 4.19.154-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) kworker/1:1 D26264 25 2 0x80000000 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) Workqueue: usb_hub_wq hub_event vhci_hcd: connection closed vhci_hcd: stop threads Call Trace: vhci_hcd: release socket context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 vhci_hcd: disconnect device 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 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: port 1 already used worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: stop threads ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 vhci_hcd: release socket INFO: task kworker/0:2:3683 blocked for more than 140 seconds. vhci_hcd: disconnect device Not tainted 4.19.154-syzkaller #0 vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 1 already used kworker/0:2 D25128 3683 2 0x80000000 Workqueue: usb_hub_wq hub_event vhci_hcd: connection closed vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) Call Trace: vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: stop threads context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 vhci_hcd: release socket schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device 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(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-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 hub_port_init+0x12d9/0x2fa0 drivers/usb/core/hub.c:4654 vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device 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(1) speed_str(low-speed) process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd: connection closed worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used 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/1:4:9367 blocked for more than 140 seconds. Not tainted 4.19.154-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:4 D26280 9367 2 0x80000000 Workqueue: usb_hub_wq hub_event vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) 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 vhci_hcd: connection closed vhci_hcd: stop threads usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd: release socket 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 hub_port_init+0x12d9/0x2fa0 drivers/usb/core/hub.c:4654 vhci_hcd vhci_hcd.0: port 1 already used 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 vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 1 already used INFO: task kworker/1:5:9632 blocked for more than 140 seconds. Not tainted 4.19.154-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) kworker/1:5 D27992 9632 2 0x80000000 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed Workqueue: usb_hub_wq hub_event vhci_hcd: connection closed vhci_hcd: release socket Call Trace: vhci_hcd: disconnect device vhci_hcd: stop threads context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device schedule+0x8d/0x1b0 kernel/sched/core.c:3561 vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 vhci_hcd vhci_hcd.0: port 1 already used 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(1) speed_str(low-speed) vhci_hcd: connection closed 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 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/1:6:9662 blocked for more than 140 seconds. vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) Not tainted 4.19.154-syzkaller #0 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: connection closed vhci_hcd: stop threads kworker/1:6 D26296 9662 2 0x80000000 vhci_hcd: release socket Workqueue: usb_hub_wq hub_event vhci_hcd: disconnect device 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 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 vhci_hcd vhci_hcd.0: port 1 already used 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: vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 1 already used 5 locks held by kworker/1:0/19: vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) #0: 000000006c04a1a3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: stop threads vhci_hcd: release socket #1: 000000000b7d17fb ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: stop threads #2: 00000000f29b1ab7 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000f29b1ab7 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) #3: 000000007eff90a8 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 000000007eff90a8 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 000000007eff90a8 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 000000007eff90a8 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 000000007eff90a8 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) #4: 0000000056da1a91 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: stop threads 5 locks held by kworker/1:1/25: vhci_hcd: release socket #0: 000000006c04a1a3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd: disconnect device #1: 00000000d48b5ac7 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads #2: vhci_hcd: release socket 00000000a5bcae60 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] 00000000a5bcae60 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device #3: 000000003a881940 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 000000003a881940 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 000000003a881940 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 000000003a881940 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 000000003a881940 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 000000009fdd1a13 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 1 lock held by khungtaskd/1566: vhci_hcd vhci_hcd.0: port 1 already used #0: 00000000dcb0b2e4 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440 vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) 5 locks held by kworker/0:2/3683: vhci_hcd: connection closed vhci_hcd: stop threads #0: 000000006c04a1a3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd: release socket #1: 000000008737cf4b ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: disconnect device #2: 00000000b5f35b74 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000b5f35b74 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000a6462bdb (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000a6462bdb (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000a6462bdb (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000a6462bdb (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000a6462bdb (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 00000000669d0175 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 1 lock held by in:imklog/7864: #0: 00000000754343ed (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 5 locks held by kworker/1:4/9367: #0: 000000006c04a1a3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 0000000030743edd ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 00000000a7366a47 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000a7366a47 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000129abd3b (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000129abd3b (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000129abd3b (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000129abd3b (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000129abd3b (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 00000000c7476bc2 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 5 locks held by kworker/1:5/9632: #0: 000000006c04a1a3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 000000004fb4aa24 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 00000000d1815774 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000d1815774 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000a56a5d12 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000a56a5d12 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000a56a5d12 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000a56a5d12 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000a56a5d12 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 00000000879b135c (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 5 locks held by kworker/1:6/9662: #0: 000000006c04a1a3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 000000004b0e3f97 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd vhci_hcd.0: port 1 already used #2: 0000000096f14fc1 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 0000000096f14fc1 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 000000005c77168a (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 000000005c77168a (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 000000005c77168a (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 000000005c77168a (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 000000005c77168a (&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: 00000000821a3bd6 (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) 9 locks held by syz-executor.4/19018: vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) #0: 000000007170b98f (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: stop threads #1: 00000000dcb0b2e4 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000dcb0b2e4 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd: release socket #2: 00000000dcb0b2e4 (rcu_read_lock){....}, at: is_bpf_text_address+0x0/0x1b0 kernel/bpf/core.c:532 vhci_hcd: connection closed vhci_hcd: disconnect device #3: 00000000787b72ae (kn->count#73){.+.+}, at: kernfs_fop_write+0x223/0x470 fs/kernfs/file.c:308 vhci_hcd: stop threads 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(1) speed_str(low-speed) vhci_hcd: connection closed #4: vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: release socket 00000000a53e8344 (&(&bh->lock)->rlock){..-.}, at: spin_lock_irq include/linux/spinlock.h:354 [inline] 00000000a53e8344 (&(&bh->lock)->rlock){..-.}, at: usb_giveback_urb_bh+0x2ae/0x450 drivers/usb/core/hcd.c:1802 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) #5: 000000002188c1a9 (hcd_urb_list_lock){..-.}, at: spin_lock include/linux/spinlock.h:329 [inline] #5: 000000002188c1a9 (hcd_urb_list_lock){..-.}, at: usb_hcd_link_urb_to_ep+0x29/0x380 drivers/usb/core/hcd.c:1245 vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd: release socket vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) #6: 00000000dcb0b2e4 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #6: 00000000dcb0b2e4 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 1 already used #7: 00000000dcb0b2e4 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #7: 00000000dcb0b2e4 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads #8: 00000000149e636c (console_owner_lock){....}, at: console_lock_spinning_disable_and_check kernel/printk/printk.c:1649 [inline] #8: 00000000149e636c (console_owner_lock){....}, at: console_unlock+0x9bd/0x1110 kernel/printk/printk.c:2463 vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd: release socket vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1566 Comm: khungtaskd Not tainted 4.19.154-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 vhci_hcd: disconnect device 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 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 4691 Comm: systemd-journal Not tainted 4.19.154-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:pmd_val arch/x86/include/asm/paravirt.h:499 [inline] RIP: 0010:pmd_page_vaddr arch/x86/include/asm/pgtable.h:782 [inline] RIP: 0010:pte_offset_kernel arch/x86/include/asm/pgtable.h:824 [inline] RIP: 0010:handle_pte_fault mm/memory.c:4112 [inline] RIP: 0010:__handle_mm_fault+0x83e/0x41c0 mm/memory.c:4258 Code: 00 00 fc ff df 48 89 ea 48 c1 eb 09 48 c1 ea 03 81 e3 f8 0f 00 00 80 3c 02 00 0f 85 2f 33 00 00 48 b8 00 00 00 00 00 fc ff df <4c> 8b 7d 00 48 c7 c5 e0 c4 ce 89 48 89 ea 48 c1 ea 03 80 3c 02 00 RSP: 0000:ffff8880a0fc7ce8 EFLAGS: 00000246 RAX: dffffc0000000000 RBX: 0000000000000d70 RCX: ffffffff818f40fd RDX: 1ffff110141f9ef2 RSI: ffffffff818f410b RDI: 0000000000000005 RBP: ffff8880a0fcf790 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000005 R11: 0000000000000000 R12: 00000000aab9d067 R13: 1ffff110141f8fa3 R14: 0000000000000000 R15: ffff8880a0c9a380 FS: 00007f2a2178b8c0(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f2a1e5aea80 CR3: 00000000a13ab000 CR4: 00000000001406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: handle_mm_fault+0x436/0xb10 mm/memory.c:4295 __do_page_fault+0x68e/0xd60 arch/x86/mm/fault.c:1412 page_fault+0x1e/0x30 arch/x86/entry/entry_64.S:1205 RIP: 0033:0x7f2a2147cc11 Code: c0 78 5d 48 8b 4c 24 08 4c 8d 4c 24 20 31 d2 49 89 d8 89 ee 4c 89 e7 e8 ad d4 ff ff 85 c0 78 40 48 8b 44 24 20 48 8b 74 24 08 <48> c7 00 00 00 00 00 48 89 58 08 40 88 28 49 8b 94 24 c8 00 00 00 RSP: 002b:00007ffe92807230 EFLAGS: 00010202 RAX: 00007f2a1e5aea80 RBX: 0000000000000065 RCX: 00000000008a3a80 RDX: 0000000000000000 RSI: 00000000008a3a80 RDI: 000055eb76ea5d80 RBP: 0000000000000001 R08: 00000000008a3ae5 R09: 000055eb76ea5d80 R10: 001cefaca69163b3 R11: 0000000000000001 R12: 000055eb76e9ce80 R13: 00007ffe92807360 R14: 00007ffe92807358 R15: 0000000011a0d43f