bisecting fixing commit since 5b7a52cd2eef952cee8a72512ef370bcdef46636 building syzkaller on a7aac492ebbc53e5c7bc4b5bbaf55f428c54093f testing commit 5b7a52cd2eef952cee8a72512ef370bcdef46636 with gcc (GCC) 8.4.1 20210217 kernel signature: 1d5f4d2a7d12afec17713146a4b1b47d1fff0572d6bc4713113cd1f6a69e7663 run #0: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #1: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #2: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #3: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #4: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #5: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #6: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #7: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #8: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #9: crashed: BUG: unable to handle kernel run #10: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #11: crashed: BUG: unable to handle kernel run #12: crashed: BUG: unable to handle kernel run #13: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #14: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #15: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #16: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #17: crashed: INFO: task hung in usb_get_descriptor run #18: crashed: INFO: task hung in hub_port_init run #19: crashed: INFO: task hung in hub_port_init testing current HEAD 29c52025152bab4c557d8174da58f1a4c8e70438 testing commit 29c52025152bab4c557d8174da58f1a4c8e70438 with gcc (GCC) 8.4.1 20210217 kernel signature: 02d6672a430bab1897d367c514d9acafe00f3c632945c7a8ab34a06d4ccfc1b3 run #0: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #1: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #2: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #3: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #4: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #5: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #6: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #7: crashed: BUG: unable to handle kernel NULL pointer dereference in vhci_shutdown_connection run #8: crashed: INFO: task hung in hub_port_init run #9: crashed: INFO: task hung in hub_port_init revisions tested: 2, total time: 28m57.94098774s (build: 14m21.944088403s, test: 13m42.682129146s) the crash still happens on HEAD commit msg: Linux 4.14.221 crash: INFO: task hung in hub_port_init vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(0) rhport(1) sockfd(7) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) INFO: task kworker/0:0:3 blocked for more than 140 seconds. Not tainted 4.14.221-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:0 D27488 3 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x519/0x1490 kernel/locking/mutex.c:893 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 kthread+0x338/0x400 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task kworker/1:0:18 blocked for more than 140 seconds. Not tainted 4.14.221-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:0 D25232 18 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 usb_kill_urb drivers/usb/core/urb.c:691 [inline] usb_kill_urb+0x193/0x210 drivers/usb/core/urb.c:683 usb_start_wait_urb+0x1f5/0x4d0 drivers/usb/core/message.c:62 usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x31b/0x550 drivers/usb/core/message.c:151 hub_port_init+0x6ab/0x26d0 drivers/usb/core/hub.c:4555 hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 kthread+0x338/0x400 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task kworker/0:1:24 blocked for more than 140 seconds. Not tainted 4.14.221-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:1 D26336 24 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 usb_kill_urb drivers/usb/core/urb.c:691 [inline] usb_kill_urb+0x193/0x210 drivers/usb/core/urb.c:683 usb_start_wait_urb+0x1f5/0x4d0 drivers/usb/core/message.c:62 usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x31b/0x550 drivers/usb/core/message.c:151 usb_get_descriptor+0xba/0x140 drivers/usb/core/message.c:652 usb_get_device_descriptor+0x6b/0xb0 drivers/usb/core/message.c:924 hub_port_init+0x83e/0x26d0 drivers/usb/core/hub.c:4647 hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 kthread+0x338/0x400 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task kworker/0:2:3470 blocked for more than 140 seconds. vhci_hcd vhci_hcd.0: port 1 already used Not tainted 4.14.221-syzkaller #0 vhci_hcd: stop threads vhci_hcd: connection closed vhci_hcd: release socket vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: connection closed kworker/0:2 D vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd: stop threads 26368 3470 2 0x80000000 vhci_hcd: release socket Workqueue: usb_hub_wq hub_event vhci_hcd: disconnect device vhci_hcd: stop threads Call Trace: vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(5) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) schedule+0x7f/0x1b0 kernel/sched/core.c:3428 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(3) rhport(1) sockfd(8) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) usb_kill_urb drivers/usb/core/urb.c:691 [inline] usb_kill_urb+0x193/0x210 drivers/usb/core/urb.c:683 vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed usb_start_wait_urb+0x1f5/0x4d0 drivers/usb/core/message.c:62 vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(2) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(6) usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x31b/0x550 drivers/usb/core/message.c:151 vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(3) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(1) rhport(1) sockfd(5) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(6) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 0 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: pdev(4) rhport(1) sockfd(5) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 0 already used vhci_hcd: stop threads hub_port_init+0x6ab/0x26d0 drivers/usb/core/hub.c:4555 hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 vhci_hcd: release socket vhci_hcd: disconnect device process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd vhci_hcd.0: pdev(5) rhport(1) sockfd(8) vhci_hcd: connection closed vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 vhci_hcd: stop threads kthread+0x338/0x400 kernel/kthread.c:232 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 vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 vhci_hcd: stop threads vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(6) vhci_hcd: release socket vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) INFO: task kworker/1:2:4052 blocked for more than 140 seconds. vhci_hcd: disconnect device Not tainted 4.14.221-syzkaller #0 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed 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: pdev(0) rhport(1) sockfd(5) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) kworker/1:2 D vhci_hcd: stop threads 27112 4052 2 0x80000000 vhci_hcd: release socket Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 schedule+0x7f/0x1b0 kernel/sched/core.c:3428 usb_kill_urb drivers/usb/core/urb.c:691 [inline] usb_kill_urb+0x193/0x210 drivers/usb/core/urb.c:683 usb_start_wait_urb+0x1f5/0x4d0 drivers/usb/core/message.c:62 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: stop threads vhci_hcd: release socket usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x31b/0x550 drivers/usb/core/message.c:151 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) hub_port_init+0x6ab/0x26d0 drivers/usb/core/hub.c:4555 vhci_hcd: release socket hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(5) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd: release socket process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(3) rhport(1) sockfd(7) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: release socket process_scheduled_works kernel/workqueue.c:2176 [inline] worker_thread+0x641/0xee0 kernel/workqueue.c:2252 vhci_hcd: disconnect device kthread+0x338/0x400 kernel/kthread.c:232 vhci_hcd: stop threads vhci_hcd: connection closed vhci_hcd: connection closed ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 vhci_hcd: connection closed INFO: task kworker/1:3:7218 blocked for more than 140 seconds. vhci_hcd: release socket Not tainted 4.14.221-syzkaller #0 vhci_hcd: disconnect device "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: stop threads kworker/1:3 D vhci_hcd: release socket 26368 7218 2 0x80000000 vhci_hcd: disconnect device Workqueue: usb_hub_wq hub_event vhci_hcd: stop threads Call Trace: vhci_hcd: release socket context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: disconnect device 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: pdev(4) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 2 already used schedule+0x7f/0x1b0 kernel/sched/core.c:3428 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) usb_kill_urb drivers/usb/core/urb.c:691 [inline] usb_kill_urb+0x193/0x210 drivers/usb/core/urb.c:683 vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: pdev(4) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: pdev(1) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(3) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) usb_start_wait_urb+0x1f5/0x4d0 drivers/usb/core/message.c:62 vhci_hcd vhci_hcd.0: pdev(5) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: pdev(2) rhport(1) sockfd(8) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: release socket usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x31b/0x550 drivers/usb/core/message.c:151 vhci_hcd vhci_hcd.0: port 0 already used vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 0 already used hub_port_init+0x6ab/0x26d0 drivers/usb/core/hub.c:4555 vhci_hcd vhci_hcd.0: pdev(0) rhport(1) sockfd(7) 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 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd: release socket hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: stop threads vhci_hcd: release socket process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 vhci_hcd: connection closed worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 vhci_hcd: disconnect device kthread+0x338/0x400 kernel/kthread.c:232 vhci_hcd vhci_hcd.0: port 0 already used vhci_hcd: stop threads ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task kworker/0:4:7501 blocked for more than 140 seconds. vhci_hcd vhci_hcd.0: port 0 already used Not tainted 4.14.221-syzkaller #0 vhci_hcd: release socket "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: disconnect device kworker/0:4 D27408 7501 2 0x80000000 vhci_hcd: stop threads Workqueue: usb_hub_wq hub_event vhci_hcd vhci_hcd.0: port 1 already used Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 vhci_hcd: release socket vhci_hcd: disconnect device schedule+0x7f/0x1b0 kernel/sched/core.c:3428 vhci_hcd: stop threads schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3486 vhci_hcd: release socket __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x519/0x1490 kernel/locking/mutex.c:893 vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 vhci_hcd: stop threads vhci_hcd: release socket hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 vhci_hcd: disconnect device vhci_hcd: stop threads hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 vhci_hcd: release socket process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 vhci_hcd: disconnect device kthread+0x338/0x400 kernel/kthread.c:232 vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 vhci_hcd: stop threads INFO: task kworker/1:4:7678 blocked for more than 140 seconds. vhci_hcd: release socket Not tainted 4.14.221-syzkaller #0 vhci_hcd: disconnect device "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 1 already used kworker/1:4 D26336 7678 2 0x80000000 vhci_hcd vhci_hcd.0: port 2 already used Workqueue: usb_hub_wq hub_event vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: release socket Call Trace: vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: stop threads vhci_hcd: release socket context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used schedule+0x7f/0x1b0 kernel/sched/core.c:3428 vhci_hcd vhci_hcd.0: pdev(4) 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: stop threads usb_kill_urb drivers/usb/core/urb.c:691 [inline] usb_kill_urb+0x193/0x210 drivers/usb/core/urb.c:683 vhci_hcd: release socket vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd: disconnect device 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 vhci_hcd.0: port 1 already used vhci_hcd: stop threads usb_start_wait_urb+0x1f5/0x4d0 drivers/usb/core/message.c:62 vhci_hcd vhci_hcd.0: pdev(4) rhport(1) sockfd(5) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 0 already used vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(1) rhport(1) sockfd(5) vhci_hcd vhci_hcd.0: pdev(2) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(5) rhport(1) sockfd(9) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: connection closed usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x31b/0x550 drivers/usb/core/message.c:151 vhci_hcd: release socket 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 vhci_hcd.0: pdev(3) rhport(0) sockfd(3) vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: disconnect device hub_port_init+0x6ab/0x26d0 drivers/usb/core/hub.c:4555 hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 kthread+0x338/0x400 kernel/kthread.c:232 vhci_hcd: stop threads vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(5) vhci_hcd vhci_hcd.0: pdev(3) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd: connection closed vhci_hcd vhci_hcd.0: pdev(0) rhport(1) sockfd(8) ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed INFO: task kworker/0:5:9168 blocked for more than 140 seconds. vhci_hcd: connection closed vhci_hcd vhci_hcd.0: pdev(3) rhport(1) sockfd(9) vhci_hcd: release socket vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: connection closed vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: connection closed vhci_hcd: release socket vhci_hcd: connection closed vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: stop threads Not tainted 4.14.221-syzkaller #0 vhci_hcd: release socket "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd: disconnect device kworker/0:5 D26368 9168 2 0x80000000 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed Workqueue: usb_hub_wq hub_event vhci_hcd: stop threads vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: release socket vhci_hcd: disconnect device Call Trace: vhci_hcd: stop threads context_switch kernel/sched/core.c:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 vhci_hcd: release socket schedule+0x7f/0x1b0 kernel/sched/core.c:3428 vhci_hcd: disconnect device schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3486 vhci_hcd: stop threads __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x519/0x1490 kernel/locking/mutex.c:893 vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 vhci_hcd: stop threads vhci_hcd: release socket hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 vhci_hcd: disconnect device vhci_hcd: stop threads vhci_hcd: release socket hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 vhci_hcd: disconnect device process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 vhci_hcd: stop threads vhci_hcd: release socket worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 vhci_hcd: disconnect device kthread+0x338/0x400 kernel/kthread.c:232 vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 vhci_hcd: stop threads INFO: task kworker/1:5:9270 blocked for more than 140 seconds. vhci_hcd: release socket Not tainted 4.14.221-syzkaller #0 vhci_hcd vhci_hcd.0: port 1 already used "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: disconnect device kworker/1:5 D28088 9270 2 0x80000000 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:2808 [inline] __schedule+0x824/0x1ee0 kernel/sched/core.c:3384 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: stop threads schedule+0x7f/0x1b0 kernel/sched/core.c:3428 vhci_hcd: release socket schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3486 vhci_hcd: disconnect device __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x519/0x1490 kernel/locking/mutex.c:893 vhci_hcd vhci_hcd.0: pdev(4) rhport(0) sockfd(5) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: pdev(4) rhport(1) sockfd(7) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(3) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(3) vhci_hcd: connection closed vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: pdev(1) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd vhci_hcd.0: pdev(1) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: port 3 already used vhci_hcd: release socket vhci_hcd vhci_hcd.0: pdev(5) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: pdev(5) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: port 2 already used vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 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 vhci_hcd.0: pdev(0) rhport(0) sockfd(3) vhci_hcd: disconnect device vhci_hcd vhci_hcd.0: devid(0) speed(5) speed_str(super-speed) hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed 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 vhci_hcd.0: pdev(3) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd vhci_hcd.0: pdev(0) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) vhci_hcd vhci_hcd.0: pdev(2) rhport(0) sockfd(6) vhci_hcd vhci_hcd.0: port 1 already used vhci_hcd vhci_hcd.0: devid(0) speed(4) speed_str(wireless) vhci_hcd: stop threads vhci_hcd vhci_hcd.0: pdev(2) rhport(1) sockfd(9) vhci_hcd vhci_hcd.0: devid(0) speed(1) speed_str(low-speed) hub_port_connect drivers/usb/core/hub.c:4905 [inline] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] port_event drivers/usb/core/hub.c:5194 [inline] hub_event+0xf3c/0x3740 drivers/usb/core/hub.c:5274 vhci_hcd: release socket process_one_work+0x74f/0x1620 kernel/workqueue.c:2116 worker_thread+0xcc/0xee0 kernel/workqueue.c:2250 vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads kthread+0x338/0x400 kernel/kthread.c:232 vhci_hcd: release socket vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: disconnect device vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: connection closed vhci_hcd: stop threads vhci_hcd: release socket vhci_hcd: disconnect device ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 vhci_hcd: stop threads Showing all locks held in the system: vhci_hcd: release socket vhci_hcd: disconnect device 5 locks held by kworker/0:0/3: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/1:0/18: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/0:1/24: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 1 lock held by khungtaskd/1529: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x79/0x218 kernel/locking/lockdep.c:4548 5 locks held by kworker/0:2/3470: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/1:2/4052: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/1:3/7218: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/0:4/7501: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/1:4/7678: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/0:5/9168: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/1:5/9270: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/0:6/9309: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 5 locks held by kworker/0:8/9336: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x681/0x1620 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6b4/0x1620 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x125/0x3740 drivers/usb/core/hub.c:5220 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2934 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4904 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5088 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5194 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0xf21/0x3740 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x145/0x26d0 drivers/usb/core/hub.c:4429 ============================================= NMI backtrace for cpu 0 vhci_hcd vhci_hcd.0: port 1 already used CPU: 0 PID: 1529 Comm: khungtaskd Not tainted 4.14.221-syzkaller #0 vhci_hcd vhci_hcd.0: port 2 already used Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x14b/0x1e7 lib/dump_stack.c:58 nmi_cpu_backtrace.cold.0+0x3c/0x78 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0xf6/0x120 lib/nmi_backtrace.c:62 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline] watchdog+0x51b/0xa70 kernel/hung_task.c:274 kthread+0x338/0x400 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: 31508 Comm: syz-executor.4 Not tainted 4.14.221-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff8881d706a480 task.stack: ffff8881c49d8000 RIP: 0010:check_preemption_disabled+0x1/0x230 lib/smp_processor_id.c:13 RSP: 0018:ffff8881f6507d38 EFLAGS: 00000202 RAX: 0000000000000001 RBX: ffffffff88f7db80 RCX: ffff8881d706ad08 RDX: ffffffff81377470 RSI: ffffffff87cc6c40 RDI: ffffffff87cc6c80 RBP: ffff8881f6507d48 R08: ffff8881d706adf0 R09: 0000000000000000 R10: 00000000000000c8 R11: ffff8881d706a480 R12: 0000000000000000 R13: ffff8881ed0483c0 R14: 00000000ffffffff R15: 0000000000000000 FS: 00007f8c36042700(0000) GS:ffff8881f6500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fe422eb9000 CR3: 00000001ecc13002 CR4: 00000000001606e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: rcu_dynticks_curr_cpu_in_eqs kernel/rcu/tree.c:360 [inline] rcu_is_watching+0x15/0xb0 kernel/rcu/tree.c:1130 rcu_read_lock include/linux/rcupdate.h:630 [inline] __atomic_notifier_call_chain+0xde/0x110 kernel/notifier.c:182 atomic_notifier_call_chain+0x11/0x20 kernel/notifier.c:193 profile_handoff_task+0x15/0x20 kernel/profile.c:148 __put_task_struct+0x225/0x420 kernel/fork.c:419 put_task_struct include/linux/sched/task.h:98 [inline] delayed_put_task_struct+0x1a7/0x260 kernel/exit.c:180 __rcu_reclaim kernel/rcu/rcu.h:195 [inline] rcu_do_batch kernel/rcu/tree.c:2699 [inline] invoke_rcu_callbacks kernel/rcu/tree.c:2962 [inline] __rcu_process_callbacks kernel/rcu/tree.c:2929 [inline] rcu_process_callbacks+0x7a5/0x11e0 kernel/rcu/tree.c:2946 __do_softirq+0x247/0x9a2 kernel/softirq.c:288 invoke_softirq kernel/softirq.c:368 [inline] irq_exit+0x15f/0x1a0 kernel/softirq.c:409 exiting_irq arch/x86/include/asm/apic.h:638 [inline] smp_apic_timer_interrupt+0x149/0x5d0 arch/x86/kernel/apic/apic.c:1106 apic_timer_interrupt+0x9a/0xa0 arch/x86/entry/entry_64.S:793 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline] RIP: 0010:console_trylock_spinning kernel/printk/printk.c:1685 [inline] RIP: 0010:vprintk_emit+0x42c/0x4e0 kernel/printk/printk.c:1922 RSP: 0018:ffff8881c49df630 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff10 RAX: dffffc0000000000 RBX: 0000000000000286 RCX: 1ffff1103ae0d5b4 RDX: 0000000000000000 RSI: ffff8881d706ad80 RDI: 0000000000000286 RBP: ffff8881c49df680 R08: ffff8881d706ada0 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff11e1098 R13: 1ffffffff11e109a R14: 0000000000000000 R15: 1ffffffff11e1099 dev_vprintk_emit+0x1cd/0x3b0 drivers/base/core.c:3004 dev_printk_emit+0x88/0xa0 drivers/base/core.c:3015 __dev_printk+0xd7/0x2e0 drivers/base/core.c:3027 dev_err+0xbd/0xe0 drivers/base/core.c:3070 store_attach+0x706/0x7f0 drivers/usb/usbip/vhci_sysfs.c:382 dev_attr_store+0x37/0x70 drivers/base/core.c:738 sysfs_kf_write+0xfd/0x150 fs/sysfs/file.c:141 kernfs_fop_write+0x255/0x410 fs/kernfs/file.c:316 __vfs_write+0xdb/0x840 fs/read_write.c:480 vfs_write+0x150/0x4f0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0x100/0x250 fs/read_write.c:582 do_syscall_64+0x1c7/0x5b0 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x418a2f RSP: 002b:00007f8c36041b90 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 0000000000000008 RCX: 0000000000418a2f RDX: 0000000000000008 RSI: 00007f8c36041be0 RDI: 0000000000000007 RBP: 0000000000000007 R08: 0000000000000000 R09: 00007f8c36041b30 R10: 0000000000000000 R11: 0000000000000293 R12: 00000000004be3f0 R13: 00007f8c36041be0 R14: 0000000000000000 R15: 0000000000022000 Code: 70 ff ff ff e9 be f4 ff ff 4c 89 8d 70 ff ff ff e8 45 02 62 fe 4c 8b 8d 70 ff ff ff e9 5c f4 ff ff 90 90 90 90 90 90 90 90 90 55 <48> 89 e5 41 57 41 56 41 55 41 54 53 48 83 ec 08 65 8b 1d 68 ab