syzbot


INFO: task hung in chmod_common (2)

Status: auto-closed as invalid on 2021/04/20 00:40
Subsystems: fs
[Documentation on labels]
First crash: 1185d, last: 1185d
Similar bugs (4)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in chmod_common (4) exfat 2 165d 212d 0/26 auto-obsoleted due to no activity on 2024/02/03 21:30
android-49 INFO: task hung in chmod_common 9 1906d 1832d 0/3 auto-closed as invalid on 2019/07/28 15:36
upstream INFO: task hung in chmod_common (3) arm 1 434d 434d 0/26 auto-obsoleted due to no activity on 2023/05/10 13:01
upstream INFO: task hung in chmod_common fs 21 2003d 2104d 0/26 closed as invalid on 2018/12/31 08:00

Sample crash report:
INFO: task syz-executor.1:10024 blocked for more than 143 seconds.
      Not tainted 5.11.0-rc4-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.1  state:D stack:29656 pid:10024 ppid:  8488 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4313 [inline]
 __schedule+0x90c/0x21a0 kernel/sched/core.c:5064
 schedule+0xcf/0x270 kernel/sched/core.c:5143
 rwsem_down_write_slowpath+0x7e5/0x1200 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+0x132/0x150 kernel/locking/rwsem.c:1407
 inode_lock include/linux/fs.h:773 [inline]
 chmod_common+0x14b/0x3f0 fs/open.c:577
 do_fchmodat+0xb5/0x140 fs/open.c:621
 __do_sys_chmod fs/open.c:639 [inline]
 __se_sys_chmod fs/open.c:637 [inline]
 __x64_sys_chmod+0x58/0x80 fs/open.c:637
 do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
 entry_SYSCALL_64_after_hwframe+0x44/0xa9
RIP: 0033:0x45e219
RSP: 002b:00007f3d48107c68 EFLAGS: 00000246 ORIG_RAX: 000000000000005a
RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 000000000045e219
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000080
RBP: 000000000119c108 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000119c0dc
R13: 00007ffc05ed1e9f R14: 00007f3d481089c0 R15: 000000000119c0dc
INFO: task syz-executor.1:10027 blocked for more than 143 seconds.
      Not tainted 5.11.0-rc4-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.1  state:D stack:27288 pid:10027 ppid:  8488 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4313 [inline]
 __schedule+0x90c/0x21a0 kernel/sched/core.c:5064
 schedule+0xcf/0x270 kernel/sched/core.c:5143
 rwsem_down_write_slowpath+0x7e5/0x1200 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+0x132/0x150 kernel/locking/rwsem.c:1407
 inode_lock include/linux/fs.h:773 [inline]
 lock_mount+0x8a/0x2e0 fs/namespace.c:2198
 do_new_mount_fc fs/namespace.c:2824 [inline]
 do_new_mount fs/namespace.c:2883 [inline]
 path_mount+0x1787/0x20c0 fs/namespace.c:3211
 do_mount fs/namespace.c:3224 [inline]
 __do_sys_mount fs/namespace.c:3432 [inline]
 __se_sys_mount fs/namespace.c:3409 [inline]
 __x64_sys_mount+0x27f/0x300 fs/namespace.c:3409
 do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
 entry_SYSCALL_64_after_hwframe+0x44/0xa9
RIP: 0033:0x45e219
RSP: 002b:00007f3d480e6c68 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 000000000045e219
RDX: 0000000020002100 RSI: 00000000200020c0 RDI: 0000000000000000
RBP: 000000000119c1c8 R08: 0000000020000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000119c184
R13: 00007ffc05ed1e9f R14: 00007f3d480e79c0 R15: 000000000119c184
INFO: task syz-executor.1:10053 blocked for more than 144 seconds.
      Not tainted 5.11.0-rc4-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.1  state:D stack:29496 pid:10053 ppid:  8488 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4313 [inline]
 __schedule+0x90c/0x21a0 kernel/sched/core.c:5064
 schedule+0xcf/0x270 kernel/sched/core.c:5143
 rwsem_down_write_slowpath+0x7e5/0x1200 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+0x132/0x150 kernel/locking/rwsem.c:1407
 inode_lock include/linux/fs.h:773 [inline]
 chmod_common+0x14b/0x3f0 fs/open.c:577
 do_fchmodat+0xb5/0x140 fs/open.c:621
 __do_sys_chmod fs/open.c:639 [inline]
 __se_sys_chmod fs/open.c:637 [inline]
 __x64_sys_chmod+0x58/0x80 fs/open.c:637
 do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
 entry_SYSCALL_64_after_hwframe+0x44/0xa9
RIP: 0033:0x45e219
RSP: 002b:00007f3d480a4c68 EFLAGS: 00000246 ORIG_RAX: 000000000000005a
RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 000000000045e219
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000080
RBP: 000000000119c300 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000119c2d4
R13: 00007ffc05ed1e9f R14: 00007f3d480a59c0 R15: 000000000119c2d4

Showing all locks held in the system:
1 lock held by khungtaskd/1646:
 #0: ffffffff8b373920 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6254
1 lock held by in:imklog/8306:
 #0: ffff888027df4ff0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:947
3 locks held by syz-executor.1/10018:
2 locks held by syz-executor.1/10024:
 #0: ffff888073e24460 (sb_writers#14){.+.+}-{0:0}, at: chmod_common+0xf4/0x3f0 fs/open.c:573
 #1: ffff888076d70810 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: inode_lock include/linux/fs.h:773 [inline]
 #1: ffff888076d70810 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: chmod_common+0x14b/0x3f0 fs/open.c:577
1 lock held by syz-executor.1/10027:
 #0: ffff888076d70810 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: inode_lock include/linux/fs.h:773 [inline]
 #0: ffff888076d70810 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: lock_mount+0x8a/0x2e0 fs/namespace.c:2198
2 locks held by syz-executor.1/10053:
 #0: ffff888073e24460 (sb_writers#14){.+.+}-{0:0}, at: chmod_common+0xf4/0x3f0 fs/open.c:573
 #1: ffff888076d70810 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: inode_lock include/linux/fs.h:773 [inline]
 #1: ffff888076d70810 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: chmod_common+0x14b/0x3f0 fs/open.c:577

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

NMI backtrace for cpu 0
CPU: 0 PID: 1646 Comm: khungtaskd Not tainted 5.11.0-rc4-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:79 [inline]
 dump_stack+0x107/0x163 lib/dump_stack.c:120
 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:209 [inline]
 watchdog+0xd43/0xfa0 kernel/hung_task.c:294
 kthread+0x3b1/0x4a0 kernel/kthread.c:292
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 3156 Comm: kworker/u4:4 Not tainted 5.11.0-rc4-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue:  0x0 (bat_events)
RIP: 0010:__sanitizer_cov_trace_pc+0x37/0x60 kernel/kcov.c:197
Code: 81 e1 00 01 00 00 65 48 8b 14 25 00 f0 01 00 a9 00 01 ff 00 74 0e 85 c9 74 35 8b 82 cc 14 00 00 85 c0 74 2b 8b 82 a8 14 00 00 <83> f8 02 75 20 48 8b 8a b0 14 00 00 8b 92 ac 14 00 00 48 8b 01 48
RSP: 0018:ffffc900025c7d18 EFLAGS: 00000046
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 0000000000000000
RDX: ffff88801a819bc0 RSI: ffffffff8149da84 RDI: 0000000000000003
RBP: ffff88801b703448 R08: 0000000000000000 R09: ffff88801b70344f
R10: ffffffff8149da72 R11: 0000000000000000 R12: ffff88802188a805
R13: ffff88801aba1400 R14: ffff88801aba1400 R15: ffff888010069800
FS:  0000000000000000(0000) GS:ffff8880b9f00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f0a13ef64a0 CR3: 0000000012c47000 CR4: 0000000000350ee0
Call Trace:
 get_work_pwq kernel/workqueue.c:686 [inline]
 process_one_work+0xc4/0x15f0 kernel/workqueue.c:2172
 worker_thread+0x64c/0x1120 kernel/workqueue.c:2421
 kthread+0x3b1/0x4a0 kernel/kthread.c:292
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296

Crashes (2):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/01/20 00:34 upstream 1e2a199f6ccd 63631df1 .config console log report info ci-upstream-kasan-gce-root INFO: task hung in chmod_common
2021/01/20 00:34 upstream 1e2a199f6ccd 63631df1 .config console log report info ci-upstream-kasan-gce-selinux-root INFO: task hung in chmod_common
* Struck through repros no longer work on HEAD.