INFO: task kworker/1:4:4694 blocked for more than 143 seconds.
Not tainted 5.16.0-rc2-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/1:4 state:D stack:24464 pid: 4694 ppid: 2 flags:0x00004000
Workqueue: usb_hub_wq hub_event
Call Trace:
context_switch kernel/sched/core.c:4972 [inline]
__schedule+0xb72/0x1460 kernel/sched/core.c:6253
schedule+0x12b/0x1f0 kernel/sched/core.c:6326
usb_kill_urb+0x1d5/0x310 drivers/usb/core/urb.c:720
usb_start_wait_urb+0x265/0x530 drivers/usb/core/message.c:64
usb_internal_control_msg drivers/usb/core/message.c:102 [inline]
usb_control_msg+0x2a5/0x4b0 drivers/usb/core/message.c:153
usb_get_descriptor+0xb5/0x3c0 drivers/usb/core/message.c:793
usb_get_device_descriptor+0x8a/0xe0 drivers/usb/core/message.c:1071
hub_port_init+0x106f/0x2e60 drivers/usb/core/hub.c:4922
hub_port_connect+0xc30/0x2910 drivers/usb/core/hub.c:5282
hub_port_connect_change+0x5f9/0xc20 drivers/usb/core/hub.c:5497
port_event+0xb67/0x1220 drivers/usb/core/hub.c:5643
hub_event+0x4ed/0xe40 drivers/usb/core/hub.c:5725
process_one_work+0x853/0x1140 kernel/workqueue.c:2298
worker_thread+0xac1/0x1320 kernel/workqueue.c:2445
kthread+0x468/0x490 kernel/kthread.c:327
ret_from_fork+0x1f/0x30
Showing all locks held in the system:
1 lock held by khungtaskd/26:
#0: ffffffff8cb1dd40 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
1 lock held by in:imklog/6197:
#0: ffff88807eb6d4f0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x24e/0x2f0 fs/file.c:990
3 locks held by kworker/u4:6/6334:
#0: ffff8880b9b395d8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x25/0x110 kernel/sched/core.c:478
#1: ffff8880b9b27888 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x4c1/0x780 kernel/sched/psi.c:880
#2: ffff8880b9b3d1e0 (&c->lock){..-.}-{2:2}, at: timekeeping_get_delta kernel/time/timekeeping.c:252 [inline]
#2: ffff8880b9b3d1e0 (&c->lock){..-.}-{2:2}, at: timekeeping_get_ns kernel/time/timekeeping.c:386 [inline]
#2: ffff8880b9b3d1e0 (&c->lock){..-.}-{2:2}, at: ktime_get+0x95/0x2b0 kernel/time/timekeeping.c:829
2 locks held by kworker/1:3/4647:
#0: ffff888011466538 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
#1: ffffc9000d0d7d20 ((work_completion)(&rew.rew_work)){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2273
5 locks held by kworker/1:4/4694:
#0: ffff8880127ebd38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
#1: ffffc90010157d20 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2273
#2: ffff88801ea71220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:760 [inline]
#2: ffff88801ea71220 (&dev->mutex){....}-{3:3}, at: hub_event+0x1b2/0xe40 drivers/usb/core/hub.c:5671
#3: ffff88801ea4b5c0 (&port_dev->status_lock){+.+.}-{3:3}, at: usb_lock_port drivers/usb/core/hub.c:3086 [inline]
#3: ffff88801ea4b5c0 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_port_connect+0x4f4/0x2910 drivers/usb/core/hub.c:5249
#4: ffff88801e922068 (hcd->address0_mutex){+.+.}-{3:3}, at: hub_port_connect+0x51d/0x2910 drivers/usb/core/hub.c:5250
3 locks held by kworker/1:1/24228:
#0: ffff888011464d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x7ca/0x1140
#1: ffffc90015fd7d20 ((work_completion)(&pwq->unbound_release_work)){+.+.}-{0:0}, at: process_one_work+0x808/0x1140 kernel/workqueue.c:2273
#2: ffffffff8cb225a8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:290 [inline]
#2: ffffffff8cb225a8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x277/0x740 kernel/rcu/tree_exp.h:836
1 lock held by systemd-udevd/30118:
#0: ffff88801b09a918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_get_by_dev+0xf7/0xba0 block/bdev.c:817
2 locks held by syz-executor.5/30378:
#0: ffff88801b09a918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_put+0xf8/0x7a0 block/bdev.c:915
#1: ffff88801af7e360 (&lo->lo_mutex){+.+.}-{3:3}, at: __loop_clr_fd+0x6d/0x9b0 drivers/block/loop.c:1106
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 26 Comm: khungtaskd Not tainted 5.16.0-rc2-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0x1dc/0x2d8 lib/dump_stack.c:106
nmi_cpu_backtrace+0x45f/0x490 lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x16a/0x280 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
watchdog+0xc82/0xcd0 kernel/hung_task.c:295
kthread+0x468/0x490 kernel/kthread.c:327
ret_from_fork+0x1f/0x30
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 2957 Comm: systemd-journal Not tainted 5.16.0-rc2-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:lock_acquire+0x193/0x4d0 kernel/locking/lockdep.c:5637
Code: 9c 7e 45 31 c9 41 f7 c4 00 02 00 00 41 0f 94 c1 48 8b 7c 24 30 44 89 f6 8b 54 24 1c 48 8b 4c 24 38 4c 8b 44 24 28 6a 00 6a 00 75 10 ff 74 24 38 e8 c1 04 00 00 48 83 c4 20 48 c7 c7 40 18 6f
RSP: 0018:ffffc90001edf9f0 EFLAGS: 00000046
RAX: 0000000000000001 RBX: ffffc90001edfa80 RCX: 0000000000000000
RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff90edd5d0
RBP: ffffc90001edfb48 R08: 0000000000000001 R09: 0000000000000001
R10: fffffbfff1bfd1ae R11: 0000000000000000 R12: 0000000000000046
R13: 1ffff920003dbf48 R14: 0000000000000000 R15: dffffc0000000000
FS: 00007f69e234c8c0(0000) GS:ffff8880b9b00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f69dfae6000 CR3: 000000001d153000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
__raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
_raw_spin_lock_irqsave+0xd1/0x120 kernel/locking/spinlock.c:162
__debug_check_no_obj_freed lib/debugobjects.c:980 [inline]
debug_check_no_obj_freed+0xa9/0x650 lib/debugobjects.c:1023
slab_free_hook mm/slub.c:1698 [inline]
slab_free_freelist_hook+0xcf/0x1a0 mm/slub.c:1749
slab_free mm/slub.c:3513 [inline]
kmem_cache_free+0xa4/0x180 mm/slub.c:3530
do_sys_openat2+0x431/0x500 fs/open.c:1221
do_sys_open fs/open.c:1228 [inline]
__do_sys_open fs/open.c:1236 [inline]
__se_sys_open fs/open.c:1232 [inline]
__x64_sys_open+0x221/0x270 fs/open.c:1232
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f69e18db840
Code: 73 01 c3 48 8b 0d 68 77 20 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 83 3d 89 bb 20 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 1e f6 ff ff 48 89 04 24
RSP: 002b:00007ffc1a85ee98 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007ffc1a85f1a0 RCX: 00007f69e18db840
RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000561306f98060
RBP: 000000000000000d R08: 000000000000c0c1 R09: 00000000ffffffff
R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000561306f94040 R14: 00007ffc1a85f160 R15: 0000561306f9fbe0
----------------
Code disassembly (best guess):
0: 9c pushfq
1: 7e 45 jle 0x48
3: 31 c9 xor %ecx,%ecx
5: 41 f7 c4 00 02 00 00 test $0x200,%r12d
c: 41 0f 94 c1 sete %r9b
10: 48 8b 7c 24 30 mov 0x30(%rsp),%rdi
15: 44 89 f6 mov %r14d,%esi
18: 8b 54 24 1c mov 0x1c(%rsp),%edx
1c: 48 8b 4c 24 38 mov 0x38(%rsp),%rcx
21: 4c 8b 44 24 28 mov 0x28(%rsp),%r8
26: 6a 00 pushq $0x0
28: 6a 00 pushq $0x0
* 2a: ff 75 10 pushq 0x10(%rbp) <-- trapping instruction
2d: ff 74 24 38 pushq 0x38(%rsp)
31: e8 c1 04 00 00 callq 0x4f7
36: 48 83 c4 20 add $0x20,%rsp
3a: 48 rex.W
3b: c7 .byte 0xc7
3c: c7 .byte 0xc7
3d: 40 rex
3e: 18 .byte 0x18
3f: 6f outsl %ds:(%rsi),(%dx)