INFO: task kworker/1:3:8624 blocked for more than 140 seconds. Not tainted 4.14.258-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:3 D27552 8624 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 hub_port_connect drivers/usb/core/hub.c:4921 [inline] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] port_event drivers/usb/core/hub.c:5250 [inline] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task kworker/1:8:24297 blocked for more than 140 seconds. Not tainted 4.14.258-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:8 D28872 24297 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 hub_port_connect drivers/usb/core/hub.c:4921 [inline] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] port_event drivers/usb/core/hub.c:5250 [inline] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Showing all locks held in the system: 5 locks held by kworker/0:0/3: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 5 locks held by kworker/1:0/18: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 5 locks held by kworker/0:1/24: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 1 lock held by khungtaskd/1533: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 5 locks held by kworker/1:2/3450: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 5 locks held by kworker/1:3/8624: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 5 locks held by kworker/1:4/9207: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 5 locks held by kworker/1:5/24654: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 5 locks held by kworker/1:8/24297: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1082 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3f60 drivers/usb/core/hub.c:5276 #3: (&port_dev->status_lock){+.+.}, at: [] usb_lock_port drivers/usb/core/hub.c:2936 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4920 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #3: (&port_dev->status_lock){+.+.}, at: [] hub_event+0x15c0/0x3f60 drivers/usb/core/hub.c:5330 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect drivers/usb/core/hub.c:4921 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_connect_change drivers/usb/core/hub.c:5144 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] port_event drivers/usb/core/hub.c:5250 [inline] #4: (hcd->address0_mutex){+.+.}, at: [] hub_event+0x15ea/0x3f60 drivers/usb/core/hub.c:5330 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1533 Comm: khungtaskd Not tainted 4.14.258-syzkaller #0 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+0x1b2/0x281 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x180 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline] watchdog+0x5b9/0xb40 kernel/hung_task.c:274 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 27360 Comm: syz-executor.0 Not tainted 4.14.258-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff88805f86a600 task.stack: ffff88807ac68000 RIP: 0010:preempt_count_add+0x64/0x170 kernel/sched/core.c:3110 RSP: 0018:ffff88807ac6fab0 EFLAGS: 00000282 RAX: dffffc0000000000 RBX: 0000000000000003 RCX: 0000000000000000 RDX: 1ffffffff17d93a8 RSI: ffffffff87ccf200 RDI: 0000000000000001 RBP: 0000000000000001 R08: 0000000000000222 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 R13: ffffffff88f093d8 R14: 0000000000000391 R15: ffff88808019b498 FS: 0000555555c5e400(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fa294b4f601 CR3: 000000007d418000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: rcu_lockdep_current_cpu_online kernel/rcu/tree.c:1185 [inline] rcu_lockdep_current_cpu_online+0x30/0x140 kernel/rcu/tree.c:1177 rcu_read_lock_sched_held+0xd3/0x1d0 kernel/rcu/update.c:113 trace_block_touch_buffer include/trace/events/block.h:45 [inline] touch_buffer fs/buffer.c:65 [inline] __find_get_block+0x993/0xc40 fs/buffer.c:1361 sb_find_get_block include/linux/buffer_head.h:346 [inline] recently_deleted fs/ext4/ialloc.c:692 [inline] find_inode_bit+0x1fb/0x4e0 fs/ext4/ialloc.c:732 __ext4_new_inode+0x1379/0x4eb0 fs/ext4/ialloc.c:924 ext4_symlink+0x2cf/0xaa0 fs/ext4/namei.c:3174 vfs_symlink+0x3ce/0x620 fs/namei.c:4156 SYSC_symlinkat fs/namei.c:4183 [inline] SyS_symlinkat+0x1dc/0x240 fs/namei.c:4163 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7eff40f8e7a7 RSP: 002b:00007ffc0a4f3548 EFLAGS: 00000206 ORIG_RAX: 0000000000000058 RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007eff40f8e7a7 RDX: 00007ffc0a4f3623 RSI: 00007eff40fe90a6 RDI: 00007ffc0a4f3610 RBP: 0000000000000001 R08: 0000000000000000 R09: 00007ffc0a4f33e0 R10: 00007ffc0a4f3297 R11: 0000000000000206 R12: 0000000000000001 R13: 0000000000000000 R14: 0000000000000001 R15: 00007ffc0a4f3610 Code: b3 0a 85 c9 75 07 65 8b 05 ca 5d c9 7e 48 b8 00 00 00 00 00 fc ff df 48 89 da 83 e3 07 48 c1 ea 03 83 c3 03 65 01 2d ac 5d c9 7e <0f> b6 04 02 38 c3 7c 08 84 c0 0f 85 c1 00 00 00 8b 15 86 7b b3 ---------------- Code disassembly (best guess): 0: b3 0a mov $0xa,%bl 2: 85 c9 test %ecx,%ecx 4: 75 07 jne 0xd 6: 65 8b 05 ca 5d c9 7e mov %gs:0x7ec95dca(%rip),%eax # 0x7ec95dd7 d: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 14: fc ff df 17: 48 89 da mov %rbx,%rdx 1a: 83 e3 07 and $0x7,%ebx 1d: 48 c1 ea 03 shr $0x3,%rdx 21: 83 c3 03 add $0x3,%ebx 24: 65 01 2d ac 5d c9 7e add %ebp,%gs:0x7ec95dac(%rip) # 0x7ec95dd7 * 2b: 0f b6 04 02 movzbl (%rdx,%rax,1),%eax <-- trapping instruction 2f: 38 c3 cmp %al,%bl 31: 7c 08 jl 0x3b 33: 84 c0 test %al,%al 35: 0f 85 c1 00 00 00 jne 0xfc 3b: 8b .byte 0x8b 3c: 15 .byte 0x15 3d: 86 7b b3 xchg %bh,-0x4d(%rbx)