INFO: task kworker/1:1:28838 blocked for more than 140 seconds. Not tainted 4.14.215-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:1 D28416 28838 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 usb_kill_urb.part.0+0x125/0x190 drivers/usb/core/urb.c:691 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:686 usb_start_wait_urb+0x209/0x440 drivers/usb/core/message.c:62 usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x302/0x450 drivers/usb/core/message.c:151 hub_port_init+0xfdc/0x2970 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+0x1923/0x3dc0 drivers/usb/core/hub.c:5274 process_one_work+0x793/0x14a0 kernel/workqueue.c:2116 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task kworker/1:0:3629 blocked for more than 140 seconds. Not tainted 4.14.215-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:0 D28576 3629 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 usb_kill_urb.part.0+0x125/0x190 drivers/usb/core/urb.c:691 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:686 usb_start_wait_urb+0x209/0x440 drivers/usb/core/message.c:62 usb_internal_control_msg drivers/usb/core/message.c:100 [inline] usb_control_msg+0x302/0x450 drivers/usb/core/message.c:151 hub_port_init+0xfdc/0x2970 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+0x1923/0x3dc0 drivers/usb/core/hub.c:5274 process_one_work+0x793/0x14a0 kernel/workqueue.c:2116 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 INFO: task syz-executor.1:9801 blocked for more than 140 seconds. Not tainted 4.14.215-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D29776 9801 14328 0x00000004 Call Trace: context_switch kernel/sched/core.c:2808 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3384 schedule+0x8d/0x1b0 kernel/sched/core.c:3428 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3486 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 device_lock include/linux/device.h:1081 [inline] usbdev_open+0x142/0x780 drivers/usb/core/devio.c:1023 chrdev_open+0x23c/0x6d0 fs/char_dev.c:423 do_dentry_open+0x44b/0xec0 fs/open.c:777 vfs_open+0x105/0x220 fs/open.c:888 do_last fs/namei.c:3428 [inline] path_openat+0x628/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x417d11 RSP: 002b:00007fc1b0189810 EFLAGS: 00000293 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 0000000000417d11 RDX: 0000000000000000 RSI: 0000000000000001 RDI: 00007fc1b0189840 RBP: 000000000119bfc0 R08: 000000000000000f R09: 0000000000000000 R10: ffffffffffffffff R11: 0000000000000293 R12: 000000000119bf8c R13: 00007ffced685caf R14: 00007fc1b018a9c0 R15: 000000000119bf8c Showing all locks held in the system: 1 lock held by khungtaskd/1523: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 1 lock held by in:imklog/7641: #0: (&f->f_pos_lock){+.+.}, at: [] __fdget_pos+0x1fb/0x2b0 fs/file.c:769 5 locks held by kworker/1:1/28838: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3dc0 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+0x190e/0x3dc0 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x15b/0x2970 drivers/usb/core/hub.c:4429 5 locks held by kworker/1:0/3629: #0: ("usb_hub_wq"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2087 #1: ((&hub->events)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2091 #2: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #2: (&dev->mutex){....}, at: [] hub_event+0x108/0x3dc0 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+0x190e/0x3dc0 drivers/usb/core/hub.c:5274 #4: (hcd->address0_mutex){+.+.}, at: [] hub_port_init+0x15b/0x2970 drivers/usb/core/hub.c:4429 1 lock held by syz-executor.1/9801: #0: (&dev->mutex){....}, at: [] device_lock include/linux/device.h:1081 [inline] #0: (&dev->mutex){....}, at: [] usbdev_open+0x142/0x780 drivers/usb/core/devio.c:1023 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1523 Comm: khungtaskd Not tainted 4.14.215-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/0x283 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x17f 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: 4610 Comm: systemd-journal Not tainted 4.14.215-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 task: ffff8880a1d74480 task.stack: ffff8880a1d78000 RIP: 0010:__orc_find+0x0/0xf0 arch/x86/kernel/unwind_orc.c:32 RSP: 0018:ffff8880a1d7f738 EFLAGS: 00000206 RAX: 0000000000000003 RBX: 1ffff110143afeef RCX: ffffffff81811ffa RDX: 0000000000000014 RSI: ffffffff8a7208e4 RDI: ffffffff8a18f864 RBP: 0000000000000001 R08: ffffffff8a7208e4 R09: ffffffff8a72095c R10: 000000000001efca R11: 0000000000066071 R12: ffff8880a1d7f835 R13: ffff8880a1d7f838 R14: ffff8880a1d7f850 R15: ffff8880a1d7f800 FS: 00007f7376d898c0(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f7373b7b020 CR3: 00000000a37f6000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600 Call Trace: orc_find arch/x86/kernel/unwind_orc.c:118 [inline] unwind_next_frame+0x59a/0x17d0 arch/x86/kernel/unwind_orc.c:348 __save_stack_trace+0x90/0x160 arch/x86/kernel/stacktrace.c:44 save_stack mm/kasan/kasan.c:447 [inline] set_track mm/kasan/kasan.c:459 [inline] kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:551 kmem_cache_alloc_trace+0x131/0x3d0 mm/slab.c:3618 kmalloc include/linux/slab.h:488 [inline] kzalloc include/linux/slab.h:661 [inline] aa_alloc_file_ctx security/apparmor/include/file.h:60 [inline] apparmor_file_alloc_security+0x129/0x800 security/apparmor/lsm.c:431 security_file_alloc+0x66/0xa0 security/security.c:874 get_empty_filp+0x15c/0x3e0 fs/file_table.c:129 path_openat+0x84/0x2970 fs/namei.c:3545 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f7376319840 RSP: 002b:00007ffd105137e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffd10513af0 RCX: 00007f7376319840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000558064b71670 RBP: 000000000000000d R08: 0000000000000000 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000558064b64040 R14: 00007ffd10513ab0 R15: 0000558064b6a5a0 Code: 24 08 e8 b4 0c 5d 00 48 8b 74 24 20 48 8b 54 24 18 8b 44 24 14 44 8b 44 24 08 e9 d8 fe ff ff 90 90 90 90 90 90 90 90 90 90 90 90 <41> 57 89 d0 41 56 41 55 41 54 4c 8d 64 87 fc 55 53 48 83 ec 10