XFS (loop1): Mounting V4 Filesystem XFS (loop5): Mounting V4 Filesystem XFS (loop1): Ending clean mount INFO: task kworker/1:0:22996 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. XFS (loop3): Mounting V4 Filesystem kauditd_printk_skb: 12 callbacks suppressed audit: type=1804 audit(1675358057.721:1817): pid=29362 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir1474256377/syzkaller.CSwmlt/6916/file0/bus" dev="loop1" ino=41 res=1 kworker/1:0 D28040 22996 2 0x80000000 XFS (loop5): Ending clean mount audit: type=1804 audit(1675358057.821:1818): pid=29435 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.1" name="/root/syzkaller-testdir1474256377/syzkaller.CSwmlt/6916/file0/bus" dev="loop1" ino=41 res=1 XFS (loop4): Mounting V4 Filesystem XFS (loop3): Ending clean mount audit: type=1804 audit(1675358057.891:1819): pid=29366 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.5" name="/root/syzkaller-testdir1619045036/syzkaller.tMRX9o/6327/file0/bus" dev="loop5" ino=41 res=1 Workqueue: usb_hub_wq hub_event XFS (loop4): Ending clean mount Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 audit: type=1804 audit(1675358057.991:1820): pid=29375 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.3" name="/root/syzkaller-testdir110303779/syzkaller.6eHlYZ/6124/file0/bus" dev="loop3" ino=41 res=1 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 audit: type=1804 audit(1675358058.001:1821): pid=29442 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.5" name="/root/syzkaller-testdir1619045036/syzkaller.tMRX9o/6327/file0/bus" dev="loop5" ino=41 res=1 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 audit: type=1804 audit(1675358058.021:1822): pid=29378 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir2090296110/syzkaller.nVpPAT/6000/file0/bus" dev="loop4" ino=41 res=1 audit: type=1804 audit(1675358058.041:1823): pid=29378 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.4" name="/root/syzkaller-testdir2090296110/syzkaller.nVpPAT/6000/file0/bus" dev="loop4" ino=41 res=1 audit: type=1804 audit(1675358058.111:1824): pid=29444 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.3" name="/root/syzkaller-testdir110303779/syzkaller.6eHlYZ/6124/file0/bus" dev="loop3" ino=41 res=1 usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 XFS (loop2): Mounting V4 Filesystem 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 XFS (loop2): Ending clean mount 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(1675358058.401:1825): pid=29389 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.2" name="/root/syzkaller-testdir1401117002/syzkaller.faxxDC/6665/file0/bus" dev="loop2" ino=41 res=1 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 XFS (loop3): Unmounting Filesystem XFS (loop4): Unmounting Filesystem XFS (loop1): Unmounting Filesystem XFS (loop5): Unmounting Filesystem INFO: task kworker/1:3:22096 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:3 D27712 22096 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 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x252/0x4c0 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+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 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 XFS (loop2): Unmounting Filesystem Showing all locks held in the system: 1 lock held by khungtaskd/1571: #0: 000000002094bd71 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441 1 lock held by in:imklog/7823: #0: 00000000e8743a7f (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 2 locks held by agetty/8044: #0: 000000005d8ed7b2 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272 #1: 000000006bd03492 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1950 drivers/tty/n_tty.c:2154 2 locks held by kworker/u4:4/1413: 5 locks held by kworker/1:0/22996: #0: 00000000e7e97274 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 00000000dd3b42d8 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 #2: 0000000096b5c0cb (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline] #2: 0000000096b5c0cb (&dev->mutex){....}, at: hub_event+0x11e/0x44a0 drivers/usb/core/hub.c:5387 #3: 000000004eec3fe8 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2983 [inline] #3: 000000004eec3fe8 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5069 [inline] #3: 000000004eec3fe8 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] #3: 000000004eec3fe8 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5361 [inline] #3: 000000004eec3fe8 (&port_dev->status_lock){+.+.}, at: hub_event+0x1cb8/0x44a0 drivers/usb/core/hub.c:5441 #4: 0000000014e721ea (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b9/0x3120 drivers/usb/core/hub.c:4578 5 locks held by kworker/1:3/22096: #0: 00000000e7e97274 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 0000000049588fac ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 #2: 00000000ad2e825c (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline] #2: 00000000ad2e825c (&dev->mutex){....}, at: hub_event+0x11e/0x44a0 drivers/usb/core/hub.c:5387 #3: 000000003b3b0c91 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2983 [inline] #3: 000000003b3b0c91 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5069 [inline] #3: 000000003b3b0c91 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] #3: 000000003b3b0c91 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5361 [inline] #3: 000000003b3b0c91 (&port_dev->status_lock){+.+.}, at: hub_event+0x1cb8/0x44a0 drivers/usb/core/hub.c:5441 #4: 00000000419cfc19 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b9/0x3120 drivers/usb/core/hub.c:4578 2 locks held by syz-executor.5/29475: 3 locks held by syz-executor.1/29487: 2 locks held by syz-executor.3/29488: ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1571 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 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 29501 Comm: syz-executor.2 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023 RIP: 0033:0x7ff8a9b151a9 Code: 74 35 0f 1f 80 00 00 00 00 48 39 c1 77 1c 49 8b 75 00 49 89 c1 49 29 c9 46 0f b6 0c 0e 45 84 c9 74 08 44 88 0c 06 49 8b 45 10 <48> 83 c0 01 49 89 45 10 83 ea 01 73 d2 41 81 ff 00 01 00 00 0f 85 RSP: 002b:00007ff8a80e9600 EFLAGS: 00000246 RAX: 0000000001d7e78b RBX: 00007ff8a80e9660 RCX: 0000000000000001 RDX: 000000000000006a RSI: 00007ff89fcca000 RDI: 00007ff8a80e9700 RBP: 0000000000000102 R08: 0000000000000001 R09: 0000000000000000 R10: 0000000000000000 R11: 00007ff8a80e9670 R12: 00007ff8a80e9670 R13: 00007ff8a80e9700 R14: 0000000000000001 R15: 0000000000000000 FS: 00007ff8a80ea700 GS: 0000000000000000