INFO: task syz-executor.3:24710 blocked for more than 143 seconds. Not tainted 5.14.0-rc7-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.3 state:D stack:28104 pid:24710 ppid: 24460 flags:0x00004004 Call Trace: context_switch kernel/sched/core.c:4681 [inline] __schedule+0x93a/0x26f0 kernel/sched/core.c:5938 schedule+0xd3/0x270 kernel/sched/core.c:6017 rwsem_down_write_slowpath+0x7b9/0x11d0 kernel/locking/rwsem.c:1106 __down_write_common kernel/locking/rwsem.c:1261 [inline] __down_write_common kernel/locking/rwsem.c:1258 [inline] __down_write kernel/locking/rwsem.c:1270 [inline] down_write+0x137/0x150 kernel/locking/rwsem.c:1407 inode_lock include/linux/fs.h:774 [inline] do_truncate+0x12a/0x200 fs/open.c:62 handle_truncate fs/namei.c:3030 [inline] do_open fs/namei.c:3378 [inline] path_openat+0x20f4/0x27f0 fs/namei.c:3507 do_filp_open+0x1aa/0x400 fs/namei.c:3534 do_sys_openat2+0x16d/0x420 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x13f/0x1f0 fs/open.c:1231 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:0x4665e9 RSP: 002b:00007f256d811188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056c0f0 RCX: 00000000004665e9 RDX: 0000000000007a05 RSI: 0000000020000040 RDI: ffffffffffffff9c RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000001700 R11: 0000000000000246 R12: 000000000056c0f0 R13: 00007ffdf66c222f R14: 00007f256d811300 R15: 0000000000022000 INFO: task syz-executor.3:24712 blocked for more than 143 seconds. Not tainted 5.14.0-rc7-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.3 state:D stack:29064 pid:24712 ppid: 24460 flags:0x00004004 Call Trace: context_switch kernel/sched/core.c:4681 [inline] __schedule+0x93a/0x26f0 kernel/sched/core.c:5938 schedule+0xd3/0x270 kernel/sched/core.c:6017 rwsem_down_write_slowpath+0x7b9/0x11d0 kernel/locking/rwsem.c:1106 __down_write_common kernel/locking/rwsem.c:1261 [inline] __down_write_common kernel/locking/rwsem.c:1258 [inline] __down_write kernel/locking/rwsem.c:1270 [inline] down_write+0x137/0x150 kernel/locking/rwsem.c:1407 inode_lock include/linux/fs.h:774 [inline] ext4_dio_write_iter fs/ext4/file.c:510 [inline] ext4_file_write_iter+0xaeb/0x14e0 fs/ext4/file.c:678 call_write_iter include/linux/fs.h:2114 [inline] new_sync_write+0x426/0x650 fs/read_write.c:518 vfs_write+0x75a/0xa40 fs/read_write.c:605 ksys_write+0x12d/0x250 fs/read_write.c:658 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:0x4665e9 RSP: 002b:00007f256d7cf188 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 000000000056c260 RCX: 00000000004665e9 RDX: 000000000004ea00 RSI: 0000000020000200 RDI: 0000000000000007 RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c260 R13: 00007ffdf66c222f R14: 00007f256d7cf300 R15: 0000000000022000 Showing all locks held in the system: 1 lock held by khungtaskd/1607: #0: ffffffff8b979840 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6446 1 lock held by in:imklog/8143: #0: ffff88801bdccdf0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:974 2 locks held by syz-executor.3/24710: #0: ffff88814822a460 (sb_writers#5){.+.+}-{0:0}, at: do_open fs/namei.c:3367 [inline] #0: ffff88814822a460 (sb_writers#5){.+.+}-{0:0}, at: path_openat+0x1aee/0x27f0 fs/namei.c:3507 #1: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: inode_lock include/linux/fs.h:774 [inline] #1: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: do_truncate+0x12a/0x200 fs/open.c:62 3 locks held by syz-executor.3/24712: #0: ffff888021601b30 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:974 #1: ffff88814822a460 (sb_writers#5){.+.+}-{0:0}, at: ksys_write+0x12d/0x250 fs/read_write.c:658 #2: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: inode_lock include/linux/fs.h:774 [inline] #2: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: ext4_dio_write_iter fs/ext4/file.c:510 [inline] #2: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: ext4_file_write_iter+0xaeb/0x14e0 fs/ext4/file.c:678 3 locks held by syz-executor.3/24713: #0: ffff88814822a460 (sb_writers#5){.+.+}-{0:0}, at: __ext4_ioctl+0x2e42/0x4850 fs/ext4/ioctl.c:984 #1: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: inode_lock include/linux/fs.h:774 [inline] #1: ffff88806ba146c8 (&sb->s_type->i_mutex_key#9){++++}-{3:3}, at: lock_two_nondirectories+0xec/0x110 fs/inode.c:1038 #2: ffff88806f5c2648 (&sb->s_type->i_mutex_key#9/4){+.+.}-{3:3}, at: inode_lock_nested include/linux/fs.h:809 [inline] #2: ffff88806f5c2648 (&sb->s_type->i_mutex_key#9/4){+.+.}-{3:3}, at: lock_two_nondirectories+0xd1/0x110 fs/inode.c:1040 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1607 Comm: khungtaskd Not tainted 5.14.0-rc7-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:105 nmi_cpu_backtrace.cold+0x44/0xd7 lib/nmi_backtrace.c:105 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:210 [inline] watchdog+0xd0a/0xfc0 kernel/hung_task.c:295 kthread+0x3e5/0x4d0 kernel/kthread.c:319 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: 8143 Comm: in:imklog Not tainted 5.14.0-rc7-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:write_comp_data kernel/kcov.c:218 [inline] RIP: 0010:__sanitizer_cov_trace_const_cmp8+0x3/0x70 kernel/kcov.c:291 Code: 83 c2 01 48 c7 44 30 e0 00 00 00 00 48 89 7c 30 e8 48 89 4c 30 f0 4c 89 54 d8 20 48 89 10 5b c3 0f 1f 80 00 00 00 00 49 89 f8 03 00 00 00 4c 8b 14 24 48 89 f1 65 48 8b 34 25 40 f0 01 00 e8 RSP: 0018:ffffc9000c1ef678 EFLAGS: 00000093 RAX: 0000000000000000 RBX: ffffc9000c1ee920 RCX: 0000000000000000 RDX: ffff8880334fa0c0 RSI: ffffc9000c1ee920 RDI: ffffffffffffe000 RBP: ffffc9000c1efa1a R08: ffffffffffffe000 R09: ffffffff83f39eef R10: ffffffff83f3a432 R11: 0000000000000004 R12: 00000000ffffffff R13: ffffc9008c1efa18 R14: ffffc9000c1ef920 R15: ffff0a0000000604 FS: 00007f2f12c8e700(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fe719ed6000 CR3: 000000001624d000 CR4: 00000000001506e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: check_pointer_msg lib/vsprintf.c:693 [inline] check_pointer lib/vsprintf.c:704 [inline] string+0xeb/0x3d0 lib/vsprintf.c:717 vsnprintf+0x71d/0x14f0 lib/vsprintf.c:2797 sprintf+0xc0/0x100 lib/vsprintf.c:2999 print_caller kernel/printk/printk.c:1278 [inline] info_print_prefix+0x12e/0x340 kernel/printk/printk.c:1295 record_print_text+0x14d/0x3e0 kernel/printk/printk.c:1342 syslog_print+0x2bb/0x430 kernel/printk/printk.c:1522 do_syslog.part.0+0x367/0x830 kernel/printk/printk.c:1661 do_syslog+0x49/0x60 kernel/printk/printk.c:1641 kmsg_read+0x90/0xb0 fs/proc/kmsg.c:40 pde_read fs/proc/inode.c:311 [inline] proc_reg_read+0x119/0x300 fs/proc/inode.c:321 vfs_read+0x1b5/0x570 fs/read_write.c:494 ksys_read+0x12d/0x250 fs/read_write.c:634 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:0x7f2f152d122d Code: c1 20 00 00 75 10 b8 00 00 00 00 0f 05 48 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 4e fc ff ff 48 89 04 24 b8 00 00 00 00 0f 05 <48> 8b 3c 24 48 89 c2 e8 97 fc ff ff 48 89 d0 48 83 c4 08 48 3d 01 RSP: 002b:00007f2f12c6d580 EFLAGS: 00000293 ORIG_RAX: 0000000000000000 RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f2f152d122d RDX: 0000000000001fa0 RSI: 00007f2f12c6dda0 RDI: 0000000000000004 RBP: 000055fa11e7a9d0 R08: 0000000000000000 R09: 0000000000000000 R10: 2ce33e6c02ce33e7 R11: 0000000000000293 R12: 00007f2f12c6dda0 R13: 0000000000001fa0 R14: 0000000000001f9f R15: 00007f2f12c6de35 ---------------- Code disassembly (best guess): 0: 83 c2 01 add $0x1,%edx 3: 48 c7 44 30 e0 00 00 movq $0x0,-0x20(%rax,%rsi,1) a: 00 00 c: 48 89 7c 30 e8 mov %rdi,-0x18(%rax,%rsi,1) 11: 48 89 4c 30 f0 mov %rcx,-0x10(%rax,%rsi,1) 16: 4c 89 54 d8 20 mov %r10,0x20(%rax,%rbx,8) 1b: 48 89 10 mov %rdx,(%rax) 1e: 5b pop %rbx 1f: c3 retq 20: 0f 1f 80 00 00 00 00 nopl 0x0(%rax) 27: 49 89 f8 mov %rdi,%r8 * 2a: bf 03 00 00 00 mov $0x3,%edi <-- trapping instruction 2f: 4c 8b 14 24 mov (%rsp),%r10 33: 48 89 f1 mov %rsi,%rcx 36: 65 48 8b 34 25 40 f0 mov %gs:0x1f040,%rsi 3d: 01 00 3f: e8 .byte 0xe8