INFO: task syz.4.1168:12191 blocked for more than 143 seconds. Tainted: G L syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz.4.1168 state:D stack:27288 pid:12191 tgid:12186 ppid:9996 task_flags:0x400040 flags:0x00080002 Call Trace: context_switch kernel/sched/core.c:5388 [inline] __schedule+0x1821/0x5740 kernel/sched/core.c:7189 __schedule_loop kernel/sched/core.c:7268 [inline] schedule+0x164/0x360 kernel/sched/core.c:7283 schedule_preempt_disabled+0x13/0x30 kernel/sched/core.c:7340 __mutex_lock_common kernel/locking/mutex.c:726 [inline] __mutex_lock+0x7f7/0x1550 kernel/locking/mutex.c:820 blk_debugfs_lock_nomemsave block/blk.h:758 [inline] blk_trace_startstop+0x8f/0x610 kernel/trace/blktrace.c:903 blk_trace_ioctl+0x314/0x920 kernel/trace/blktrace.c:949 blkdev_common_ioctl+0x13a7/0x3240 block/ioctl.c:717 blkdev_ioctl+0x528/0x740 block/ioctl.c:791 vfs_ioctl fs/ioctl.c:51 [inline] __do_sys_ioctl fs/ioctl.c:597 [inline] __se_sys_ioctl+0xfc/0x170 fs/ioctl.c:583 do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline] do_syscall_64+0x15f/0xf80 arch/x86/entry/syscall_64.c:94 entry_SYSCALL_64_after_hwframe+0x77/0x7f RIP: 0033:0x7fd6cc79ce59 RSP: 002b:00007fd6cd64a028 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007fd6cca16090 RCX: 00007fd6cc79ce59 RDX: 0000000000000000 RSI: 0000000000001274 RDI: 0000000000000008 RBP: 00007fd6cc832d6f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fd6cca16128 R14: 00007fd6cca16090 R15: 00007ffda4bd15a8 INFO: task syz.4.1168:12193 blocked for more than 146 seconds. Tainted: G L syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz.4.1168 state:D stack:28088 pid:12193 tgid:12186 ppid:9996 task_flags:0x480040 flags:0x00080002 Call Trace: context_switch kernel/sched/core.c:5388 [inline] __schedule+0x1821/0x5740 kernel/sched/core.c:7189 __schedule_loop kernel/sched/core.c:7268 [inline] schedule+0x164/0x360 kernel/sched/core.c:7283 schedule_preempt_disabled+0x13/0x30 kernel/sched/core.c:7340 __mutex_lock_common kernel/locking/mutex.c:726 [inline] __mutex_lock+0x7f7/0x1550 kernel/locking/mutex.c:820 relay_open+0x3b8/0x920 kernel/relay.c:515 blk_trace_setup_prepare+0x425/0x5a0 kernel/trace/blktrace.c:715 blk_trace_setup+0x2f8/0x520 kernel/trace/blktrace.c:789 blk_trace_ioctl+0x37f/0x920 kernel/trace/blktrace.c:937 blkdev_ioctl+0x4c1/0x740 block/ioctl.c:786 vfs_ioctl fs/ioctl.c:51 [inline] __do_sys_ioctl fs/ioctl.c:597 [inline] __se_sys_ioctl+0xfc/0x170 fs/ioctl.c:583 do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline] do_syscall_64+0x15f/0xf80 arch/x86/entry/syscall_64.c:94 entry_SYSCALL_64_after_hwframe+0x77/0x7f RIP: 0033:0x7fd6cc79ce59 RSP: 002b:00007fd6cd629028 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007fd6cca16180 RCX: 00007fd6cc79ce59 RDX: 0000200000000000 RSI: 00000000c0481273 RDI: 0000000000000008 RBP: 00007fd6cc832d6f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fd6cca16218 R14: 00007fd6cca16180 R15: 00007ffda4bd15a8 Showing all locks held in the system: 1 lock held by kthreadd/2: 5 locks held by kworker/1:0/24: #0: ffff88801fa95940 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3289 [inline] #0: ffff88801fa95940 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_scheduled_works+0xa35/0x1860 kernel/workqueue.c:3397 #1: ffffc900001e7c40 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3290 [inline] #1: ffffc900001e7c40 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_scheduled_works+0xa70/0x1860 kernel/workqueue.c:3397 #2: ffff88802958e1d8 (&dev->mutex){....}-{4:4}, at: device_lock include/linux/device.h:1040 [inline] #2: ffff88802958e1d8 (&dev->mutex){....}-{4:4}, at: hub_event+0x17f/0x4f30 drivers/usb/core/hub.c:5899 #3: ffff8880322a41d8 (&dev->mutex){....}-{4:4}, at: device_lock include/linux/device.h:1040 [inline] #3: ffff8880322a41d8 (&dev->mutex){....}-{4:4}, at: usb_disconnect+0xf8/0x990 drivers/usb/core/hub.c:2336 #4: ffff8880347201a0 (&dev->mutex){....}-{4:4}, at: device_lock include/linux/device.h:1040 [inline] #4: ffff8880347201a0 (&dev->mutex){....}-{4:4}, at: __device_driver_lock drivers/base/dd.c:1174 [inline] #4: ffff8880347201a0 (&dev->mutex){....}-{4:4}, at: device_release_driver_internal+0xb6/0x870 drivers/base/dd.c:1372 1 lock held by khungtaskd/31: #0: ffffffff8e95cca0 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire include/linux/rcupdate.h:300 [inline] #0: ffffffff8e95cca0 (rcu_read_lock){....}-{1:3}, at: rcu_read_lock include/linux/rcupdate.h:838 [inline] #0: ffffffff8e95cca0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2e/0x180 kernel/locking/lockdep.c:6775 2 locks held by kworker/u8:6/148: #0: ffff88813fe7c940 ((wq_completion)events_unbound#2){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3289 [inline] #0: ffff88813fe7c940 ((wq_completion)events_unbound#2){+.+.}-{0:0}, at: process_scheduled_works+0xa35/0x1860 kernel/workqueue.c:3397 #1: ffffc90002e07c40 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3290 [inline] #1: ffffc90002e07c40 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_scheduled_works+0xa70/0x1860 kernel/workqueue.c:3397 4 locks held by kworker/1:3/989: #0: ffff88813fe43140 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3289 [inline] #0: ffff88813fe43140 ((wq_completion)events){+.+.}-{0:0}, at: process_scheduled_works+0xa35/0x1860 kernel/workqueue.c:3397 #1: ffffc90004f17c40 ((work_completion)(&dev->request_module_wk)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3290 [inline] #1: ffffc90004f17c40 ((work_completion)(&dev->request_module_wk)){+.+.}-{0:0}, at: process_scheduled_works+0xa70/0x1860 kernel/workqueue.c:3397 #2: ffffffff8f9fbd20 (em28xx_devlist_mutex){+.+.}-{4:4}, at: em28xx_init_extension+0x32/0x1c0 drivers/media/usb/em28xx/em28xx-core.c:1244 #3: ffff888195d35cf0 (&dev->lock#5){+.+.}-{4:4}, at: em28xx_v4l2_init+0xd7/0x2eb0 drivers/media/usb/em28xx/em28xx-video.c:2705 1 lock held by jbd2/sda1-8/4950: #0: ffff8880b863aea0 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0xb6/0x150 kernel/sched/core.c:660 2 locks held by syslogd/4970: 2 locks held by klogd/4977: 2 locks held by udevd/4988: 4 locks held by crond/5359: 2 locks held by getty/5375: #0: ffff8880356ba0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243 #1: ffffc9000322b2e8 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x45c/0x13a0 drivers/tty/n_tty.c:2211 2 locks held by syz-executor/5607: 2 locks held by syz-executor/5620: 2 locks held by kworker/u8:9/5880: #0: ffff88813fe7c940 ((wq_completion)events_unbound#2){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3289 [inline] #0: ffff88813fe7c940 ((wq_completion)events_unbound#2){+.+.}-{0:0}, at: process_scheduled_works+0xa35/0x1860 kernel/workqueue.c:3397 #1: ffffc900045b7c40 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_one_work kernel/workqueue.c:3290 [inline] #1: ffffc900045b7c40 ((work_completion)(&sub_info->work)){+.+.}-{0:0}, at: process_scheduled_works+0xa70/0x1860 kernel/workqueue.c:3397 4 locks held by udevd/7443: #0: ffff88802fe44998 (&p->lock){+.+.}-{4:4}, at: seq_read_iter+0xb7/0xe10 fs/seq_file.c:183 #1: ffff88806aeae080 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_seq_start+0x5c/0x420 fs/kernfs/file.c:172 #2: ffff8880309a72d8 (kn->active#32){++++}-{0:0}, at: kernfs_get_active_of fs/kernfs/file.c:80 [inline] #2: ffff8880309a72d8 (kn->active#32){++++}-{0:0}, at: kernfs_seq_start+0xb2/0x420 fs/kernfs/file.c:173 #3: ffff8880322a41d8 (&dev->mutex){....}-{4:4}, at: device_lock_interruptible include/linux/device.h:1045 [inline] #3: ffff8880322a41d8 (&dev->mutex){....}-{4:4}, at: product_show+0x26/0xa0 drivers/usb/core/sysfs.c:141 2 locks held by syz-executor/9722: 3 locks held by kworker/u8:25/9723: #0: ffff8880b863aea0 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0xb6/0x150 kernel/sched/core.c:660 #1: ffff8880b8624588 (psi_seq){-.-.}-{0:0}, at: psi_task_switch+0x53/0x880 kernel/sched/psi.c:933 #2: ffffffff9a493588 (&____s->seqcount#2){----}-{0:0}, at: ktime_get+0x45/0x220 kernel/time/timekeeping.c:965 3 locks held by syz.7.869/10708: 1 lock held by syz.4.1168/12191: #0: ffff888025b85c78 (&q->debugfs_mutex){+.+.}-{4:4}, at: blk_debugfs_lock_nomemsave block/blk.h:758 [inline] #0: ffff888025b85c78 (&q->debugfs_mutex){+.+.}-{4:4}, at: blk_trace_startstop+0x8f/0x610 kernel/trace/blktrace.c:903 2 locks held by syz.4.1168/12193: #0: ffff888025b85c78 (&q->debugfs_mutex){+.+.}-{4:4}, at: blk_debugfs_lock_nomemsave block/blk.h:758 [inline] #0: ffff888025b85c78 (&q->debugfs_mutex){+.+.}-{4:4}, at: blk_debugfs_lock block/blk.h:770 [inline] #0: ffff888025b85c78 (&q->debugfs_mutex){+.+.}-{4:4}, at: blk_trace_setup+0x2d3/0x520 kernel/trace/blktrace.c:788 #1: ffffffff8e9bbe40 (relay_channels_mutex){+.+.}-{4:4}, at: relay_open+0x3b8/0x920 kernel/relay.c:515 3 locks held by dhcpcd-run-hook/13678: 3 locks held by syz.5.1474/13737: 2 locks held by syz.5.1474/13739: #0: ffff8880319340d8 (&type->s_umount_key#93/1){+.+.}-{4:4}, at: alloc_super+0x28c/0xab0 fs/super.c:345 #1: ffffffff8e7fad60 (wq_pool_mutex){+.+.}-{4:4}, at: apply_wqattrs_lock kernel/workqueue.c:5317 [inline] #1: ffffffff8e7fad60 (wq_pool_mutex){+.+.}-{4:4}, at: __alloc_workqueue+0x9f8/0x1f50 kernel/workqueue.c:5880 2 locks held by syz.0.1472/13740: 3 locks held by modprobe/13752: 1 lock held by kworker/u8:6/13758: =============================================