syzbot


INFO: task hung in vfs_setxattr

Status: auto-closed as invalid on 2020/03/08 20:30
Reported-by: syzbot+3ebdc5eaa4459865af7a@syzkaller.appspotmail.com
First crash: 1690d, last: 1628d
Similar bugs (10)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in vfs_setxattr (2) fs 1 1426d 1426d 0/26 auto-closed as invalid on 2020/07/28 17:13
linux-5.15 INFO: task hung in vfs_setxattr (2) syz error 1 231d 231d 0/3 auto-obsoleted due to no activity on 2023/12/15 17:11
android-49 INFO: task hung in vfs_setxattr 4 2083d 2109d 0/3 auto-closed as invalid on 2019/02/22 13:09
upstream INFO: task hung in vfs_setxattr (3) fs syz done error 49 899d 1317d 0/26 closed as invalid on 2022/02/08 10:51
upstream INFO: task hung in vfs_setxattr fs 46 1956d 2116d 0/26 auto-closed as invalid on 2019/06/15 01:37
linux-4.19 INFO: task hung in vfs_setxattr (2) C error 3 929d 1209d 0/1 upstream: reported C repro on 2021/01/01 10:53
android-414 INFO: task hung in vfs_setxattr 1 1832d 1832d 0/1 auto-closed as invalid on 2019/10/16 16:43
linux-5.15 INFO: task hung in vfs_setxattr 1 382d 382d 0/3 auto-obsoleted due to no activity on 2023/08/06 19:50
upstream INFO: task hung in vfs_setxattr (4) ext4 1 295d 295d 0/26 auto-obsoleted due to no activity on 2023/10/02 09:30
android-44 INFO: task hung in vfs_setxattr C 3 1707d 1712d 0/2 public: reported C repro on 2019/08/17 15:36

Sample crash report:
Bluetooth: hci0: command 0x1003 tx timeout
Bluetooth: hci0: sending frame failed (-49)
Bluetooth: hci0: command 0x1001 tx timeout
Bluetooth: hci0: sending frame failed (-49)
Bluetooth: hci0: command 0x1009 tx timeout
INFO: task syz-executor.1:21273 blocked for more than 140 seconds.
      Not tainted 4.19.82 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D29712 21273  20556 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x866/0x1dc0 kernel/sched/core.c:3515
 schedule+0x92/0x1c0 kernel/sched/core.c:3559
 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline]
 rwsem_down_write_failed+0x774/0xc30 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:747 [inline]
 vfs_setxattr+0x98/0x100 fs/xattr.c:218
 setxattr+0x26f/0x380 fs/xattr.c:450
 path_setxattr+0x197/0x1b0 fs/xattr.c:469
 __do_sys_setxattr fs/xattr.c:484 [inline]
 __se_sys_setxattr fs/xattr.c:480 [inline]
 __x64_sys_setxattr+0xc4/0x150 fs/xattr.c:480
 do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a219
Code: 80 00 00 00 0f 86 94 01 00 00 48 81 fb 00 01 00 00 0f 86 b3 01 00 00 80 3d d5 80 45 01 01 0f 84 83 00 00 00 f3 0f 7f 07 f3 0f <7f> 47 10 f3 0f 7f 47 20 f3 0f 7f 47 30 f3 0f 7f 47 40 f3 0f 7f 47
RSP: 002b:00007f5113e93c78 EFLAGS: 00000246 ORIG_RAX: 00000000000000bc
RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 000000000045a219
RDX: 0000000020000800 RSI: 00000000200001c0 RDI: 0000000020000180
RBP: 000000000075c118 R08: 0000000000000002 R09: 0000000000000000
R10: 00000000000000fe R11: 0000000000000246 R12: 00007f5113e946d4
R13: 00000000004c0e0e R14: 00000000004e0ba0 R15: 00000000ffffffff
INFO: task syz-executor.1:21274 blocked for more than 140 seconds.
      Not tainted 4.19.82 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D29560 21274  20556 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x866/0x1dc0 kernel/sched/core.c:3515
 schedule+0x92/0x1c0 kernel/sched/core.c:3559
 __rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
 rwsem_down_read_failed+0x21b/0x3c0 kernel/locking/rwsem-xadd.c:309
 call_rwsem_down_read_failed+0x18/0x30 arch/x86/lib/rwsem.S:94
 __down_read arch/x86/include/asm/rwsem.h:83 [inline]
 down_read+0x49/0xb0 kernel/locking/rwsem.c:26
 inode_lock_shared include/linux/fs.h:757 [inline]
 do_last fs/namei.c:3323 [inline]
 path_openat+0x1e59/0x45e0 fs/namei.c:3534
 do_filp_open+0x1a1/0x280 fs/namei.c:3564
 do_sys_open+0x3fe/0x550 fs/open.c:1088
 __do_sys_openat fs/open.c:1115 [inline]
 __se_sys_openat fs/open.c:1109 [inline]
 __x64_sys_openat+0x9d/0x100 fs/open.c:1109
 do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a219
Code: 80 00 00 00 0f 86 94 01 00 00 48 81 fb 00 01 00 00 0f 86 b3 01 00 00 80 3d d5 80 45 01 01 0f 84 83 00 00 00 f3 0f 7f 07 f3 0f <7f> 47 10 f3 0f 7f 47 20 f3 0f 7f 47 30 f3 0f 7f 47 40 f3 0f 7f 47
RSP: 002b:00007f5113e72c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 000000000045a219
RDX: 0000000000000400 RSI: 00000000200002c0 RDI: ffffffffffffff9c
RBP: 000000000075c1c0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f5113e736d4
R13: 00000000004c6ece R14: 00000000004dc8e0 R15: 00000000ffffffff
INFO: task syz-executor.1:21276 blocked for more than 140 seconds.
      Not tainted 4.19.82 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.1  D29880 21276  20556 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x866/0x1dc0 kernel/sched/core.c:3515
 schedule+0x92/0x1c0 kernel/sched/core.c:3559
 __rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline]
 rwsem_down_write_failed+0x774/0xc30 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_nested+0x57/0x90 kernel/locking/rwsem.c:194
 inode_lock_nested include/linux/fs.h:782 [inline]
 filename_create+0x17c/0x4f0 fs/namei.c:3635
 user_path_create fs/namei.c:3693 [inline]
 do_mknodat fs/namei.c:3754 [inline]
 do_mknodat+0x1a4/0x480 fs/namei.c:3742
 __do_sys_mknod fs/namei.c:3794 [inline]
 __se_sys_mknod fs/namei.c:3792 [inline]
 __x64_sys_mknod+0x7b/0xb0 fs/namei.c:3792
 do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a219
Code: 80 00 00 00 0f 86 94 01 00 00 48 81 fb 00 01 00 00 0f 86 b3 01 00 00 80 3d d5 80 45 01 01 0f 84 83 00 00 00 f3 0f 7f 07 f3 0f <7f> 47 10 f3 0f 7f 47 20 f3 0f 7f 47 30 f3 0f 7f 47 40 f3 0f 7f 47
RSP: 002b:00007f5113e30c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000085
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a219
RDX: 0000000000000000 RSI: 0000000000002000 RDI: 00000000200004c0
RBP: 000000000075c310 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f5113e316d4
R13: 00000000004c6c08 R14: 00000000004dc340 R15: 00000000ffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/1039:
 #0: 000000003cc436aa (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x27e kernel/locking/lockdep.c:4438
3 locks held by rs:main Q:Reg/7512:
 #0: 00000000cd1b2069 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1821 [inline]
 #0: 00000000cd1b2069 (&rq->lock){-.-.}, at: __schedule+0x1f8/0x1dc0 kernel/sched/core.c:3453
 #1: 000000003cc436aa (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:423 [inline]
 #1: 000000003cc436aa (rcu_read_lock){....}, at: update_curr+0x2cc/0x8a0 kernel/sched/fair.c:828
 #2: 000000003cc436aa (rcu_read_lock){....}, at: fast_dput fs/dcache.c:714 [inline]
 #2: 000000003cc436aa (rcu_read_lock){....}, at: dput+0x38/0x670 fs/dcache.c:833
1 lock held by rsyslogd/7514:
 #0: 000000008c86436d (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110 fs/file.c:767
2 locks held by getty/7638:
 #0: 00000000cc65c332 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 00000000a39e7082 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7639:
 #0: 00000000d920236c (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 000000006bf2ff37 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7640:
 #0: 000000001285ea58 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 00000000a173fe0d (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7641:
 #0: 0000000008b507cc (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 0000000041216fd7 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7642:
 #0: 00000000586d1920 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 00000000c8eb3e66 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7643:
 #0: 00000000f413fbd3 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 00000000fd8c0d97 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by getty/7644:
 #0: 0000000052515cda (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:362
 #1: 0000000056dc3208 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b30 drivers/tty/n_tty.c:2154
2 locks held by syz-executor.1/21246:
 #0: 0000000052523c95 (sb_writers#22){.+.+}, at: sb_start_write include/linux/fs.h:1578 [inline]
 #0: 0000000052523c95 (sb_writers#22){.+.+}, at: mnt_want_write+0x3f/0xc0 fs/namespace.c:360
 #1: 0000000097429eef (&sb->s_type->i_mutex_key#26/1){+.+.}, at: inode_lock_nested include/linux/fs.h:782 [inline]
 #1: 0000000097429eef (&sb->s_type->i_mutex_key#26/1){+.+.}, at: filename_create+0x17c/0x4f0 fs/namei.c:3635
2 locks held by syz-executor.1/21273:
 #0: 0000000052523c95 (sb_writers#22){.+.+}, at: sb_start_write include/linux/fs.h:1578 [inline]
 #0: 0000000052523c95 (sb_writers#22){.+.+}, at: mnt_want_write+0x3f/0xc0 fs/namespace.c:360
 #1: 0000000097429eef (&sb->s_type->i_mutex_key#26){++++}, at: inode_lock include/linux/fs.h:747 [inline]
 #1: 0000000097429eef (&sb->s_type->i_mutex_key#26){++++}, at: vfs_setxattr+0x98/0x100 fs/xattr.c:218
1 lock held by syz-executor.1/21274:
 #0: 0000000097429eef (&sb->s_type->i_mutex_key#26){++++}, at: inode_lock_shared include/linux/fs.h:757 [inline]
 #0: 0000000097429eef (&sb->s_type->i_mutex_key#26){++++}, at: do_last fs/namei.c:3323 [inline]
 #0: 0000000097429eef (&sb->s_type->i_mutex_key#26){++++}, at: path_openat+0x1e59/0x45e0 fs/namei.c:3534
1 lock held by syz-executor.1/21275:
 #0: 00000000f9d427c5 (&type->s_umount_key#65/1){+.+.}, at: alloc_super fs/super.c:226 [inline]
 #0: 00000000f9d427c5 (&type->s_umount_key#65/1){+.+.}, at: sget_userns+0x208/0xd30 fs/super.c:519
2 locks held by syz-executor.1/21276:
 #0: 0000000052523c95 (sb_writers#22){.+.+}, at: sb_start_write include/linux/fs.h:1578 [inline]
 #0: 0000000052523c95 (sb_writers#22){.+.+}, at: mnt_want_write+0x3f/0xc0 fs/namespace.c:360
 #1: 0000000097429eef (&sb->s_type->i_mutex_key#26/1){+.+.}, at: inode_lock_nested include/linux/fs.h:782 [inline]
 #1: 0000000097429eef (&sb->s_type->i_mutex_key#26/1){+.+.}, at: filename_create+0x17c/0x4f0 fs/namei.c:3635

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 1039 Comm: khungtaskd Not tainted 4.19.82 #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+0x172/0x1f0 lib/dump_stack.c:113
 nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1b0/0x1f8 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+0x9df/0xee0 kernel/hung_task.c:287
 kthread+0x354/0x420 kernel/kthread.c:246
 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 skipped: idling at native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:60

Crashes (3):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2019/11/09 20:30 linux-4.19.y 5ee93551c703 1e35461e .config console log report ci2-linux-4-19
2019/10/11 13:03 linux-4.19.y 58fce2064530 1a3bad90 .config console log report ci2-linux-4-19
2019/09/08 10:01 linux-4.19.y e7d2672c66e4 a60cb4cd .config console log report ci2-linux-4-19
* Struck through repros no longer work on HEAD.