syzbot


INFO: task hung in path_openat (6)

Status: closed as invalid on 2022/02/07 19:19
Reported-by: syzbot+@syzkaller.appspotmail.com
First crash: 477d, last: 327d
similar bugs (12):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 INFO: task hung in path_openat 2 1180d 1213d 0/1 auto-closed as invalid on 2020/01/11 07:40
upstream INFO: task hung in path_openat (4) 1 951d 951d 0/24 auto-closed as invalid on 2020/07/28 10:23
upstream INFO: task hung in path_openat (3) 4 1110d 1227d 0/24 auto-closed as invalid on 2020/02/19 20:16
upstream INFO: task hung in path_openat (7) 114 5d10h 61d 0/24 upstream: reported on 2022/10/06 10:29
linux-4.19 INFO: task hung in path_openat (2) 1 380d 380d 0/1 auto-closed as invalid on 2022/03/21 04:47
linux-4.14 INFO: task hung in path_openat 1 620d 620d 0/1 auto-closed as invalid on 2021/07/23 23:26
android-49 INFO: task hung in path_openat 64 1351d 1332d 0/3 auto-closed as invalid on 2019/09/22 08:41
upstream INFO: task hung in path_openat (5) 23 591d 752d 0/24 auto-closed as invalid on 2021/07/22 20:44
linux-4.19 INFO: task hung in path_openat (3) 7 5d10h 116d 0/1 upstream: reported on 2022/08/11 13:14
android-414 INFO: task hung in path_openat 42 1254d 1333d 0/1 auto-closed as invalid on 2019/10/28 21:04
upstream INFO: task hung in path_openat 246 1418d 1708d 0/24 closed as dup on 2018/09/08 15:37
upstream INFO: task hung in path_openat (2) 1 1321d 1321d 0/24 closed as invalid on 2019/05/08 13:05

Sample crash report:
INFO: task syz-executor.4:1108 blocked for more than 143 seconds.
      Not tainted 5.16.0-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.4  state:D stack:29288 pid: 1108 ppid:   966 flags:0x00000004
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:4986 [inline]
 __schedule+0xab2/0x4f00 kernel/sched/core.c:6296
 schedule+0xd2/0x260 kernel/sched/core.c:6369
 rwsem_down_write_slowpath+0x634/0x1110 kernel/locking/rwsem.c:1142
 __down_write_common kernel/locking/rwsem.c:1259 [inline]
 __down_write_common kernel/locking/rwsem.c:1256 [inline]
 __down_write kernel/locking/rwsem.c:1268 [inline]
 down_write+0x135/0x150 kernel/locking/rwsem.c:1515
 inode_lock include/linux/fs.h:784 [inline]
 open_last_lookups fs/namei.c:3347 [inline]
 path_openat+0x94b/0x2940 fs/namei.c:3556
 do_filp_open+0x1aa/0x400 fs/namei.c:3586
 do_sys_openat2+0x16d/0x4d0 fs/open.c:1214
 do_sys_open fs/open.c:1230 [inline]
 __do_sys_open fs/open.c:1238 [inline]
 __se_sys_open fs/open.c:1234 [inline]
 __x64_sys_open+0x119/0x1c0 fs/open.c:1234
 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:0x7f0f8ed3deb9
RSP: 002b:00007f0f8d62f168 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007f0f8ee512a0 RCX: 00007f0f8ed3deb9
RDX: 000000000000007a RSI: 00000000001401c3 RDI: 00000000200000c0
RBP: 00007f0f8ed9808d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffc18d2143f R14: 00007f0f8d62f300 R15: 0000000000022000
 </TASK>

Showing all locks held in the system:
1 lock held by khungtaskd/27:
 #0: ffffffff8bb851e0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6460
2 locks held by getty/3284:
 #0: ffff8880239ef098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:244
 #1: ffffc90002b962e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xcf0/0x1230 drivers/tty/n_tty.c:2077
1 lock held by syz-executor.4/1107:
2 locks held by syz-executor.4/1108:
 #0: ffff88803406e460 (sb_writers#14){.+.+}-{0:0}, at: open_last_lookups fs/namei.c:3339 [inline]
 #0: ffff88803406e460 (sb_writers#14){.+.+}-{0:0}, at: path_openat+0x28d1/0x2940 fs/namei.c:3556
 #1: ffff88803887a170 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: inode_lock include/linux/fs.h:784 [inline]
 #1: ffff88803887a170 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: open_last_lookups fs/namei.c:3347 [inline]
 #1: ffff88803887a170 (&type->i_mutex_dir_key#8){++++}-{3:3}, at: path_openat+0x94b/0x2940 fs/namei.c:3556

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

NMI backtrace for cpu 1
CPU: 1 PID: 27 Comm: khungtaskd Not tainted 5.16.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
 nmi_cpu_backtrace.cold+0x47/0x144 lib/nmi_backtrace.c:111
 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:210 [inline]
 watchdog+0xc1d/0xf50 kernel/hung_task.c:295
 kthread+0x405/0x4f0 kernel/kthread.c:327
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 20517 Comm: kworker/u4:12 Not tainted 5.16.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: phy9 ieee80211_iface_work
RIP: 0010:__orc_find+0x45/0xf0 arch/x86/kernel/unwind_orc.c:49
Code: 00 00 4c 39 e7 49 89 fd 0f 87 9a 00 00 00 49 bf 00 00 00 00 00 fc ff df 49 89 fe 48 89 fd eb 0c 48 8d 6b 04 49 89 de 49 39 ec <72> 4d 4c 89 e0 48 29 e8 48 89 c2 48 c1 e8 3f 48 c1 fa 02 48 01 d0
RSP: 0018:ffffc90011b4f410 EFLAGS: 00000297
RAX: ffffffff889ab3d9 RBX: ffffffff8dfefc88 RCX: ffffffff889abda8
RDX: 0000000000000000 RSI: ffffffff8e9d196e RDI: ffffffff8dfefc88
RBP: ffffffff8dfefc8c R08: ffffffff8e9d196e R09: 0000000000000001
R10: fffff52002369eb1 R11: 000000000008808a R12: ffffffff8dfefc88
R13: ffffffff8dfefc88 R14: ffffffff8dfefc88 R15: dffffc0000000000
FS:  0000000000000000(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000c01473ebc0 CR3: 000000001cc79000 CR4: 0000000000350ef0
Call Trace:
 <TASK>
 orc_find arch/x86/kernel/unwind_orc.c:173 [inline]
 unwind_next_frame+0x32a/0x1ce0 arch/x86/kernel/unwind_orc.c:443
 arch_stack_walk+0x7d/0xe0 arch/x86/kernel/stacktrace.c:25
 stack_trace_save+0x8c/0xc0 kernel/stacktrace.c:122
 kasan_save_stack+0x1e/0x50 mm/kasan/common.c:38
 kasan_set_track+0x21/0x30 mm/kasan/common.c:46
 kasan_set_free_info+0x20/0x30 mm/kasan/generic.c:370
 ____kasan_slab_free mm/kasan/common.c:367 [inline]
 ____kasan_slab_free+0x130/0x160 mm/kasan/common.c:329
 kasan_slab_free include/linux/kasan.h:236 [inline]
 slab_free_hook mm/slub.c:1728 [inline]
 slab_free_freelist_hook+0x8b/0x1c0 mm/slub.c:1754
 slab_free mm/slub.c:3509 [inline]
 kfree+0xcb/0x280 mm/slub.c:4562
 ieee802_11_parse_elems_crc+0xb15/0x1050 net/mac80211/util.c:1535
 ieee802_11_parse_elems net/mac80211/ieee80211_i.h:2228 [inline]
 ieee80211_rx_mgmt_probe_beacon net/mac80211/ibss.c:1605 [inline]
 ieee80211_ibss_rx_queued_mgmt+0xd0e/0x3150 net/mac80211/ibss.c:1639
 ieee80211_iface_process_skb net/mac80211/iface.c:1527 [inline]
 ieee80211_iface_work+0xa69/0xd00 net/mac80211/iface.c:1581
 process_one_work+0x9ac/0x1650 kernel/workqueue.c:2307
 worker_thread+0x657/0x1110 kernel/workqueue.c:2454
 kthread+0x405/0x4f0 kernel/kthread.c:327
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
 </TASK>
----------------
Code disassembly (best guess):
   0:	00 00                	add    %al,(%rax)
   2:	4c 39 e7             	cmp    %r12,%rdi
   5:	49 89 fd             	mov    %rdi,%r13
   8:	0f 87 9a 00 00 00    	ja     0xa8
   e:	49 bf 00 00 00 00 00 	movabs $0xdffffc0000000000,%r15
  15:	fc ff df
  18:	49 89 fe             	mov    %rdi,%r14
  1b:	48 89 fd             	mov    %rdi,%rbp
  1e:	eb 0c                	jmp    0x2c
  20:	48 8d 6b 04          	lea    0x4(%rbx),%rbp
  24:	49 89 de             	mov    %rbx,%r14
  27:	49 39 ec             	cmp    %rbp,%r12
* 2a:	72 4d                	jb     0x79 <-- trapping instruction
  2c:	4c 89 e0             	mov    %r12,%rax
  2f:	48 29 e8             	sub    %rbp,%rax
  32:	48 89 c2             	mov    %rax,%rdx
  35:	48 c1 e8 3f          	shr    $0x3f,%rax
  39:	48 c1 fa 02          	sar    $0x2,%rdx
  3d:	48 01 d0             	add    %rdx,%rax

Crashes (13):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce-root 2022/01/13 10:32 upstream f079ab01b560 44d1319a .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2022/01/09 18:21 upstream 4634129ad9fd 2ca0d385 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2022/01/09 09:56 upstream 21f35d2ca83e 2ca0d385 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-selinux-root 2021/12/26 08:28 upstream e2ae0d4a6b0b 6caa12e4 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2021/12/07 06:43 upstream f80ef9e49fdf 0230ba3e .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2021/11/23 02:55 upstream 136057256686 545ab074 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-root 2021/11/21 10:33 upstream 923dcc5eb0c1 4eb20a4e .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2021/11/18 12:20 upstream 42eb8fdac2fc cafff8b6 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2021/11/16 08:53 upstream 8ab774587903 83f5c9b5 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-selinux-root 2021/10/31 21:05 upstream 180eca540ae0 098b5d53 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-root 2021/10/31 00:23 upstream 119c85055d86 098b5d53 .config log report info INFO: task hung in path_openat
ci-upstream-kasan-gce-smack-root 2021/08/16 05:01 upstream 7c60610d4767 2489ab88 .config log report info INFO: task hung in path_openat
ci-upstream-linux-next-kasan-gce-root 2021/12/24 07:01 linux-next 79f063d60c8c 6caa12e4 .config log report info INFO: task can't die in path_openat
* Struck through repros no longer work on HEAD.