INFO: task syz-executor.5:13648 can't die for more than 143 seconds. task:syz-executor.5 state:D stack:24264 pid:13648 ppid: 6897 flags:0x00004004 Call Trace: context_switch kernel/sched/core.c:4987 [inline] __schedule+0xa9a/0x4940 kernel/sched/core.c:6268 schedule+0xd2/0x260 kernel/sched/core.c:6341 __bio_queue_enter+0x3c5/0x6a0 block/blk-core.c:441 bio_queue_enter block/blk.h:91 [inline] blk_mq_get_request block/blk-mq.c:2588 [inline] blk_mq_submit_bio+0x4ae/0x22c0 block/blk-mq.c:2632 __submit_bio block/blk-core.c:849 [inline] __submit_bio_noacct_mq block/blk-core.c:924 [inline] submit_bio_noacct block/blk-core.c:950 [inline] submit_bio_noacct+0x82c/0xa20 block/blk-core.c:939 submit_bio block/blk-core.c:1011 [inline] submit_bio+0x1ea/0x430 block/blk-core.c:969 submit_bh_wbc+0x5e8/0x7e0 fs/buffer.c:3091 __block_write_full_page+0x875/0x1350 fs/buffer.c:1846 block_write_full_page+0x14d/0x190 fs/buffer.c:3017 __writepage+0x60/0x180 mm/page-writeback.c:2320 write_cache_pages+0x6f4/0x10c0 mm/page-writeback.c:2255 generic_writepages mm/page-writeback.c:2346 [inline] generic_writepages+0xe2/0x150 mm/page-writeback.c:2335 do_writepages+0x1ab/0x690 mm/page-writeback.c:2364 filemap_fdatawrite_wbc mm/filemap.c:407 [inline] filemap_fdatawrite_wbc+0x143/0x1b0 mm/filemap.c:397 __filemap_fdatawrite_range mm/filemap.c:440 [inline] file_write_and_wait_range+0x163/0x1e0 mm/filemap.c:824 blkdev_fsync+0x4a/0xa0 block/fops.c:465 vfs_fsync_range+0x13a/0x220 fs/sync.c:182 generic_write_sync include/linux/fs.h:2956 [inline] blkdev_write_iter+0x552/0x690 block/fops.c:559 call_write_iter include/linux/fs.h:2162 [inline] do_iter_readv_writev+0x472/0x750 fs/read_write.c:725 do_iter_write+0x188/0x710 fs/read_write.c:851 vfs_iter_write+0x70/0xa0 fs/read_write.c:892 iter_file_splice_write+0x723/0xc70 fs/splice.c:689 do_splice_from fs/splice.c:767 [inline] direct_splice_actor+0x110/0x180 fs/splice.c:936 splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891 do_splice_direct+0x1b3/0x280 fs/splice.c:979 do_sendfile+0xaf2/0x1250 fs/read_write.c:1245 __do_sys_sendfile64 fs/read_write.c:1310 [inline] __se_sys_sendfile64 fs/read_write.c:1296 [inline] __x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1296 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x7f1dbbafcae9 RSP: 002b:00007f1db9072188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 RAX: ffffffffffffffda RBX: 00007f1dbbc0ff60 RCX: 00007f1dbbafcae9 RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000004 RBP: 00007f1dbbb56f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000024002da8 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff7e3436ff R14: 00007f1db9072300 R15: 0000000000022000 INFO: task syz-executor.5:13648 blocked for more than 144 seconds. Not tainted 5.16.0-rc1-next-20211116-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.5 state:D stack:24264 pid:13648 ppid: 6897 flags:0x00004004 Call Trace: context_switch kernel/sched/core.c:4987 [inline] __schedule+0xa9a/0x4940 kernel/sched/core.c:6268 schedule+0xd2/0x260 kernel/sched/core.c:6341 __bio_queue_enter+0x3c5/0x6a0 block/blk-core.c:441 bio_queue_enter block/blk.h:91 [inline] blk_mq_get_request block/blk-mq.c:2588 [inline] blk_mq_submit_bio+0x4ae/0x22c0 block/blk-mq.c:2632 __submit_bio block/blk-core.c:849 [inline] __submit_bio_noacct_mq block/blk-core.c:924 [inline] submit_bio_noacct block/blk-core.c:950 [inline] submit_bio_noacct+0x82c/0xa20 block/blk-core.c:939 submit_bio block/blk-core.c:1011 [inline] submit_bio+0x1ea/0x430 block/blk-core.c:969 submit_bh_wbc+0x5e8/0x7e0 fs/buffer.c:3091 __block_write_full_page+0x875/0x1350 fs/buffer.c:1846 block_write_full_page+0x14d/0x190 fs/buffer.c:3017 __writepage+0x60/0x180 mm/page-writeback.c:2320 write_cache_pages+0x6f4/0x10c0 mm/page-writeback.c:2255 generic_writepages mm/page-writeback.c:2346 [inline] generic_writepages+0xe2/0x150 mm/page-writeback.c:2335 do_writepages+0x1ab/0x690 mm/page-writeback.c:2364 filemap_fdatawrite_wbc mm/filemap.c:407 [inline] filemap_fdatawrite_wbc+0x143/0x1b0 mm/filemap.c:397 __filemap_fdatawrite_range mm/filemap.c:440 [inline] file_write_and_wait_range+0x163/0x1e0 mm/filemap.c:824 blkdev_fsync+0x4a/0xa0 block/fops.c:465 vfs_fsync_range+0x13a/0x220 fs/sync.c:182 generic_write_sync include/linux/fs.h:2956 [inline] blkdev_write_iter+0x552/0x690 block/fops.c:559 call_write_iter include/linux/fs.h:2162 [inline] do_iter_readv_writev+0x472/0x750 fs/read_write.c:725 do_iter_write+0x188/0x710 fs/read_write.c:851 vfs_iter_write+0x70/0xa0 fs/read_write.c:892 iter_file_splice_write+0x723/0xc70 fs/splice.c:689 do_splice_from fs/splice.c:767 [inline] direct_splice_actor+0x110/0x180 fs/splice.c:936 splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891 do_splice_direct+0x1b3/0x280 fs/splice.c:979 do_sendfile+0xaf2/0x1250 fs/read_write.c:1245 __do_sys_sendfile64 fs/read_write.c:1310 [inline] __se_sys_sendfile64 fs/read_write.c:1296 [inline] __x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1296 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x7f1dbbafcae9 RSP: 002b:00007f1db9072188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 RAX: ffffffffffffffda RBX: 00007f1dbbc0ff60 RCX: 00007f1dbbafcae9 RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000004 RBP: 00007f1dbbb56f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000024002da8 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff7e3436ff R14: 00007f1db9072300 R15: 0000000000022000 INFO: task syz-executor.5:13658 can't die for more than 144 seconds. task:syz-executor.5 state:D stack:29072 pid:13658 ppid: 6897 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4987 [inline] __schedule+0xa9a/0x4940 kernel/sched/core.c:6268 schedule+0xd2/0x260 kernel/sched/core.c:6341 blk_mq_freeze_queue_wait+0x112/0x160 block/blk-mq.c:178 __loop_clr_fd+0x1ae/0x1070 drivers/block/loop.c:1122 loop_clr_fd drivers/block/loop.c:1237 [inline] lo_ioctl+0x398/0x17c0 drivers/block/loop.c:1562 blkdev_ioctl+0x37a/0x800 block/ioctl.c:609 vfs_ioctl fs/ioctl.c:51 [inline] __do_sys_ioctl fs/ioctl.c:874 [inline] __se_sys_ioctl fs/ioctl.c:860 [inline] __x64_sys_ioctl+0x193/0x200 fs/ioctl.c:860 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x7f1dbbafcae9 RSP: 002b:00007f1db9051188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f1dbbc10020 RCX: 00007f1dbbafcae9 RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000004 RBP: 00007f1dbbb56f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff7e3436ff R14: 00007f1db9051300 R15: 0000000000022000 INFO: task syz-executor.5:13658 blocked for more than 144 seconds. Not tainted 5.16.0-rc1-next-20211116-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.5 state:D stack:29072 pid:13658 ppid: 6897 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4987 [inline] __schedule+0xa9a/0x4940 kernel/sched/core.c:6268 schedule+0xd2/0x260 kernel/sched/core.c:6341 blk_mq_freeze_queue_wait+0x112/0x160 block/blk-mq.c:178 __loop_clr_fd+0x1ae/0x1070 drivers/block/loop.c:1122 loop_clr_fd drivers/block/loop.c:1237 [inline] lo_ioctl+0x398/0x17c0 drivers/block/loop.c:1562 blkdev_ioctl+0x37a/0x800 block/ioctl.c:609 vfs_ioctl fs/ioctl.c:51 [inline] __do_sys_ioctl fs/ioctl.c:874 [inline] __se_sys_ioctl fs/ioctl.c:860 [inline] __x64_sys_ioctl+0x193/0x200 fs/ioctl.c:860 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x7f1dbbafcae9 RSP: 002b:00007f1db9051188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f1dbbc10020 RCX: 00007f1dbbafcae9 RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000004 RBP: 00007f1dbbb56f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff7e3436ff R14: 00007f1db9051300 R15: 0000000000022000 Showing all locks held in the system: 1 lock held by khungtaskd/27: #0: ffffffff8bb83b20 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6458 3 locks held by kworker/u4:2/44: #0: ffff888013bb4138 ((wq_completion)writeback){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: ffff888013bb4138 ((wq_completion)writeback){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline] #0: ffff888013bb4138 ((wq_completion)writeback){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1198 [inline] #0: ffff888013bb4138 ((wq_completion)writeback){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:635 [inline] #0: ffff888013bb4138 ((wq_completion)writeback){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:662 [inline] #0: ffff888013bb4138 ((wq_completion)writeback){+.+.}-{0:0}, at: process_one_work+0x896/0x1690 kernel/workqueue.c:2269 #1: ffffc9000114fdb0 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_one_work+0x8ca/0x1690 kernel/workqueue.c:2273 #2: ffff888010e6a0e0 (&type->s_umount_key#48){.+.+}-{3:3}, at: trylock_super+0x1d/0x100 fs/super.c:418 1 lock held by in:imklog/6391: #0: ffff888072466ff0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:990 1 lock held by syz-executor.5/13658: #0: ffff88801aafc360 (&lo->lo_mutex){+.+.}-{3:3}, at: __loop_clr_fd+0x7a/0x1070 drivers/block/loop.c:1106 2 locks held by systemd-udevd/13693: #0: ffff88801ab08918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_get_by_dev.part.0+0x9b/0xb50 block/bdev.c:819 #1: ffff88801aafc360 (&lo->lo_mutex){+.+.}-{3:3}, at: lo_open+0x75/0x120 drivers/block/loop.c:1733 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 27 Comm: khungtaskd Not tainted 5.16.0-rc1-next-20211116-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+0xcd/0x134 lib/dump_stack.c:106 nmi_cpu_backtrace.cold+0x47/0x144 lib/nmi_backtrace.c:111 nmi_trigger_cpumask_backtrace+0x1b3/0x230 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:254 [inline] watchdog+0xcb7/0xed0 kernel/hung_task.c:339 kthread+0x405/0x4f0 kernel/kthread.c:327 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 2963 Comm: systemd-journal Not tainted 5.16.0-rc1-next-20211116-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:hlock_id kernel/locking/lockdep.c:401 [inline] RIP: 0010:__lock_acquire+0xa26/0x54a0 kernel/locking/lockdep.c:5015 Code: c3 89 44 24 60 44 01 f8 c1 c3 04 89 44 24 50 89 5c 24 58 48 b8 00 00 00 00 00 fc ff df 48 8b 54 24 38 48 c1 ea 03 0f b6 04 02 <84> c0 74 08 3c 03 0f 8e 35 38 00 00 41 0f b7 44 24 20 66 25 ff 1f RSP: 0018:ffffc90001adf7f0 EFLAGS: 00000806 RAX: 0000000000000000 RBX: 000000008c452585 RCX: ffffffff815cad6a RDX: 1ffff1100fe5cc4e RSI: 0000000000000008 RDI: ffff88807f2e6271 RBP: ffff88807f2e6272 R08: 0000000000000000 R09: ffffffff8ff77a17 R10: fffffbfff1feef42 R11: 0000000000000000 R12: ffff88807f2e6250 R13: ffff88807f2e57c0 R14: 0000000000000000 R15: 58c45258731f1bcf FS: 00007f86797e58c0(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f8676ced000 CR3: 0000000019db1000 CR4: 00000000003506f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire kernel/locking/lockdep.c:5637 [inline] lock_acquire+0x1ab/0x510 kernel/locking/lockdep.c:5602 fs_reclaim_acquire mm/page_alloc.c:4552 [inline] fs_reclaim_acquire+0xd2/0x160 mm/page_alloc.c:4543 might_alloc include/linux/sched/mm.h:248 [inline] slab_pre_alloc_hook mm/slab.h:492 [inline] slab_alloc_node mm/slub.c:3148 [inline] slab_alloc mm/slub.c:3242 [inline] kmem_cache_alloc+0x3e/0x3a0 mm/slub.c:3247 kmem_cache_zalloc include/linux/slab.h:714 [inline] __alloc_file+0x21/0x280 fs/file_table.c:101 alloc_empty_file+0x6d/0x170 fs/file_table.c:150 path_openat+0xe1/0x2750 fs/namei.c:3545 do_filp_open+0x1aa/0x400 fs/namei.c:3586 do_sys_openat2+0x16d/0x4d0 fs/open.c:1212 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+0x119/0x1c0 fs/open.c:1232 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x7f8678d74840 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:00007ffe1fe13668 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffe1fe13970 RCX: 00007f8678d74840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 000055ea8481c320 RBP: 000000000000000d R08: 0000000000000000 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 000055ea84818040 R14: 00007ffe1fe13930 R15: 000055ea84825dd0 ---------------- Code disassembly (best guess): 0: c3 retq 1: 89 44 24 60 mov %eax,0x60(%rsp) 5: 44 01 f8 add %r15d,%eax 8: c1 c3 04 rol $0x4,%ebx b: 89 44 24 50 mov %eax,0x50(%rsp) f: 89 5c 24 58 mov %ebx,0x58(%rsp) 13: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 1a: fc ff df 1d: 48 8b 54 24 38 mov 0x38(%rsp),%rdx 22: 48 c1 ea 03 shr $0x3,%rdx 26: 0f b6 04 02 movzbl (%rdx,%rax,1),%eax * 2a: 84 c0 test %al,%al <-- trapping instruction 2c: 74 08 je 0x36 2e: 3c 03 cmp $0x3,%al 30: 0f 8e 35 38 00 00 jle 0x386b 36: 41 0f b7 44 24 20 movzwl 0x20(%r12),%eax 3c: 66 25 ff 1f and $0x1fff,%ax