syzbot


INFO: task hung in lock_rename (3)

Status: auto-closed as invalid on 2021/10/02 18:08
Reported-by: syzbot+@syzkaller.appspotmail.com
First crash: 519d, last: 519d
similar bugs (7):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in lock_rename 53 1466d 1600d 0/24 auto-closed as invalid on 2019/05/30 13:09
upstream INFO: task hung in lock_rename (4) 3 308d 402d 0/24 closed as invalid on 2022/02/08 09:40
linux-4.19 INFO: task hung in lock_rename 1 332d 332d 0/1 auto-closed as invalid on 2022/05/08 10:15
upstream INFO: task hung in lock_rename (5) 2 264d 293d 0/24 auto-closed as invalid on 2022/06/15 04:15
upstream INFO: task hung in lock_rename (2) 7 670d 694d 0/24 auto-closed as invalid on 2021/05/17 09:31
linux-4.14 INFO: task hung in lock_rename 1 177d 177d 0/1 auto-obsoleted due to no activity on 2022/10/10 13:20
android-49 INFO: task hung in lock_rename C 9 1561d 1332d 0/3 public: reported C repro on 2019/04/14 00:00

Sample crash report:
INFO: task syz-executor.5:22399 blocked for more than 143 seconds.
      Not tainted 5.13.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5  state:D stack:29856 pid:22399 ppid: 26398 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4683 [inline]
 __schedule+0x934/0x2710 kernel/sched/core.c:5940
 schedule+0xd3/0x270 kernel/sched/core.c:6019
 rwsem_down_write_slowpath+0x7c2/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_nested+0x13b/0x150 kernel/locking/rwsem.c:1517
 inode_lock_nested include/linux/fs.h:809 [inline]
 lock_rename+0x225/0x280 fs/namei.c:2846
 do_renameat2+0x61c/0xcb0 fs/namei.c:4637
 __do_sys_rename fs/namei.c:4745 [inline]
 __se_sys_rename fs/namei.c:4743 [inline]
 __x64_sys_rename+0x7d/0xa0 fs/namei.c:4743
 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:0x4665d9
RSP: 002b:00007f5b91caa188 EFLAGS: 00000246 ORIG_RAX: 0000000000000052
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000020000140 RDI: 0000000020000080
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd28ea364f R14: 00007f5b91caa300 R15: 0000000000022000
INFO: task syz-executor.5:22432 blocked for more than 143 seconds.
      Not tainted 5.13.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5  state:D stack:28904 pid:22432 ppid: 26398 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4683 [inline]
 __schedule+0x934/0x2710 kernel/sched/core.c:5940
 schedule+0xd3/0x270 kernel/sched/core.c:6019
 rwsem_down_read_slowpath+0x4c5/0x990 kernel/locking/rwsem.c:992
 __down_read_common kernel/locking/rwsem.c:1213 [inline]
 __down_read kernel/locking/rwsem.c:1222 [inline]
 down_read+0xe4/0x440 kernel/locking/rwsem.c:1355
 inode_lock_shared include/linux/fs.h:784 [inline]
 lookup_slow fs/namei.c:1645 [inline]
 walk_component+0x409/0x6a0 fs/namei.c:1942
 lookup_last fs/namei.c:2397 [inline]
 path_lookupat+0x1ba/0x850 fs/namei.c:2421
 filename_lookup+0x1b1/0x570 fs/namei.c:2454
 user_path_at include/linux/namei.h:60 [inline]
 __do_sys_chdir fs/open.c:493 [inline]
 __se_sys_chdir fs/open.c:487 [inline]
 __x64_sys_chdir+0xb7/0x260 fs/open.c:487
 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:0x4665d9
RSP: 002b:00007f5b91c89188 EFLAGS: 00000246 ORIG_RAX: 0000000000000050
RAX: ffffffffffffffda RBX: 000000000056c0f0 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00000000200000c0
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c0f0
R13: 00007ffd28ea364f R14: 00007f5b91c89300 R15: 0000000000022000
INFO: task syz-executor.5:22433 blocked for more than 144 seconds.
      Not tainted 5.13.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.5  state:D stack:29936 pid:22433 ppid: 26398 flags:0x00000004
Call Trace:
 context_switch kernel/sched/core.c:4683 [inline]
 __schedule+0x934/0x2710 kernel/sched/core.c:5940
 schedule+0xd3/0x270 kernel/sched/core.c:6019
 rwsem_down_write_slowpath+0x7c2/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_nested+0x13b/0x150 kernel/locking/rwsem.c:1517
 inode_lock_nested include/linux/fs.h:809 [inline]
 filename_create+0x15a/0x490 fs/namei.c:3592
 user_path_create fs/namei.c:3650 [inline]
 do_mknodat.part.0+0xcd/0x4c0 fs/namei.c:3731
 do_mknodat fs/namei.c:3773 [inline]
 __do_sys_mknod fs/namei.c:3775 [inline]
 __se_sys_mknod fs/namei.c:3773 [inline]
 __x64_sys_mknod+0xf8/0x120 fs/namei.c:3773
 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:0x4665d9
RSP: 002b:00007f5b91c68188 EFLAGS: 00000246 ORIG_RAX: 0000000000000085
RAX: ffffffffffffffda RBX: 000000000056c1a8 RCX: 00000000004665d9
RDX: 000000000000070b RSI: 00000000ffffc000 RDI: 0000000020000180
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c1a8
R13: 00007ffd28ea364f R14: 00007f5b91c68300 R15: 0000000000022000

Showing all locks held in the system:
1 lock held by khungtaskd/1629:
 #0: ffffffff8c179140 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6446
1 lock held by in:imklog/8168:
 #0: ffff888019143670 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:974
1 lock held by systemd-udevd/26652:
 #0: ffff8880b9c35b58 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:460 [inline]
 #0: ffff8880b9c35b58 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock kernel/sched/sched.h:1307 [inline]
 #0: ffff8880b9c35b58 (&rq->__lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1610 [inline]
 #0: ffff8880b9c35b58 (&rq->__lock){-.-.}-{2:2}, at: __schedule+0x22d/0x2710 kernel/sched/core.c:5854
1 lock held by systemd-udevd/26763:
2 locks held by syz-executor.5/22392:
2 locks held by syz-executor.5/22399:
 #0: ffff88809ab40460 (sb_writers#18){.+.+}-{0:0}, at: do_renameat2+0x386/0xcb0 fs/namei.c:4632
 #1: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8/1){+.+.}-{3:3}, at: inode_lock_nested include/linux/fs.h:809 [inline]
 #1: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8/1){+.+.}-{3:3}, at: lock_rename+0x225/0x280 fs/namei.c:2846
1 lock held by syz-executor.5/22432:
 #0: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: inode_lock_shared include/linux/fs.h:784 [inline]
 #0: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: lookup_slow fs/namei.c:1645 [inline]
 #0: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: walk_component+0x409/0x6a0 fs/namei.c:1942
2 locks held by syz-executor.5/22433:
 #0: ffff88809ab40460 (sb_writers#18){.+.+}-{0:0}, at: filename_create+0x104/0x490 fs/namei.c:3587
 #1: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8/1){+.+.}-{3:3}, at: inode_lock_nested include/linux/fs.h:809 [inline]
 #1: ffff8880b2d96bc0 (&type->i_mutex_dir_key#8/1){+.+.}-{3:3}, at: filename_create+0x15a/0x490 fs/namei.c:3592

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

NMI backtrace for cpu 0
CPU: 0 PID: 1629 Comm: khungtaskd Not tainted 5.13.0-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_lvl+0xcd/0x134 lib/dump_stack.c:96
 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+0xd4b/0xfb0 kernel/hung_task.c:294
 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: 11334 Comm: kworker/u4:8 Not tainted 5.13.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: bat_events batadv_nc_worker
RIP: 0010:check_preemption_disabled+0x2/0x140 lib/smp_processor_id.c:13
Code: 1f 44 00 00 e8 9f d8 75 f8 65 48 8b 3c 25 00 f0 01 00 e8 d1 83 2f f8 eb 99 0f 1f 44 00 00 0f 0b e9 23 ff ff ff cc cc cc 41 56 <41> 55 49 89 f5 41 54 55 48 89 fd 53 0f 1f 44 00 00 65 44 8b 25 ed
RSP: 0018:ffffc900099a7b70 EFLAGS: 00000046
RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffffffff898bc160 RDI: ffffffff89e2e860
RBP: ffffffff8c179080 R08: 0000000000000000 R09: ffffffff8deb2457
R10: fffffbfff1bd648a R11: 0000000000000000 R12: ffff8880366ba000
R13: 0000000000000000 R14: 00000000ffffffff R15: ffff8880366baa40
FS:  0000000000000000(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000055aedda5a020 CR3: 00000000242e5000 CR4: 00000000001506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 lockdep_recursion_finish kernel/locking/lockdep.c:438 [inline]
 lock_is_held_type+0xd5/0x140 kernel/locking/lockdep.c:5669
 lock_is_held include/linux/lockdep.h:283 [inline]
 rcu_read_lock_sched_held+0x3a/0x70 kernel/rcu/update.c:125
 trace_lock_release include/trace/events/lock.h:58 [inline]
 lock_release+0x5d3/0x720 kernel/locking/lockdep.c:5636
 rcu_lock_release include/linux/rcupdate.h:272 [inline]
 rcu_read_unlock include/linux/rcupdate.h:725 [inline]
 batadv_nc_purge_orig_hash net/batman-adv/network-coding.c:408 [inline]
 batadv_nc_worker+0x7a3/0xe50 net/batman-adv/network-coding.c:715
 process_one_work+0x98d/0x1630 kernel/workqueue.c:2276
 worker_thread+0x658/0x11f0 kernel/workqueue.c:2422
 kthread+0x3e5/0x4d0 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce-selinux-root 2021/07/04 18:01 upstream 3dbdb38e2869 55aa55c2 .config log report info INFO: task hung in lock_rename
* Struck through repros no longer work on HEAD.