syzbot


INFO: task hung in lookup_slow

Status: auto-closed as invalid on 2020/10/13 00:17
Reported-by: syzbot+1df45df1ed6d62073fa6@syzkaller.appspotmail.com
First crash: 1411d, last: 1411d
Similar bugs (8)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in lookup_slow (2) fs 2 1703d 1715d 0/26 auto-closed as invalid on 2019/11/26 02:54
upstream INFO: task hung in lookup_slow exfat 148 1924d 2217d 0/26 closed as dup on 2018/09/08 15:36
linux-6.1 INFO: task hung in lookup_slow 4 4d09h 12d 0/3 upstream: reported on 2024/04/14 05:59
linux-5.15 INFO: task hung in lookup_slow (2) 9 1d23h 11d 0/3 upstream: reported on 2024/04/15 04:02
upstream INFO: task hung in lookup_slow (3) exfat arm C unreliable 285 1h56m 532d 0/26 upstream: reported C repro on 2022/11/11 00:21
android-49 INFO: task hung in lookup_slow syz 36 1729d 1839d 0/3 public: reported syz repro on 2019/04/14 08:51
linux-4.14 INFO: task hung in lookup_slow C error 1 602d 632d 0/1 upstream: reported C repro on 2022/08/03 08:46
linux-5.15 INFO: task hung in lookup_slow 2 375d 380d 0/3 auto-obsoleted due to no activity on 2023/08/15 05:05

Sample crash report:
audit: type=1804 audit(1592180050.464:1939): pid=13062 uid=0 auid=0 ses=4 subj=system_u:system_r:kernel_t:s0 op=invalid_pcr cause=ToMToU comm="syz-executor.4" name="/dev/vcsa" dev="sda1" ino=16339 res=1
NOHZ: local_softirq_pending 08
NOHZ: local_softirq_pending 08
INFO: task syz-executor.3:13055 blocked for more than 140 seconds.
      Not tainted 4.19.128-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3  D29424 13055   6729 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8a2/0x1fc0 kernel/sched/core.c:3515
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 __rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
 rwsem_down_read_failed+0x21a/0x3b0 kernel/locking/rwsem-xadd.c:309
 call_rwsem_down_read_failed+0x14/0x30 arch/x86/lib/rwsem.S:94
 __down_read arch/x86/include/asm/rwsem.h:83 [inline]
 down_read+0x45/0xb0 kernel/locking/rwsem.c:26
 inode_lock_shared include/linux/fs.h:758 [inline]
 lookup_slow+0x43/0x70 fs/namei.c:1688
 walk_component+0x759/0xd30 fs/namei.c:1811
 lookup_last fs/namei.c:2274 [inline]
 path_lookupat.isra.0+0x1f5/0x8c0 fs/namei.c:2319
 filename_lookup+0x1b0/0x410 fs/namei.c:2349
 user_path_at include/linux/namei.h:57 [inline]
 vfs_statx+0x113/0x1e0 fs/stat.c:185
 vfs_lstat include/linux/fs.h:3134 [inline]
 __do_sys_newlstat fs/stat.c:350 [inline]
 __se_sys_newlstat+0x96/0x120 fs/stat.c:344
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45ca69
Code: Bad RIP value.
RSP: 002b:00007f74366bdc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000006
RAX: ffffffffffffffda RBX: 00000000004f6300 RCX: 000000000045ca69
RDX: 0000000000000000 RSI: 0000000020000400 RDI: 00000000200003c0
RBP: 000000000078c040 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000744 R14: 00000000004ca38d R15: 00007f74366be6d4
INFO: task syz-executor.3:13056 blocked for more than 140 seconds.
      Not tainted 4.19.128-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3  D29536 13056   6729 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8a2/0x1fc0 kernel/sched/core.c:3515
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 __rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
 rwsem_down_read_failed+0x21a/0x3b0 kernel/locking/rwsem-xadd.c:309
 call_rwsem_down_read_failed+0x14/0x30 arch/x86/lib/rwsem.S:94
 __down_read arch/x86/include/asm/rwsem.h:83 [inline]
 down_read+0x45/0xb0 kernel/locking/rwsem.c:26
 inode_lock_shared include/linux/fs.h:758 [inline]
 lookup_slow+0x43/0x70 fs/namei.c:1688
 walk_component+0x759/0xd30 fs/namei.c:1811
 lookup_last fs/namei.c:2274 [inline]
 path_lookupat.isra.0+0x1f5/0x8c0 fs/namei.c:2319
 filename_lookup+0x1b0/0x410 fs/namei.c:2349
 user_path include/linux/namei.h:62 [inline]
 do_mount+0x13d/0x2b50 fs/namespace.c:2739
 ksys_mount+0xd7/0x150 fs/namespace.c:3015
 __do_sys_mount fs/namespace.c:3029 [inline]
 __se_sys_mount fs/namespace.c:3026 [inline]
 __x64_sys_mount+0xba/0x150 fs/namespace.c:3026
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45ca69
Code: Bad RIP value.
RSP: 002b:00007f743669cc78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000004f6cc0 RCX: 000000000045ca69
RDX: 0000000020000380 RSI: 0000000020000340 RDI: 00000000200001c0
RBP: 000000000078c0e0 R08: 0000000020000480 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 000000000000076b R14: 00000000004ca563 R15: 00007f743669d6d4
INFO: task syz-executor.3:13057 blocked for more than 140 seconds.
      Not tainted 4.19.128-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3  D29744 13057   6729 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8a2/0x1fc0 kernel/sched/core.c:3515
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 __rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
 rwsem_down_read_failed+0x21a/0x3b0 kernel/locking/rwsem-xadd.c:309
 call_rwsem_down_read_failed+0x14/0x30 arch/x86/lib/rwsem.S:94
 __down_read arch/x86/include/asm/rwsem.h:83 [inline]
 down_read+0x45/0xb0 kernel/locking/rwsem.c:26
 inode_lock_shared include/linux/fs.h:758 [inline]
 lookup_slow+0x43/0x70 fs/namei.c:1688
 walk_component+0x759/0xd30 fs/namei.c:1811
 lookup_last fs/namei.c:2274 [inline]
 path_lookupat.isra.0+0x1f5/0x8c0 fs/namei.c:2319
 filename_lookup+0x1b0/0x410 fs/namei.c:2349
 user_path_at include/linux/namei.h:57 [inline]
 ksys_chdir+0x84/0x1d0 fs/open.c:458
 __do_sys_chdir fs/open.c:480 [inline]
 __se_sys_chdir fs/open.c:478 [inline]
 __x64_sys_chdir+0x2d/0x40 fs/open.c:478
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45ca69
Code: Bad RIP value.
RSP: 002b:00007f743667bc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000050
RAX: ffffffffffffffda RBX: 00000000004dae80 RCX: 000000000045ca69
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000040
RBP: 000000000078c180 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000072 R14: 00000000004c25a9 R15: 00007f743667c6d4
INFO: task syz-executor.3:13059 blocked for more than 140 seconds.
      Not tainted 4.19.128-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3  D29536 13059   6729 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8a2/0x1fc0 kernel/sched/core.c:3515
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 __rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
 rwsem_down_read_failed+0x21a/0x3b0 kernel/locking/rwsem-xadd.c:309
 call_rwsem_down_read_failed+0x14/0x30 arch/x86/lib/rwsem.S:94
 __down_read arch/x86/include/asm/rwsem.h:83 [inline]
 down_read+0x45/0xb0 kernel/locking/rwsem.c:26
 inode_lock_shared include/linux/fs.h:758 [inline]
 lookup_slow+0x43/0x70 fs/namei.c:1688
 walk_component+0x759/0xd30 fs/namei.c:1811
 lookup_last fs/namei.c:2274 [inline]
 path_lookupat.isra.0+0x1f5/0x8c0 fs/namei.c:2319
 filename_lookup+0x1b0/0x410 fs/namei.c:2349
 user_path include/linux/namei.h:62 [inline]
 do_mount+0x13d/0x2b50 fs/namespace.c:2739
 ksys_mount+0xd7/0x150 fs/namespace.c:3015
 __do_sys_mount fs/namespace.c:3029 [inline]
 __se_sys_mount fs/namespace.c:3026 [inline]
 __x64_sys_mount+0xba/0x150 fs/namespace.c:3026
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45ca69
Code: Bad RIP value.
RSP: 002b:00007f743665ac78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000004f6c40 RCX: 000000000045ca69
RDX: 0000000020000140 RSI: 0000000020000000 RDI: 0000000000000000
RBP: 000000000078c220 R08: 0000000020000300 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000769 R14: 00000000004ca549 R15: 00007f743665b6d4
INFO: task syz-executor.3:13061 blocked for more than 140 seconds.
      Not tainted 4.19.128-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3  D29904 13061   6729 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x8a2/0x1fc0 kernel/sched/core.c:3515
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 __rwsem_down_read_failed_common kernel/locking/rwsem-xadd.c:292 [inline]
 rwsem_down_read_failed+0x21a/0x3b0 kernel/locking/rwsem-xadd.c:309
 call_rwsem_down_read_failed+0x14/0x30 arch/x86/lib/rwsem.S:94
 __down_read arch/x86/include/asm/rwsem.h:83 [inline]
 down_read+0x45/0xb0 kernel/locking/rwsem.c:26
 inode_lock_shared include/linux/fs.h:758 [inline]
 do_last fs/namei.c:3326 [inline]
 path_openat+0x1a04/0x2eb0 fs/namei.c:3537
 do_filp_open+0x1a1/0x280 fs/namei.c:3567
 do_sys_open+0x3aa/0x510 fs/open.c:1085
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45ca69
Code: Bad RIP value.
RSP: 002b:00007f7436639c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00000000004f7600 RCX: 000000000045ca69
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000080
RBP: 000000000078c2c0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000790 R14: 00000000005261df R15: 00007f743663a6d4

Showing all locks held in the system:
1 lock held by khungtaskd/1079:
 #0: 000000004d5ba277 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
1 lock held by in:imklog/6124:
2 locks held by agetty/6135:
 #0: 000000003d8823c1 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
 #1: 00000000bc0ae9c6 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2154
2 locks held by syz-executor.3/13033:
 #0: 0000000018b257c5 (sb_writers#25){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
 #0: 0000000018b257c5 (sb_writers#25){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
 #1: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30/1){+.+.}, at: inode_lock_nested include/linux/fs.h:783 [inline]
 #1: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30/1){+.+.}, at: filename_create+0x15a/0x490 fs/namei.c:3638
1 lock held by syz-executor.3/13055:
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: inode_lock_shared include/linux/fs.h:758 [inline]
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: lookup_slow+0x43/0x70 fs/namei.c:1688
1 lock held by syz-executor.3/13056:
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: inode_lock_shared include/linux/fs.h:758 [inline]
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: lookup_slow+0x43/0x70 fs/namei.c:1688
1 lock held by syz-executor.3/13057:
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: inode_lock_shared include/linux/fs.h:758 [inline]
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: lookup_slow+0x43/0x70 fs/namei.c:1688
1 lock held by syz-executor.3/13059:
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: inode_lock_shared include/linux/fs.h:758 [inline]
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: lookup_slow+0x43/0x70 fs/namei.c:1688
1 lock held by syz-executor.3/13061:
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: inode_lock_shared include/linux/fs.h:758 [inline]
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: do_last fs/namei.c:3326 [inline]
 #0: 00000000ad4cc8dc (&sb->s_type->i_mutex_key#30){++++}, at: path_openat+0x1a04/0x2eb0 fs/namei.c:3537

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

NMI backtrace for cpu 1
CPU: 1 PID: 1079 Comm: khungtaskd Not tainted 4.19.128-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+0x1fc/0x2fe lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1a6/0x1eb lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
 watchdog+0x962/0xe40 kernel/hung_task.c:287
 kthread+0x30b/0x410 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
CPU: 0 PID: 2036 Comm: kworker/u4:2 Not tainted 4.19.128-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: bat_events batadv_nc_worker
RIP: 0010:bytes_is_nonzero mm/kasan/kasan.c:167 [inline]
RIP: 0010:memory_is_nonzero mm/kasan/kasan.c:184 [inline]
RIP: 0010:memory_is_poisoned_n mm/kasan/kasan.c:210 [inline]
RIP: 0010:memory_is_poisoned mm/kasan/kasan.c:241 [inline]
RIP: 0010:check_memory_region_inline mm/kasan/kasan.c:257 [inline]
RIP: 0010:check_memory_region+0x108/0x180 mm/kasan/kasan.c:267
Code: 4a 8d 04 23 4d 85 c0 75 25 49 89 e9 49 29 c1 e9 74 ff ff ff 4d 85 c9 74 be 49 01 d9 eb 09 48 83 c0 01 4c 39 c8 74 b0 80 38 00 <74> f2 eb a4 4c 89 c0 49 39 c2 74 4b 5b 5d 41 5c e9 93 0f 00 00 49
RSP: 0018:ffff8882118bfa10 EFLAGS: 00000046
RAX: fffffbfff164e629 RBX: fffffbfff164e629 RCX: ffffffff814fbda8
RDX: 0000000000000001 RSI: 0000000000000004 RDI: ffffffff8b273148
RBP: fffffbfff164e62a R08: 0000000000000001 R09: fffffbfff164e62a
R10: fffffbfff164e629 R11: ffffffff8b27314b R12: 0000000000000000
R13: 0000000000000000 R14: ffff888051488200 R15: ffff8880921c1030
FS:  0000000000000000(0000) GS:ffff8880ae600000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f8094834000 CR3: 0000000096262000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 atomic_inc include/asm-generic/atomic-instrumented.h:109 [inline]
 __lock_acquire+0x278/0x4380 kernel/locking/lockdep.c:3307
 lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3907
 __raw_spin_lock_bh include/linux/spinlock_api_smp.h:135 [inline]
 _raw_spin_lock_bh+0x2f/0x40 kernel/locking/spinlock.c:168
 spin_lock_bh include/linux/spinlock.h:334 [inline]
 batadv_nc_purge_paths+0xdc/0x340 net/batman-adv/network-coding.c:453
 batadv_nc_worker+0x299/0x760 net/batman-adv/network-coding.c:731
 process_one_work+0x892/0x1580 kernel/workqueue.c:2155
 worker_thread+0x96/0xe20 kernel/workqueue.c:2298
 kthread+0x30b/0x410 kernel/kthread.c:246
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2020/06/15 00:16 linux-4.19.y 3fc898571b97 2a22c77a .config console log report ci2-linux-4-19
* Struck through repros no longer work on HEAD.