INFO: task kworker/0:2:3723 blocked for more than 140 seconds. Not tainted 4.19.149-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:2 D26472 3723 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x8e5/0x22e0 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+0x24f/0x2b0 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+0x8b3/0x2ea0 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+0x1981/0x3fc0 drivers/usb/core/hub.c:5391 process_one_work+0x796/0x14e0 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/0:4:7687 blocked for more than 140 seconds. Not tainted 4.19.149-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:4 D26376 7687 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x8e5/0x22e0 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 audit: type=1800 audit(1601717029.140:16): pid=19513 uid=0 auid=0 ses=4 subj=system_u:system_r:kernel_t:s0 op=collect_data cause=failed(directio) comm="syz-executor.4" name="file0" dev="sda1" ino=17017 res=0 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x24f/0x2b0 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+0x8b3/0x2ea0 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+0x1981/0x3fc0 drivers/usb/core/hub.c:5391 process_one_work+0x796/0x14e0 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 Showing all locks held in the system: 2 locks held by kworker/1:1/25: #0: 00000000dfe32dc1 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1823 [inline] #0: 00000000dfe32dc1 (&rq->lock){-.-.}, at: __schedule+0x257/0x22e0 kernel/sched/core.c:3455 #1: 0000000044cb48a2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 0000000044cb48a2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x8b0 kernel/sched/fair.c:857 3 locks held by kworker/u4:2/88: 1 lock held by khungtaskd/1094: #0: 0000000044cb48a2 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x291 kernel/locking/lockdep.c:4440 5 locks held by kworker/u4:4/2365: 5 locks held by kworker/0:2/3723: #0: 000000001f586335 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x692/0x14e0 kernel/workqueue.c:2126 #1: 000000001896eeb1 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x6c8/0x14e0 kernel/workqueue.c:2130 #2: 00000000cac653a4 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000cac653a4 (&dev->mutex){....}, at: hub_event+0x10f/0x3fc0 drivers/usb/core/hub.c:5337 #3: 0000000059a6b2ae (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 0000000059a6b2ae (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 0000000059a6b2ae (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 0000000059a6b2ae (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 0000000059a6b2ae (&port_dev->status_lock){+.+.}, at: hub_event+0x196c/0x3fc0 drivers/usb/core/hub.c:5391 #4: 0000000037868464 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b5/0x2ea0 drivers/usb/core/hub.c:4528 1 lock held by systemd-udevd/3724: 1 lock held by in:imklog/6221: #0: 00000000546c78ca (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xe3/0x100 fs/file.c:767 5 locks held by kworker/0:4/7687: #0: 000000001f586335 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x692/0x14e0 kernel/workqueue.c:2126 #1: 0000000007573ae9 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x6c8/0x14e0 kernel/workqueue.c:2130 #2: 00000000d3aa4617 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline] #2: 00000000d3aa4617 (&dev->mutex){....}, at: hub_event+0x10f/0x3fc0 drivers/usb/core/hub.c:5337 #3: 00000000ffe35808 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2977 [inline] #3: 00000000ffe35808 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5019 [inline] #3: 00000000ffe35808 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5203 [inline] #3: 00000000ffe35808 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5311 [inline] #3: 00000000ffe35808 (&port_dev->status_lock){+.+.}, at: hub_event+0x196c/0x3fc0 drivers/usb/core/hub.c:5391 #4: 000000000ae92859 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b5/0x2ea0 drivers/usb/core/hub.c:4528 3 locks held by kworker/u4:6/7901: 2 locks held by syz-executor.1/19609: #0: 000000008ba24353 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1823 [inline] #0: 000000008ba24353 (&rq->lock){-.-.}, at: __schedule+0x257/0x22e0 kernel/sched/core.c:3455 #1: 0000000044cb48a2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 0000000044cb48a2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x8b0 kernel/sched/fair.c:857 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1094 Comm: khungtaskd Not tainted 4.19.149-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x22c/0x33e lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x18/0x3d 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+0xa62/0x1020 kernel/hung_task.c:287 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 3724 Comm: systemd-udevd Not tainted 4.19.149-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:debug_lockdep_rcu_enabled kernel/rcu/update.c:254 [inline] RIP: 0010:debug_lockdep_rcu_enabled+0x92/0xe0 kernel/rcu/update.c:251 Code: 08 85 c9 74 45 65 48 8b 1c 25 40 ee 01 00 48 8d bb 84 08 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 0f b6 14 02 <48> 89 f8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 32 8b 93 84 08 00 RSP: 0018:ffff88809017fad8 EFLAGS: 00000a07 RAX: dffffc0000000000 RBX: ffff888090176340 RCX: 0000000000000001 RDX: 0000000000000000 RSI: 0000000000000002 RDI: ffff888090176bc4 RBP: ffff88809017fb28 R08: 0000000000000001 R09: 0000000000000000 R10: 0000000000000005 R11: 0000000000000000 R12: 0000000000000008 R13: ffffffff8d3fd840 R14: 0000000000000000 R15: 0000000000000000 FS: 00007fe2d4d908c0(0000) GS:ffff8880ae200000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00000000016a5374 CR3: 00000000902ab000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600 Call Trace: rcu_read_unlock include/linux/rcupdate.h:677 [inline] avc_has_perm_noaudit security/selinux/avc.c:1159 [inline] avc_has_perm+0x18c/0x450 security/selinux/avc.c:1185 inode_has_perm+0x175/0x210 security/selinux/hooks.c:1852 path_has_perm security/selinux/hooks.c:1885 [inline] selinux_inode_getattr+0x176/0x200 security/selinux/hooks.c:3272 security_inode_getattr+0xcf/0x140 security/security.c:724 vfs_getattr fs/stat.c:113 [inline] vfs_statx+0x131/0x210 fs/stat.c:189 vfs_lstat include/linux/fs.h:3138 [inline] __do_sys_newlstat fs/stat.c:350 [inline] __se_sys_newlstat+0x96/0x120 fs/stat.c:344 do_syscall_64+0xf9/0x670 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7fe2d3c03335 Code: 69 db 2b 00 64 c7 00 16 00 00 00 b8 ff ff ff ff c3 0f 1f 40 00 83 ff 01 48 89 f0 77 30 48 89 c7 48 89 d6 b8 06 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 03 f3 c3 90 48 8b 15 31 db 2b 00 f7 d8 64 89 RSP: 002b:00007fff195f2838 EFLAGS: 00000246 ORIG_RAX: 0000000000000006 RAX: ffffffffffffffda RBX: 0000557f9805b290 RCX: 00007fe2d3c03335 RDX: 00007fff195f2870 RSI: 00007fff195f2870 RDI: 0000557f9805a290 RBP: 00007fff195f2930 R08: 00007fe2d3ec21b8 R09: 0000000000001010 R10: 00007fe2d3ec1b58 R11: 0000000000000246 R12: 0000557f9805a290 R13: 0000557f9805a29c R14: 0000557f980678e5 R15: 0000557f980678ec