syzbot


INFO: task hung in fsnotify_mark_destroy_workfn

Status: auto-closed as invalid on 2020/03/23 16:47
Reported-by: syzbot+89456abd66e669de2add@syzkaller.appspotmail.com
First crash: 2268d, last: 1823d
Similar bugs (5)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in fsnotify_mark_destroy_workfn fs syz 13 2295d 2409d 10/28 fixed on 2018/09/08 03:03
upstream INFO: task hung in fsnotify_mark_destroy_workfn (2) fs 42 1862d 2262d 0/28 closed as invalid on 2019/10/23 07:21
linux-4.19 INFO: task hung in fsnotify_mark_destroy_workfn syz done 3 1801d 1989d 1/1 fixed on 2020/01/16 11:28
upstream INFO: task hung in fsnotify_mark_destroy_workfn (3) fs 15 1646d 1687d 0/28 auto-closed as invalid on 2020/08/18 06:03
linux-4.19 INFO: task hung in fsnotify_mark_destroy_workfn (2) 1 1679d 1679d 0/1 auto-closed as invalid on 2020/08/15 10:12

Sample crash report:
ip6_tunnel: E xmit: Local address not yet configured!
ip6_tunnel: { xmit: Local address not yet configured!
INFO: task kworker/u4:11:10666 blocked for more than 140 seconds.
      Not tainted 4.14.155-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:11   D27136 10666      2 0x80000000
Workqueue: events_unbound fsnotify_mark_destroy_workfn
Call Trace:
 schedule+0x92/0x1c0 kernel/sched/core.c:3499
 schedule_timeout+0x752/0xe90 kernel/time/timer.c:1723
 do_wait_for_common kernel/sched/completion.c:91 [inline]
 __wait_for_common kernel/sched/completion.c:112 [inline]
 wait_for_common+0x29c/0x470 kernel/sched/completion.c:123
 __synchronize_srcu+0x12a/0x210 kernel/rcu/srcutree.c:898
 fsnotify_mark_destroy_workfn+0xfd/0x310 fs/notify/mark.c:757
 process_one_work+0x7f1/0x1580 kernel/workqueue.c:2134
 worker_thread+0xdd/0xdf0 kernel/workqueue.c:2271
 kthread+0x31f/0x430 kernel/kthread.c:232
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:404
INFO: task kworker/u4:16:11553 blocked for more than 140 seconds.
      Not tainted 4.14.155-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:16   D27344 11553      2 0x80000000
Workqueue: events_unbound fsnotify_connector_destroy_workfn
Call Trace:
 schedule+0x92/0x1c0 kernel/sched/core.c:3499
 schedule_timeout+0x752/0xe90 kernel/time/timer.c:1723
 do_wait_for_common kernel/sched/completion.c:91 [inline]
 __wait_for_common kernel/sched/completion.c:112 [inline]
 wait_for_common+0x29c/0x470 kernel/sched/completion.c:123
 __synchronize_srcu+0x12a/0x210 kernel/rcu/srcutree.c:898
 fsnotify_connector_destroy_workfn+0x49/0xa0 fs/notify/mark.c:156
 process_one_work+0x7f1/0x1580 kernel/workqueue.c:2134
 worker_thread+0xdd/0xdf0 kernel/workqueue.c:2271
 kthread+0x31f/0x430 kernel/kthread.c:232
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:404
INFO: task syz-executor.5:29201 blocked for more than 140 seconds.
      Not tainted 4.14.155-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5  D29232 29201  16741 0x00000004
Call Trace:
 schedule+0x92/0x1c0 kernel/sched/core.c:3499
 schedule_timeout+0x752/0xe90 kernel/time/timer.c:1723
 do_wait_for_common kernel/sched/completion.c:91 [inline]
 __wait_for_common kernel/sched/completion.c:112 [inline]
 wait_for_common+0x29c/0x470 kernel/sched/completion.c:123
 flush_work+0x3e2/0x720 kernel/workqueue.c:2908
 fsnotify_destroy_group+0x131/0x2f0 fs/notify/group.c:85
 inotify_release+0x33/0x40 fs/notify/inotify/inotify_user.c:280
 __fput+0x25e/0x710 fs/file_table.c:210
 task_work_run+0x125/0x1a0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:191 [inline]
 exit_to_usermode_loop+0x13b/0x160 arch/x86/entry/common.c:164
 prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:270 [inline]
 do_syscall_64+0x3a3/0x520 arch/x86/entry/common.c:297
 entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x4141d1
RSP: 002b:00007ffe98940000 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000005 RCX: 00000000004141d1
RDX: 0000000000000000 RSI: 0000000000000525 RDI: 0000000000000004
RBP: 0000000000000001 R08: 00000000f4dcc525 R09: 00000000f4dcc529
R10: 00007ffe989400e0 R11: 0000000000000293 R12: 000000000075c9a0
R13: 000000000075c9a0 R14: 0000000000761398 R15: 000000000075bf2c

Showing all locks held in the system:
1 lock held by khungtaskd/23:
 #0:  (tasklist_lock){.+.+}, at: [<00000000161d19fa>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4544
2 locks held by getty/1761:
 #0:  (&tty->ldisc_sem){++++}, at: [<00000000ce6f725b>] tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:284
 #1:  (&ldata->atomic_read_lock){+.+.}, at: [<000000008089fdc1>] n_tty_read+0x1f7/0x1700 drivers/tty/n_tty.c:2156
2 locks held by kworker/u4:11/10666:
 #0:  ("events_unbound"){+.+.}, at: [<0000000026bc613d>] process_one_work+0x6ff/0x1580 kernel/workqueue.c:2105
 #1:  ((reaper_work).work){+.+.}, at: [<00000000a369ae8d>] process_one_work+0x735/0x1580 kernel/workqueue.c:2109
2 locks held by kworker/u4:16/11553:
 #0:  ("events_unbound"){+.+.}, at: [<0000000026bc613d>] process_one_work+0x6ff/0x1580 kernel/workqueue.c:2105
 #1:  (connector_reaper_work){+.+.}, at: [<00000000a369ae8d>] process_one_work+0x735/0x1580 kernel/workqueue.c:2109

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

NMI backtrace for cpu 0
CPU: 0 PID: 23 Comm: khungtaskd Not tainted 4.14.155-syzkaller #0
Call Trace:
 __dump_stack lib/dump_stack.c:17 [inline]
 dump_stack+0xe5/0x154 lib/dump_stack.c:58
 nmi_cpu_backtrace.cold+0x47/0x86 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x119/0x147 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline]
 watchdog+0x629/0xbe0 kernel/hung_task.c:274
 kthread+0x31f/0x430 kernel/kthread.c:232
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:404
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 63 Comm: kworker/1:1 Not tainted 4.14.155-syzkaller #0
Workqueue: events rtc_timer_do_work
task: 000000003ba3a5e1 task.stack: 00000000d6e5bb03
RIP: 0010:strlen+0x58/0x90 lib/string.c:482
RSP: 0018:ffff8881d91d78c0 EFLAGS: 00000093
RAX: ffffffff91a13525 RBX: dffffc0000000000 RCX: 0000000000000005
RDX: 0000000000000000 RSI: ffff8881d5d3c988 RDI: ffffffff91a13520
RBP: ffffffff91a13520 R08: ffff8881d5d3c990 R09: fffffbfff2446136
R10: fffffbfff2446135 R11: ffffffff922309ab R12: 1ffff1103b23af22
R13: 0000000000000000 R14: ffffffff91ec1120 R15: ffff8881d91d7990
FS:  0000000000000000(0000) GS:ffff8881dbb00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007febd9e6bdb8 CR3: 00000000a1026001 CR4: 00000000001606a0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
 trace_event_get_offsets_lock include/trace/events/lock.h:39 [inline]
 perf_trace_lock+0xe1/0x4e0 include/trace/events/lock.h:39
 trace_lock_release include/trace/events/lock.h:58 [inline]
 lock_release+0x4e9/0x740 kernel/locking/lockdep.c:4012
 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:158 [inline]
 _raw_spin_unlock_irqrestore+0x1b/0x70 kernel/locking/spinlock.c:192
 spin_unlock_irqrestore include/linux/spinlock.h:372 [inline]
 __wake_up_common_lock+0xe0/0x170 kernel/sched/wait.c:126
 rtc_handle_legacy_irq+0x123/0x180 drivers/rtc/interface.c:523
 rtc_timer_do_work+0x21d/0x610 drivers/rtc/interface.c:881
 process_one_work+0x7f1/0x1580 kernel/workqueue.c:2134
 worker_thread+0xdd/0xdf0 kernel/workqueue.c:2271
 kthread+0x31f/0x430 kernel/kthread.c:232
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:404
Code: 7d 00 00 74 39 48 bb 00 00 00 00 00 fc ff df 48 89 e8 48 83 c0 01 48 89 c2 48 89 c1 48 c1 ea 03 83 e1 07 0f b6 14 1a 38 ca 7f 04 <84> d2 75 1f 80 38 00 75 de 48 83 c4 08 48 29 e8 5b 5d c3 48 83 

Crashes (9):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2019/11/24 16:46 android-4.14 437a2a739c5f 598ca6c8 .config console log report ci-android-414-kasan-gce-root
2019/10/26 12:32 android-4.14 4039d072b0dc 413926c5 .config console log report ci-android-414-kasan-gce-root
2019/09/19 08:13 android-4.14 6bb0e9159cb6 46c0be24 .config console log report ci-android-414-kasan-gce-root
2019/09/06 04:28 android-4.14 38733badc0e6 040fda58 .config console log report ci-android-414-kasan-gce-root
2019/08/26 13:53 android-4.14 f5189d4af2b5 d21c5d9d .config console log report ci-android-414-kasan-gce-root
2019/04/11 22:20 android-4.14 f289d4cdf0cc 13030ef8 .config console log report ci-android-414-kasan-gce-root
2019/01/22 17:13 android-4.14 5a76363f1262 985f75cc .config console log report ci-android-414-kasan-gce-root
2018/09/22 07:19 android-4.14 666c420fa3ea 37079712 .config console log report ci-android-414-kasan-gce-root
2018/09/06 08:57 android-4.14 b859aa7d7a0c 873745f2 .config console log report ci-android-414-kasan-gce-root
* Struck through repros no longer work on HEAD.