bisecting fixing commit since 3207316b3beec7e38e5dbe2f463df0cec71e0b97 building syzkaller on 79264ae39c1ef4b4875ab67d6f0c8c3e75aa6a34 testing commit 3207316b3beec7e38e5dbe2f463df0cec71e0b97 with gcc (GCC) 8.4.1 20210217 kernel signature: ce2cfed5456665df326c2e2eb6b66138d0789d17d6171e3a2b265807c599f24a all runs: crashed: INFO: task hung in vfs_setxattr testing current HEAD 1722257b8ececec9b3b83a8b14058f8209d78071 testing commit 1722257b8ececec9b3b83a8b14058f8209d78071 with gcc (GCC) 8.4.1 20210217 kernel signature: 47e79b75d25ca61c97e6fcce13c3a4788737c1b02ecbecfae6914f37eccda249 all runs: crashed: INFO: task hung in vfs_setxattr revisions tested: 2, total time: 32m3.473330332s (build: 19m4.456030681s, test: 12m22.159649828s) the crash still happens on HEAD commit msg: Linux 4.19.193 crash: INFO: task hung in vfs_setxattr Bluetooth: hci4: command 0x0406 tx timeout Bluetooth: hci2: command 0x0406 tx timeout Bluetooth: hci3: command 0x0406 tx timeout Bluetooth: hci1: command 0x0406 tx timeout Bluetooth: hci5: command 0x0406 tx timeout INFO: task syz-executor.1:9842 blocked for more than 140 seconds. Not tainted 4.19.193-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D29192 9842 8468 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x80c/0x1f70 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline] rwsem_down_write_failed+0x3bb/0x780 kernel/locking/rwsem-xadd.c:618 call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117 __down_write arch/x86/include/asm/rwsem.h:142 [inline] down_write+0x53/0x90 kernel/locking/rwsem.c:72 inode_lock include/linux/fs.h:748 [inline] vfs_setxattr+0xd6/0x280 fs/xattr.c:254 setxattr+0x1af/0x280 fs/xattr.c:520 __do_sys_fsetxattr fs/xattr.c:575 [inline] __se_sys_fsetxattr+0xff/0x160 fs/xattr.c:564 __x64_sys_fsetxattr+0xb9/0x140 fs/xattr.c:564 do_syscall_64+0xd0/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x465b09 Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007f6f0b614188 EFLAGS: 00000246 ORIG_RAX: 00000000000000be RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 0000000000465b09 RDX: 0000000000000000 RSI: 0000000020000180 RDI: 0000000000000005 RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008 R13: 00007ffc90e4348f R14: 00007f6f0b614300 R15: 0000000000022000 INFO: task syz-executor.0:9881 blocked for more than 140 seconds. Not tainted 4.19.193-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D29752 9881 8461 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x80c/0x1f70 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline] rwsem_down_write_failed+0x3bb/0x780 kernel/locking/rwsem-xadd.c:618 call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117 __down_write arch/x86/include/asm/rwsem.h:142 [inline] down_write+0x53/0x90 kernel/locking/rwsem.c:72 inode_lock include/linux/fs.h:748 [inline] vfs_setxattr+0xd6/0x280 fs/xattr.c:254 setxattr+0x1af/0x280 fs/xattr.c:520 __do_sys_fsetxattr fs/xattr.c:575 [inline] __se_sys_fsetxattr+0xff/0x160 fs/xattr.c:564 __x64_sys_fsetxattr+0xb9/0x140 fs/xattr.c:564 do_syscall_64+0xd0/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x465b09 Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007ff3ef9ba188 EFLAGS: 00000246 ORIG_RAX: 00000000000000be RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 0000000000465b09 RDX: 0000000000000000 RSI: 0000000020000180 RDI: 0000000000000005 RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008 R13: 00007fff4cdfc88f R14: 00007ff3ef9ba300 R15: 0000000000022000 INFO: task syz-executor.2:9948 blocked for more than 140 seconds. Not tainted 4.19.193-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D29752 9948 8458 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x80c/0x1f70 kernel/sched/core.c:3517 INFO: task syz-executor.3:9966 blocked for more than 140 seconds. Not tainted 4.19.193-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D29752 9966 8464 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x80c/0x1f70 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline] rwsem_down_write_failed+0x3bb/0x780 kernel/locking/rwsem-xadd.c:618 call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117 __down_write arch/x86/include/asm/rwsem.h:142 [inline] down_write+0x53/0x90 kernel/locking/rwsem.c:72 inode_lock include/linux/fs.h:748 [inline] vfs_setxattr+0xd6/0x280 fs/xattr.c:254 setxattr+0x1af/0x280 fs/xattr.c:520 __do_sys_fsetxattr fs/xattr.c:575 [inline] __se_sys_fsetxattr+0xff/0x160 fs/xattr.c:564 __x64_sys_fsetxattr+0xb9/0x140 fs/xattr.c:564 do_syscall_64+0xd0/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x465b09 Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007fd964364188 EFLAGS: 00000246 ORIG_RAX: 00000000000000be RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 0000000000465b09 RDX: 0000000000000000 RSI: 0000000020000180 RDI: 0000000000000005 RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008 R13: 00007fff12f391cf R14: 00007fd964364300 R15: 0000000000022000 INFO: task syz-executor.5:9980 blocked for more than 140 seconds. Not tainted 4.19.193-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D29752 9980 8467 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x80c/0x1f70 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline] rwsem_down_write_failed+0x3bb/0x780 kernel/locking/rwsem-xadd.c:618 call_rwsem_down_write_failed+0x17/0x30 arch/x86/lib/rwsem.S:117 __down_write arch/x86/include/asm/rwsem.h:142 [inline] down_write+0x53/0x90 kernel/locking/rwsem.c:72 inode_lock include/linux/fs.h:748 [inline] vfs_setxattr+0xd6/0x280 fs/xattr.c:254 setxattr+0x1af/0x280 fs/xattr.c:520 __do_sys_fsetxattr fs/xattr.c:575 [inline] __se_sys_fsetxattr+0xff/0x160 fs/xattr.c:564 __x64_sys_fsetxattr+0xb9/0x140 fs/xattr.c:564 do_syscall_64+0xd0/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x465b09 Code: Bad RIP value. RSP: 002b:00007ff6e0323188 EFLAGS: 00000246 ORIG_RAX: 00000000000000be RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 0000000000465b09 RDX: 0000000000000000 RSI: 0000000020000180 RDI: 0000000000000005 RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008 R13: 00007ffed1441c4f R14: 00007ff6e0323300 R15: 0000000000022000 Showing all locks held in the system: 1 lock held by khungtaskd/1571: #0: 000000004964aff7 (rcu_read_lock){....}, at: debug_show_all_locks+0x5b/0x27a kernel/locking/lockdep.c:4443 1 lock held by khugepaged/1578: 1 lock held by kswapd0/1969: 1 lock held by in:imklog/7777: #0: 00000000dcaac943 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xa7/0xd0 fs/file.c:767 2 locks held by kworker/1:4/8515: #0: 0000000093d1a040 ((wq_completion)"rcu_gp"){+.+.}, at: process_one_work+0x6e8/0x15a0 kernel/workqueue.c:2123 #1: 0000000022647e52 ((work_completion)(&rew.rew_work)){+.+.}, at: process_one_work+0x71b/0x15a0 kernel/workqueue.c:2127 3 locks held by syz-executor.1/9824: 2 locks held by syz-executor.1/9842: #0: 00000000ad2175dd (sb_writers#14){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline] #0: 00000000ad2175dd (sb_writers#14){.+.+}, at: mnt_want_write_file+0x67/0x110 fs/namespace.c:418 #1: 000000000ce70a15 (&type->i_mutex_dir_key#9){+.+.}, at: inode_lock include/linux/fs.h:748 [inline] #1: 000000000ce70a15 (&type->i_mutex_dir_key#9){+.+.}, at: vfs_setxattr+0xd6/0x280 fs/xattr.c:254 1 lock held by syz-executor.0/9881: 1 lock held by syz-executor.2/9940: 1 lock held by syz-executor.3/9960: 1 lock held by syz-executor.5/9970: #0: 00000000893f038a (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:297 [inline] #0: 00000000893f038a (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x3a0/0x8a0 kernel/rcu/tree_exp.h:667 1 lock held by syz-executor.4/9981: ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1571 Comm: khungtaskd Not tainted 4.19.193-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x17c/0x226 lib/dump_stack.c:118 nmi_cpu_backtrace.cold.0+0x3c/0x78 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0xf6/0x120 lib/nmi_backtrace.c:62 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline] watchdog+0x5c3/0xb40 kernel/hung_task.c:287 kthread+0x347/0x410 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: 9970 Comm: syz-executor.5 Not tainted 4.19.193-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline] RIP: 0010:lock_is_held_type+0x18b/0x210 kernel/locking/lockdep.c:3948 Code: ff df 41 c7 84 24 84 08 00 00 00 00 00 00 48 89 fa 48 c1 ea 03 80 3c 02 00 75 63 48 83 3d 24 4c 4b 08 00 74 30 48 89 df 57 9d <0f> 1f 44 00 00 48 83 c4 08 44 89 e8 5b 41 5c 41 5d 5d c3 48 83 c4 RSP: 0018:ffff8880aeec7300 EFLAGS: 00000282 RAX: dffffc0000000000 RBX: 0000000000000282 RCX: ffffed1016896520 RDX: 1ffffffff1322499 RSI: 0000000000000000 RDI: 0000000000000282 RBP: ffff8880aeec7320 R08: 1ffff11016896520 R09: ffffed1017464552 R10: ffffed1017464552 R11: ffff8880ba322a93 R12: ffff8880b44b2080 R13: 0000000000000001 R14: ffff88802eda1348 R15: ffff8880aeec7530 FS: 00007ff6e0344700(0000) GS:ffff8880ba300000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f345c639000 CR3: 00000000b2d40000 CR4: 00000000001406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_is_held include/linux/lockdep.h:344 [inline] rcu_read_lock_held kernel/rcu/update.c:287 [inline] rcu_read_lock_held+0xa9/0xc0 kernel/rcu/update.c:279 radix_tree_deref_slot include/linux/radix-tree.h:241 [inline] find_get_entries+0x5cb/0x8e0 mm/filemap.c:1662 pagevec_lookup_entries+0x1d/0x60 mm/swap.c:956 truncate_inode_pages_range+0x1a9/0x1920 mm/truncate.c:331 truncate_inode_pages mm/truncate.c:478 [inline] truncate_inode_pages_final+0x6f/0xa0 mm/truncate.c:529 minix_evict_inode+0x16/0xe0 fs/minix/inode.c:29 evict+0x29c/0x590 fs/inode.c:559 dispose_list+0xfe/0x1f0 fs/inode.c:594 evict_inodes+0x2b8/0x3b0 fs/inode.c:644 generic_shutdown_super+0xac/0x330 fs/super.c:448 kill_block_super+0x96/0xe0 fs/super.c:1185 deactivate_locked_super+0x77/0xd0 fs/super.c:329 deactivate_super fs/super.c:360 [inline] deactivate_super+0x13f/0x160 fs/super.c:356 cleanup_mnt+0xa3/0x130 fs/namespace.c:1098 __cleanup_mnt+0xd/0x10 fs/namespace.c:1105 task_work_run+0x108/0x180 kernel/task_work.c:113 exit_task_work include/linux/task_work.h:22 [inline] do_exit+0xa6a/0x2d90 kernel/exit.c:870 do_group_exit+0xf8/0x2c0 kernel/exit.c:967 get_signal+0x30b/0x1970 kernel/signal.c:2589 do_signal+0x87/0x1870 arch/x86/kernel/signal.c:799 exit_to_usermode_loop+0x159/0x1e0 arch/x86/entry/common.c:163 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline] syscall_return_slowpath arch/x86/entry/common.c:271 [inline] do_syscall_64+0x413/0x4e0 arch/x86/entry/common.c:296 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x465b09 Code: Bad RIP value. RSP: 002b:00007ff6e0344188 EFLAGS: 00000246 ORIG_RAX: 0000000000000107 RAX: 0000000000000000 RBX: 000000000056bf60 RCX: 0000000000465b09 RDX: 0000000000000200 RSI: 0000000020000140 RDI: 0000000000000005 RBP: 00000000004b069f R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf60 R13: 00007ffed1441c4f R14: 00007ff6e0344300 R15: 0000000000022000