syzbot


INFO: task hung in fsnotify_mark_destroy_workfn (2)

Status: auto-closed as invalid on 2020/08/15 10:12
Reported-by: syzbot+9bac094d4e5bf1e92ea6@syzkaller.appspotmail.com
First crash: 1526d, last: 1526d
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 2142d 2256d 10/27 fixed on 2018/09/08 03:03
upstream INFO: task hung in fsnotify_mark_destroy_workfn (2) fs 42 1709d 2109d 0/27 closed as invalid on 2019/10/23 07:21
linux-4.19 INFO: task hung in fsnotify_mark_destroy_workfn syz done 3 1649d 1836d 1/1 fixed on 2020/01/16 11:28
upstream INFO: task hung in fsnotify_mark_destroy_workfn (3) fs 15 1493d 1534d 0/27 auto-closed as invalid on 2020/08/18 06:03
android-414 INFO: task hung in fsnotify_mark_destroy_workfn 9 1671d 1895d 0/1 auto-closed as invalid on 2020/03/23 16:47

Sample crash report:
futex_wake_op: syz-executor.0 tries to shift op by -1; fix this program
futex_wake_op: syz-executor.0 tries to shift op by -1; fix this program
INFO: task kworker/u4:2:43 blocked for more than 140 seconds.
      Not tainted 4.19.116-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:2    D26096    43      2 0x80000000
Workqueue: events_unbound fsnotify_mark_destroy_workfn
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 schedule_timeout+0x896/0xf20 kernel/time/timer.c:1782
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common kernel/sched/completion.c:115 [inline]
 wait_for_completion+0x26a/0x3c0 kernel/sched/completion.c:136
 __synchronize_srcu+0x12e/0x210 kernel/rcu/srcutree.c:936
 fsnotify_mark_destroy_workfn+0xfd/0x330 fs/notify/mark.c:795
 process_one_work+0x91f/0x1640 kernel/workqueue.c:2155
 worker_thread+0x96/0xe20 kernel/workqueue.c:2298
 kthread+0x34a/0x420 kernel/kthread.c:246
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
INFO: task kworker/u4:7:8324 blocked for more than 140 seconds.
      Not tainted 4.19.116-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:7    D25784  8324      2 0x80000000
Workqueue: events_unbound fsnotify_connector_destroy_workfn
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 schedule_timeout+0x896/0xf20 kernel/time/timer.c:1782
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common kernel/sched/completion.c:115 [inline]
 wait_for_completion+0x26a/0x3c0 kernel/sched/completion.c:136
 __synchronize_srcu+0x12e/0x210 kernel/rcu/srcutree.c:936
 fsnotify_connector_destroy_workfn+0x49/0xa0 fs/notify/mark.c:174
 process_one_work+0x91f/0x1640 kernel/workqueue.c:2155
 worker_thread+0x96/0xe20 kernel/workqueue.c:2298
 kthread+0x34a/0x420 kernel/kthread.c:246
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
INFO: task kworker/u4:9:8398 blocked for more than 140 seconds.
      Not tainted 4.19.116-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:9    D25096  8398      2 0x80000000
Workqueue: netns cleanup_net
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 schedule_timeout+0x896/0xf20 kernel/time/timer.c:1782
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common kernel/sched/completion.c:115 [inline]
 wait_for_completion+0x26a/0x3c0 kernel/sched/completion.c:136
 flush_workqueue+0x40f/0x14b0 kernel/workqueue.c:2715
 rxrpc_destroy_all_connections+0xf9/0x3ff net/rxrpc/conn_object.c:471
 rxrpc_exit_net+0x1b1/0x2f0 net/rxrpc/net_ns.c:123
 ops_exit_list.isra.0+0x9d/0x140 net/core/net_namespace.c:153
 cleanup_net+0x3bf/0x850 net/core/net_namespace.c:553
 process_one_work+0x91f/0x1640 kernel/workqueue.c:2155
 worker_thread+0x96/0xe20 kernel/workqueue.c:2298
 kthread+0x34a/0x420 kernel/kthread.c:246
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
INFO: task syz-executor.4:11584 blocked for more than 140 seconds.
      Not tainted 4.19.116-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D27312 11584   6804 0x00000004
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3559
 schedule_timeout+0x896/0xf20 kernel/time/timer.c:1782
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common kernel/sched/completion.c:115 [inline]
 wait_for_completion+0x26a/0x3c0 kernel/sched/completion.c:136
 __flush_work+0x48b/0x870 kernel/workqueue.c:2928
 __cancel_work_timer+0x388/0x4f0 kernel/workqueue.c:3015
 p9_conn_destroy net/9p/trans_fd.c:878 [inline]
 p9_fd_close+0x29c/0x520 net/9p/trans_fd.c:913
 p9_client_create+0x98c/0x1400 net/9p/client.c:1084
 v9fs_session_init+0x1e7/0x18c0 fs/9p/v9fs.c:421
 v9fs_mount+0x79/0x900 fs/9p/vfs_super.c:135
 mount_fs+0xa3/0x30c fs/super.c:1261
 vfs_kern_mount.part.0+0x68/0x400 fs/namespace.c:961
 vfs_kern_mount fs/namespace.c:951 [inline]
 do_new_mount fs/namespace.c:2469 [inline]
 do_mount+0x4f4/0x2a40 fs/namespace.c:2799
 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:0x45c889
Code: Bad RIP value.
RSP: 002b:00007fef18861c78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00007fef188626d4 RCX: 000000000045c889
RDX: 0000000020000140 RSI: 0000000020000100 RDI: 0000000000000000
RBP: 000000000076bf00 R08: 0000000020000340 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 000000000000074c R14: 00000000004ca0ed R15: 000000000076bf0c

Showing all locks held in the system:
2 locks held by kworker/u4:2/43:
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: process_one_work+0x81a/0x1640 kernel/workqueue.c:2126
 #1: 00000000163f089f ((reaper_work).work){+.+.}, at: process_one_work+0x84e/0x1640 kernel/workqueue.c:2130
1 lock held by khungtaskd/1079:
 #0: 000000003b53be79 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
2 locks held by kworker/1:2/2914:
1 lock held by in:imklog/6142:
 #0: 0000000051132936 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xe3/0x100 fs/file.c:767
2 locks held by agetty/6368:
 #0: 0000000038d42eb2 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272
 #1: 000000002cb1543d (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1a50 drivers/tty/n_tty.c:2154
2 locks held by kworker/u4:7/8324:
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
 #0: 0000000062a762e3 ((wq_completion)"events_unbound"){+.+.}, at: process_one_work+0x81a/0x1640 kernel/workqueue.c:2126
 #1: 00000000b42e1c9a (connector_reaper_work){+.+.}, at: process_one_work+0x84e/0x1640 kernel/workqueue.c:2130
3 locks held by kworker/u4:9/8398:
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline]
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline]
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline]
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline]
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
 #0: 000000003fdd0510 ((wq_completion)"%s""netns"){+.+.}, at: process_one_work+0x81a/0x1640 kernel/workqueue.c:2126
 #1: 00000000b76d225b (net_cleanup_work){+.+.}, at: process_one_work+0x84e/0x1640 kernel/workqueue.c:2130
 #2: 000000008ab2f8f1 (pernet_ops_rwsem){++++}, at: cleanup_net+0xa8/0x850 net/core/net_namespace.c:520

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

NMI backtrace for cpu 0
CPU: 0 PID: 1079 Comm: khungtaskd Not tainted 4.19.116-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+0x188/0x20d 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+0x34a/0x420 kernel/kthread.c:246
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 2914 Comm: kworker/1:2 Not tainted 4.19.116-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: events p9_read_work
RIP: 0010:snd_pcm_plug_client_channels_buf+0xba/0x420 sound/core/oss/pcm_plugin.c:590
Code: e8 7b 84 ec fb 85 db 0f 84 a9 02 00 00 e8 fe 82 ec fb 48 8d bd 50 04 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 <80> 3c 02 00 0f 85 3e 03 00 00 48 8b ad 50 04 00 00 4c 8d 65 18 e8
RSP: 0000:ffff88809e37f948 EFLAGS: 00000a06
RAX: dffffc0000000000 RBX: 0000000000000001 RCX: ffffffff857b2695
RDX: 1ffff1100ab72b22 RSI: ffffffff857b26a2 RDI: ffff888055b95910
RBP: ffff888055b954c0 R08: ffff88809e3703c0 R09: ffffed100ab72b11
R10: ffff88809e37fa40 R11: ffff888055b95887 R12: 0000000000000001
R13: ffff88809e37f9c8 R14: 0000000000001000 R15: ffff888050500400
FS:  0000000000000000(0000) GS:ffff8880ae700000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000000071b4d4 CR3: 00000000a010a000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
 snd_pcm_oss_read2+0x1a9/0x3a0 sound/core/oss/pcm_oss.c:1472
 snd_pcm_oss_read1 sound/core/oss/pcm_oss.c:1513 [inline]
 snd_pcm_oss_read+0x3ec/0x6a0 sound/core/oss/pcm_oss.c:2753
 __vfs_read+0xf7/0x740 fs/read_write.c:416
 vfs_read+0x18e/0x3b0 fs/read_write.c:452
 kernel_read+0xa6/0x110 fs/read_write.c:431
 p9_fd_read net/9p/trans_fd.c:278 [inline]
 p9_read_work+0x299/0xf70 net/9p/trans_fd.c:313
 process_one_work+0x91f/0x1640 kernel/workqueue.c:2155
 worker_thread+0x96/0xe20 kernel/workqueue.c:2298
 kthread+0x34a/0x420 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/04/17 10:11 linux-4.19.y 8488c3f3bc86 18397578 .config console log report ci2-linux-4-19
* Struck through repros no longer work on HEAD.