vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device INFO: task kworker/0:2:3683 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/0:2 D26296 3683 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 vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) 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: devid(0) speed(5) speed_str(super-speed) 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_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) hub_port_init+0x748/0x2fa0 drivers/usb/core/hub.c:4749 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) 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 process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd: release socket worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/0:3:8374 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/0:3 D25784 8374 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 vhci_hcd vhci_hcd.0: port 1 already used usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) 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: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-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 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device usb_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 hub_port_init+0x748/0x2fa0 drivers/usb/core/hub.c:4749 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(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd: connection closed vhci_hcd: stop threads ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task kworker/1:3:9343 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: release socket kworker/1:3 D25344 9343 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 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(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) 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: connection closed vhci_hcd: stop threads vhci_hcd: release socket usb_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 hub_port_init+0x748/0x2fa0 drivers/usb/core/hub.c:4749 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 vhci_hcd.0: pdev(1) rhport(0) sockfd(3) process_one_work+0x864/0x1570 kernel/workqueue.c:2155 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 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/1:4:9345 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 D26296 9345 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 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(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: connection closed 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 vhci_hcd: stop threads vhci_hcd: release socket usb_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 vhci_hcd: disconnect device usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) hub_port_init+0x748/0x2fa0 drivers/usb/core/hub.c:4749 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) 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 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 vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-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/0:5:9513 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/0:5 D25336 9513 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 vhci_hcd vhci_hcd.0: port 1 already used 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(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads usb_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 hub_port_init+0x748/0x2fa0 drivers/usb/core/hub.c:4749 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: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device 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(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads worker_thread+0x64c/0x1130 kernel/workqueue.c:2298 kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd: release socket ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 vhci_hcd: disconnect device INFO: task kworker/0:6:9643 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/0:6 D27160 9643 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 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 vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) 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: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd: release socket vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: stop threads usb_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 hub_port_init+0x748/0x2fa0 drivers/usb/core/hub.c:4749 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(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads 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 kthread+0x33f/0x460 kernel/kthread.c:259 vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd: release socket vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 vhci_hcd: disconnect device Showing all locks held in the system: vhci_hcd: stop threads 5 locks held by kworker/u4:0/7: vhci_hcd: release socket 1 lock held by khungtaskd/1567: vhci_hcd: disconnect device #0: 000000007e5cce3d (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440 5 locks held by kworker/0:2/3683: #0: 00000000d77ae61f ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 000000006df56997 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 000000000ca9f88b (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 000000000ca9f88b (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000a2e173ac (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000a2e173ac (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000a2e173ac (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000a2e173ac (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000a2e173ac (&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: 0000000080746ffc (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 1 lock held by in:imklog/7794: #0: 00000000bfe33463 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 3 locks held by syz-executor.3/8125: #0: 000000007dfaa4cc (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline] #0: 000000007dfaa4cc (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 000000007e5cce3d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 000000007e5cce3d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) #2: 000000007e5cce3d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #2: 000000007e5cce3d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) 5 locks held by kworker/0:3/8374: vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: connection closed vhci_hcd: stop threads #0: 00000000d77ae61f ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd: release socket #1: 000000004ac4bddf ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) #2: 0000000019f7c62a (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 0000000019f7c62a (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: stop threads #3: 00000000bf4b5d66 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000bf4b5d66 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000bf4b5d66 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000bf4b5d66 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000bf4b5d66 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 vhci_hcd: release socket #4: 00000000a69ad999 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 5 locks held by kworker/1:3/9343: #0: 00000000d77ae61f ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 000000009eb60eaf ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd: disconnect device #2: 00000000018904c4 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000018904c4 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 00000000feb409e8 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000feb409e8 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000feb409e8 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000feb409e8 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000feb409e8 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 00000000b3fdc036 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 5 locks held by kworker/1:4/9345: #0: 00000000d77ae61f ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 00000000bd71131c ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 0000000017b71cd3 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 0000000017b71cd3 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 000000003fb597a4 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 000000003fb597a4 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 000000003fb597a4 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 000000003fb597a4 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 000000003fb597a4 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 000000006ab85680 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 vhci_hcd vhci_hcd.0: port 1 already used 5 locks held by kworker/0:5/9513: #0: 00000000d77ae61f ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 #1: 00000000f0de9830 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 #2: 00000000c66a3d24 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000c66a3d24 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 vhci_hcd vhci_hcd.0: port 1 already used #3: 00000000ba6676a9 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000ba6676a9 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000ba6676a9 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000ba6676a9 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000ba6676a9 (&port_dev->status_lock){+.+.}, at: hub_event+0x1ce3/0x43f0 drivers/usb/core/hub.c:5391 #4: 00000000392f67a7 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 3 locks held by kworker/u4:7/9610: 5 locks held by kworker/0:6/9643: vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) #0: 00000000d77ae61f ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2126 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) #1: 00000000d1c474ec ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2130 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: port 1 already used #2: 00000000bc7c17d2 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000bc7c17d2 (&dev->mutex){....}, at: hub_event+0x11b/0x43f0 drivers/usb/core/hub.c:5337 #3: 0000000074d370e3 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 0000000074d370e3 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 0000000074d370e3 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 0000000074d370e3 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 0000000074d370e3 (&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: 00000000a5cf4091 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b6/0x2fa0 drivers/usb/core/hub.c:4528 4 locks held by syz-executor.5/20280: vhci_hcd: connection closed 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(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) vhci_hcd: connection closed #0: vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: disconnect device 000000005d03cdc1 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline] 000000005d03cdc1 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 vhci_hcd: connection closed vhci_hcd: stop threads #1: 000000007e5cce3d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 000000007e5cce3d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 vhci_hcd: release socket #2: 000000007e5cce3d (rcu_read_lock){....}, at: is_bpf_text_address+0x0/0x1b0 kernel/bpf/core.c:532 vhci_hcd: disconnect device vhci_hcd: connection closed #3: vhci_hcd: stop threads 00000000aa86e9d1 (kn->count#73){.+.+}, at: kernfs_fop_write+0x223/0x470 fs/kernfs/file.c:308 vhci_hcd: release socket ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1567 Comm: khungtaskd Not tainted 4.19.154-syzkaller #0 vhci_hcd: disconnect device 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 vhci_hcd: stop threads 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 vhci_hcd: release socket 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: vhci_hcd: disconnect device NMI backtrace for cpu 0 CPU: 0 PID: 9610 Comm: kworker/u4:7 Not tainted 4.19.154-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: usbip_event event_handler RIP: 0010:mark_lock+0xa6/0x1160 kernel/locking/lockdep.c:3105 Code: 01 00 00 48 81 eb 50 01 00 00 48 81 c3 40 65 d5 8c 48 8d 7b 30 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 <0f> 85 04 0d 00 00 4c 89 e0 48 23 43 30 0f 84 ad 00 00 00 b8 01 00 RSP: 0018:ffff8880ba0079f0 EFLAGS: 00000046 RAX: dffffc0000000000 RBX: ffffffff8cd5a6e0 RCX: 1ffff11013d70dac RDX: 1ffffffff19ab4e2 RSI: ffff88809eb86d40 RDI: ffffffff8cd5a710 RBP: ffff88809eb86d40 R08: 0000000000000033 R09: 0000000000000003 R10: ffff88809eb86d40 R11: 0000000000000000 R12: 0000000000000010 R13: ffff88809eb86440 R14: ffff88809eb86d60 R15: 0000000000000004 FS: 0000000000000000(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f35fe3d3000 CR3: 00000000ab460000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: mark_irqflags kernel/locking/lockdep.c:3009 [inline] __lock_acquire+0xdc4/0x3ff0 kernel/locking/lockdep.c:3372 lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3907 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:144 spin_lock include/linux/spinlock.h:329 [inline] __queue_work+0x359/0x1100 kernel/workqueue.c:1419 call_timer_fn+0x177/0x700 kernel/time/timer.c:1338 expire_timers+0x97/0x4e0 kernel/time/timer.c:1371 __run_timers kernel/time/timer.c:1703 [inline] run_timer_softirq+0x21c/0x670 kernel/time/timer.c:1716 __do_softirq+0x26c/0x9a0 kernel/softirq.c:292 invoke_softirq kernel/softirq.c:372 [inline] irq_exit+0x215/0x260 kernel/softirq.c:412 exiting_irq arch/x86/include/asm/apic.h:544 [inline] smp_apic_timer_interrupt+0x136/0x550 arch/x86/kernel/apic/apic.c:1094 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline] RIP: 0010:console_trylock_spinning kernel/printk/printk.c:1724 [inline] RIP: 0010:vprintk_emit+0x668/0x740 kernel/printk/printk.c:1964 Code: 48 b8 00 00 00 00 00 fc ff df 41 80 7c 05 00 00 0f 85 d9 00 00 00 48 83 3d 0c cc a1 08 00 74 37 e8 fd 1a 15 00 48 89 df 57 9d <0f> 1f 44 00 00 e9 82 fe ff ff e8 e9 1a 15 00 0f 0b e8 e2 1a 15 00 RSP: 0018:ffff88809bfe7bb8 EFLAGS: 00000293 ORIG_RAX: ffffffffffffff13 RAX: ffff88809eb86440 RBX: 0000000000000293 RCX: 1ffff11013d70da2 RDX: 0000000000000000 RSI: ffffffff814fb403 RDI: 0000000000000293 RBP: ffff88809bfe7bf8 R08: ffffffff8cd1ea78 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 000000000000001b R13: 1ffffffff13e3001 R14: 0000000000000200 R15: 0000000000000001 vprintk_func+0x79/0x17e kernel/printk/printk_safe.c:397 printk+0xba/0xed kernel/printk/printk.c:2040 event_handler+0x1f0/0x4f0 drivers/usb/usbip/usbip_event.c:78 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