INFO: task kworker/1:15:16437 blocked for more than 140 seconds. Not tainted 4.19.211-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:15 D25872 16437 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 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 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'. usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 netlink: 500 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 300 bytes leftover after parsing attributes in process `syz-executor.1'. kauditd_printk_skb: 69 callbacks suppressed audit: type=1800 audit(1674095762.968:470): pid=6093 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="bus" dev="sda1" ino=15835 res=0 attempt to access beyond end of device usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 loop2: rw=0, want=201326594, limit=1024 Buffer I/O error on dev loop2, logical block 100663296, async page read hfsplus: unable to mark blocks free: error -5 hfsplus: can't free extent hub_port_init+0x12dc/0x3120 drivers/usb/core/hub.c:4704 hub_port_connect drivers/usb/core/hub.c:5070 [inline] hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] port_event drivers/usb/core/hub.c:5361 [inline] hub_event+0x1ccd/0x44a0 drivers/usb/core/hub.c:5441 process_one_work+0x864/0x1570 kernel/workqueue.c:2153 worker_thread+0x64c/0x1130 kernel/workqueue.c:2296 kthread+0x33f/0x460 kernel/kthread.c:259 audit: type=1804 audit(1674095763.398:471): pid=6184 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir1204758617/syzkaller.LkAm95/1066/bus" dev="sda1" ino=15813 res=1 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Showing all locks held in the system: 4 locks held by kworker/u4:1/23: audit: type=1800 audit(1674095763.398:472): pid=6184 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15813 res=0 1 lock held by khungtaskd/1570: #0: 0000000013e6ea08 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441 1 lock held by in:imklog/7803: #0: 00000000e94d667c (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 3 locks held by kworker/u4:7/9469: 5 locks held by kworker/1:15/16437: #0: 000000004afebee6 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 00000000a39f65d0 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 audit: type=1800 audit(1674095764.138:473): pid=6176 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="bus" dev="sda1" ino=15774 res=0 #2: 000000006fe46af2 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline] #2: 000000006fe46af2 (&dev->mutex){....}, at: hub_event+0x11e/0x44a0 drivers/usb/core/hub.c:5387 #3: 0000000052e6fff4 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2983 [inline] #3: 0000000052e6fff4 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5069 [inline] #3: 0000000052e6fff4 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] #3: 0000000052e6fff4 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5361 [inline] #3: 0000000052e6fff4 (&port_dev->status_lock){+.+.}, at: hub_event+0x1cb8/0x44a0 drivers/usb/core/hub.c:5441 #4: 000000002c50fc7f (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b9/0x3120 drivers/usb/core/hub.c:4578 3 locks held by syz-executor.4/6190: #0: 000000008193aa98 (&sb->s_type->i_mutex_key#13){+.+.}, at: inode_lock include/linux/fs.h:748 [inline] #0: 000000008193aa98 (&sb->s_type->i_mutex_key#13){+.+.}, at: __sock_release+0x86/0x2a0 net/socket.c:598 #1: 00000000f703e46a (sk_lock-AF_CAN){+.+.}, at: lock_sock include/net/sock.h:1512 [inline] #1: 00000000f703e46a (sk_lock-AF_CAN){+.+.}, at: bcm_release+0x1ff/0x950 net/can/bcm.c:1558 #2: 00000000e82709ab (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:297 [inline] #2: 00000000e82709ab (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x4dc/0x6f0 kernel/rcu/tree_exp.h:667 2 locks held by syz-executor.2/6196: 1 lock held by syz-executor.1/6217: audit: type=1804 audit(1674095764.468:474): pid=6234 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir1204758617/syzkaller.LkAm95/1067/bus" dev="sda1" ino=15780 res=1 #0: 00000000c07e4e92 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000c07e4e92 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 3 locks held by syz-executor.3/6241: 3 locks held by syz-executor.0/6238: ============================================= audit: type=1800 audit(1674095764.468:475): pid=6234 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15780 res=0 audit: type=1800 audit(1674095764.608:476): pid=6239 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="bus" dev="sda1" ino=15819 res=0 NMI backtrace for cpu 0 audit: type=1800 audit(1674095764.608:477): pid=6239 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="bus" dev="sda1" ino=15819 res=0 CPU: 0 PID: 1570 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 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+0x991/0xe60 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 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 4694 Comm: systemd-journal Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023 RIP: 0010:__read_once_size include/linux/compiler.h:263 [inline] RIP: 0010:check_kcov_mode kernel/kcov.c:69 [inline] RIP: 0010:write_comp_data+0x1d/0x70 kernel/kcov.c:122 Code: d1 48 89 11 c3 0f 1f 84 00 00 00 00 00 49 89 f1 49 89 fa 65 48 8b 34 25 c0 df 01 00 65 8b 05 7a 59 9f 7e a9 00 01 1f 00 75 4f <8b> 86 60 13 00 00 83 f8 03 75 44 48 8b 86 68 13 00 00 8b b6 64 13 RSP: 0018:ffff8880a0f77d98 EFLAGS: 00000046 RAX: 0000000080000000 RBX: 0000000000000000 RCX: ffffffff8167a97c RDX: 0000000000000000 RSI: ffff8880a0f6e600 RDI: 0000000000000005 RBP: ffff888098b11480 R08: 0000000000400000 R09: 0000000000000000 R10: 0000000000000005 R11: 00000000009f958f R12: ffff88813bff04c0 R13: ffffffff834394da R14: 0000000000000000 R15: 0000000000000000 FS: 00007f1b9f8488c0(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 0000001b2cc26000 CR3: 00000000a0c9f000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: trace_hardirqs_on+0x3c/0x210 kernel/trace/trace_preemptirq.c:24 kfree+0x110/0x210 mm/slab.c:3823 aa_free_file_ctx security/apparmor/include/file.h:76 [inline] apparmor_file_free_security+0x9a/0xd0 security/apparmor/lsm.c:448 security_file_free+0x3e/0x70 security/security.c:885 file_free fs/file_table.c:54 [inline] __fput+0x42a/0x890 fs/file_table.c:294 task_work_run+0x148/0x1c0 kernel/task_work.c:113 tracehook_notify_resume include/linux/tracehook.h:193 [inline] exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline] syscall_return_slowpath arch/x86/entry/common.c:271 [inline] do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7f1b9edd7840 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:00007ffdab88fff8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: fffffffffffffffe RBX: 00007ffdab890300 RCX: 00007f1b9edd7840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 000055b899f78750 RBP: 000000000000000d R08: 000000000000ffc0 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 000055b899f6b040 R14: 00007ffdab8902c0 R15: 000055b899f78400