INFO: task syz-executor.0:16999 can't die for more than 143 seconds. task:syz-executor.0 state:D stack:24072 pid:16999 ppid: 6536 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 mpage_bio_submit fs/mpage.c:66 [inline] mpage_readahead+0x589/0x750 fs/mpage.c:393 read_pages+0x1e4/0xfa0 mm/readahead.c:129 page_cache_ra_unbounded+0x64b/0x940 mm/readahead.c:238 do_page_cache_ra mm/readahead.c:268 [inline] ondemand_readahead+0x594/0x1090 mm/readahead.c:551 page_cache_async_ra mm/readahead.c:608 [inline] page_cache_async_ra+0x2e1/0x3f0 mm/readahead.c:583 page_cache_async_readahead include/linux/pagemap.h:1036 [inline] filemap_readahead mm/filemap.c:2531 [inline] filemap_get_pages+0x6f1/0x1be0 mm/filemap.c:2572 filemap_read+0x315/0xeb0 mm/filemap.c:2642 generic_file_read_iter+0x3b6/0x5a0 mm/filemap.c:2792 blkdev_read_iter+0xf8/0x1d0 block/fops.c:583 call_read_iter include/linux/fs.h:2156 [inline] generic_file_splice_read+0x453/0x6d0 fs/splice.c:311 do_splice_to+0x1bf/0x250 fs/splice.c:796 splice_direct_to_actor+0x2c2/0x8c0 fs/splice.c:870 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:0x7f0d6c786ae9 RSP: 002b:00007f0d69cfc188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 RAX: ffffffffffffffda RBX: 00007f0d6c899f60 RCX: 00007f0d6c786ae9 RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000004 RBP: 00007f0d6c7e0f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000024002da6 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd94eb5d3f R14: 00007f0d69cfc300 R15: 0000000000022000 INFO: task syz-executor.0:16999 blocked for more than 143 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.0 state:D stack:24072 pid:16999 ppid: 6536 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 mpage_bio_submit fs/mpage.c:66 [inline] mpage_readahead+0x589/0x750 fs/mpage.c:393 read_pages+0x1e4/0xfa0 mm/readahead.c:129 page_cache_ra_unbounded+0x64b/0x940 mm/readahead.c:238 do_page_cache_ra mm/readahead.c:268 [inline] ondemand_readahead+0x594/0x1090 mm/readahead.c:551 page_cache_async_ra mm/readahead.c:608 [inline] page_cache_async_ra+0x2e1/0x3f0 mm/readahead.c:583 page_cache_async_readahead include/linux/pagemap.h:1036 [inline] filemap_readahead mm/filemap.c:2531 [inline] filemap_get_pages+0x6f1/0x1be0 mm/filemap.c:2572 filemap_read+0x315/0xeb0 mm/filemap.c:2642 generic_file_read_iter+0x3b6/0x5a0 mm/filemap.c:2792 blkdev_read_iter+0xf8/0x1d0 block/fops.c:583 call_read_iter include/linux/fs.h:2156 [inline] generic_file_splice_read+0x453/0x6d0 fs/splice.c:311 do_splice_to+0x1bf/0x250 fs/splice.c:796 splice_direct_to_actor+0x2c2/0x8c0 fs/splice.c:870 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:0x7f0d6c786ae9 RSP: 002b:00007f0d69cfc188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028 RAX: ffffffffffffffda RBX: 00007f0d6c899f60 RCX: 00007f0d6c786ae9 RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000004 RBP: 00007f0d6c7e0f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000024002da6 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd94eb5d3f R14: 00007f0d69cfc300 R15: 0000000000022000 INFO: task syz-executor.0:17007 can't die for more than 144 seconds. task:syz-executor.0 state:D stack:29072 pid:17007 ppid: 6536 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:0x7f0d6c786ae9 RSP: 002b:00007f0d69cdb188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f0d6c89a020 RCX: 00007f0d6c786ae9 RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000004 RBP: 00007f0d6c7e0f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd94eb5d3f R14: 00007f0d69cdb300 R15: 0000000000022000 INFO: task syz-executor.0:17007 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.0 state:D stack:29072 pid:17007 ppid: 6536 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:0x7f0d6c786ae9 RSP: 002b:00007f0d69cdb188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f0d6c89a020 RCX: 00007f0d6c786ae9 RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000004 RBP: 00007f0d6c7e0f6d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd94eb5d3f R14: 00007f0d69cdb300 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 2 locks held by kworker/u4:4/945: #0: ffff8880b9c39a98 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2b/0x120 kernel/sched/core.c:478 #1: ffffc9000469fdb0 ((work_completion)(&(&bat_priv->nc.work)->work)){+.+.}-{0:0}, at: process_one_work+0x8ca/0x1690 kernel/workqueue.c:2273 2 locks held by systemd-udevd/2973: #0: ffff88801a33d918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_get_by_dev.part.0+0x9b/0xb50 block/bdev.c:819 #1: ffff88801a339360 (&lo->lo_mutex){+.+.}-{3:3}, at: lo_open+0x75/0x120 drivers/block/loop.c:1733 1 lock held by in:imklog/6223: #0: ffff88801df1a370 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:990 2 locks held by kworker/u4:12/7725: #0: ffff8880b9c39a98 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2b/0x120 kernel/sched/core.c:478 #1: ffff8880b9c279c8 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x3a6/0x490 kernel/sched/psi.c:880 1 lock held by syz-executor.0/16999: #0: ffff8881448fb888 (mapping.invalidate_lock#2){++++}-{3:3}, at: filemap_invalidate_lock_shared include/linux/fs.h:838 [inline] #0: ffff8881448fb888 (mapping.invalidate_lock#2){++++}-{3:3}, at: page_cache_ra_unbounded+0x1b3/0x940 mm/readahead.c:194 1 lock held by syz-executor.0/17007: #0: ffff88801a339360 (&lo->lo_mutex){+.+.}-{3:3}, at: __loop_clr_fd+0x7a/0x1070 drivers/block/loop.c:1106 ============================================= NMI backtrace for cpu 0 CPU: 0 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 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 2960 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:__preempt_count_add arch/x86/include/asm/preempt.h:80 [inline] RIP: 0010:preempt_count_add+0x38/0x140 kernel/sched/core.c:5445 Code: 00 fc ff df 48 89 c1 53 83 e0 07 89 fb 48 c1 e9 03 83 c0 03 0f b6 14 11 38 d0 7c 08 84 d2 0f 85 e1 00 00 00 8b 15 18 0c fc 0e <65> 01 1d 51 22 b2 7e 85 d2 75 11 65 8b 05 46 22 b2 7e 0f b6 c0 3d RSP: 0018:ffffc90001f6f9b8 EFLAGS: 00000297 RAX: 0000000000000003 RBX: 0000000000000001 RCX: 1ffffffff2098b38 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000001 RBP: 0000000000000001 R08: ffffffff8e39650e R09: 0000000000000001 R10: fffff520003edf60 R11: 000000000008808a R12: ffffc90001f6faf0 R13: 0000000000000000 R14: ffffc90001f6faa8 R15: ffff88807b32c500 FS: 00007fe16425a8c0(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fe16180e000 CR3: 000000007ad73000 CR4: 00000000003506e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: unwind_next_frame+0x120/0x1ce0 arch/x86/kernel/unwind_orc.c:428 arch_stack_walk+0x7d/0xe0 arch/x86/kernel/stacktrace.c:25 stack_trace_save+0x8c/0xc0 kernel/stacktrace.c:122 kasan_save_stack+0x1e/0x50 mm/kasan/common.c:38 kasan_set_track mm/kasan/common.c:46 [inline] set_alloc_info mm/kasan/common.c:434 [inline] ____kasan_kmalloc mm/kasan/common.c:513 [inline] ____kasan_kmalloc mm/kasan/common.c:472 [inline] __kasan_kmalloc+0xa9/0xd0 mm/kasan/common.c:522 kmalloc include/linux/slab.h:595 [inline] kzalloc include/linux/slab.h:724 [inline] lsm_cred_alloc security/security.c:537 [inline] security_prepare_creds+0x10e/0x190 security/security.c:1692 prepare_creds+0x56e/0x7b0 kernel/cred.c:291 access_override_creds fs/open.c:351 [inline] do_faccessat+0x3f4/0x850 fs/open.c:415 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:0x7fe1635159c7 Code: 83 c4 08 48 3d 01 f0 ff ff 73 01 c3 48 8b 0d c8 d4 2b 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 b8 15 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d a1 d4 2b 00 f7 d8 64 89 01 48 RSP: 002b:00007ffd353a99a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000015 RAX: ffffffffffffffda RBX: 00007ffd353ac9d0 RCX: 00007fe1635159c7 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000563af4fdf9a3 RBP: 00007ffd353a9af0 R08: 0000563af4fd53e5 R09: 0000000000000018 R10: 0000000000000069 R11: 0000000000000246 R12: 0000000000000000 R13: 0000000000000000 R14: 0000563af5d778a0 R15: 00007ffd353a9fe0 ---------------- Code disassembly (best guess), 4 bytes skipped: 0: 48 89 c1 mov %rax,%rcx 3: 53 push %rbx 4: 83 e0 07 and $0x7,%eax 7: 89 fb mov %edi,%ebx 9: 48 c1 e9 03 shr $0x3,%rcx d: 83 c0 03 add $0x3,%eax 10: 0f b6 14 11 movzbl (%rcx,%rdx,1),%edx 14: 38 d0 cmp %dl,%al 16: 7c 08 jl 0x20 18: 84 d2 test %dl,%dl 1a: 0f 85 e1 00 00 00 jne 0x101 20: 8b 15 18 0c fc 0e mov 0xefc0c18(%rip),%edx # 0xefc0c3e * 26: 65 01 1d 51 22 b2 7e add %ebx,%gs:0x7eb22251(%rip) # 0x7eb2227e <-- trapping instruction 2d: 85 d2 test %edx,%edx 2f: 75 11 jne 0x42 31: 65 8b 05 46 22 b2 7e mov %gs:0x7eb22246(%rip),%eax # 0x7eb2227e 38: 0f b6 c0 movzbl %al,%eax 3b: 3d .byte 0x3d