INFO: task kworker/u4:3:500 blocked for more than 143 seconds. Not tainted 5.1.0-rc5-next-20190418 #28 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:3 D25000 500 2 0x80004000 Workqueue: events_unbound fsnotify_connector_destroy_workfn Call Trace: context_switch kernel/sched/core.c:2817 [inline] __schedule+0x7d1/0x15c0 kernel/sched/core.c:3444 schedule+0xa8/0x260 kernel/sched/core.c:3508 schedule_timeout+0x717/0xc50 kernel/time/timer.c:1783 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+0x29c/0x440 kernel/sched/completion.c:136 __synchronize_srcu+0x197/0x250 kernel/rcu/srcutree.c:921 synchronize_srcu_expedited kernel/rcu/srcutree.c:946 [inline] synchronize_srcu+0x239/0x3e8 kernel/rcu/srcutree.c:997 fsnotify_connector_destroy_workfn+0x4e/0xa0 fs/notify/mark.c:177 process_one_work+0x98e/0x1790 kernel/workqueue.c:2268 worker_thread+0x98/0xe40 kernel/workqueue.c:2414 kthread+0x357/0x430 kernel/kthread.c:254 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 INFO: task kworker/u4:15:12336 blocked for more than 143 seconds. Not tainted 5.1.0-rc5-next-20190418 #28 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:15 D24904 12336 2 0x80004000 Workqueue: events_unbound fsnotify_mark_destroy_workfn Call Trace: context_switch kernel/sched/core.c:2817 [inline] __schedule+0x7d1/0x15c0 kernel/sched/core.c:3444 schedule+0xa8/0x260 kernel/sched/core.c:3508 schedule_timeout+0x717/0xc50 kernel/time/timer.c:1783 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+0x29c/0x440 kernel/sched/completion.c:136 __synchronize_srcu+0x197/0x250 kernel/rcu/srcutree.c:921 synchronize_srcu+0x2dc/0x3e8 kernel/rcu/srcutree.c:999 fsnotify_mark_destroy_workfn+0x110/0x3b0 fs/notify/mark.c:827 process_one_work+0x98e/0x1790 kernel/workqueue.c:2268 worker_thread+0x98/0xe40 kernel/workqueue.c:2414 kthread+0x357/0x430 kernel/kthread.c:254 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 INFO: task syz-executor.5:3085 blocked for more than 144 seconds. Not tainted 5.1.0-rc5-next-20190418 #28 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D28624 3085 7798 0x00004004 Call Trace: context_switch kernel/sched/core.c:2817 [inline] __schedule+0x7d1/0x15c0 kernel/sched/core.c:3444 schedule+0xa8/0x260 kernel/sched/core.c:3508 synchronize_rcu_expedited+0x431/0x5b0 kernel/rcu/tree_exp.h:807 namespace_unlock+0x108/0x130 fs/namespace.c:1384 unlock_mount+0x55/0xa0 fs/namespace.c:2144 do_loopback fs/namespace.c:2285 [inline] do_mount+0xe5e/0x2c40 fs/namespace.c:2936 ksys_mount+0xdb/0x150 fs/namespace.c:3151 __do_sys_mount fs/namespace.c:3165 [inline] __se_sys_mount fs/namespace.c:3162 [inline] __x64_sys_mount+0xbe/0x150 fs/namespace.c:3162 do_syscall_64+0x103/0x670 arch/x86/entry/common.c:298 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x45b69a Code: Bad RIP value. RSP: 002b:00007f0697a98a88 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5 RAX: ffffffffffffffda RBX: 00007f0697a98b40 RCX: 000000000045b69a RDX: 00007f0697a98ae0 RSI: 00000000200001c0 RDI: 00007f0697a98b00 RBP: 0000000000000005 R08: 00007f0697a98b40 R09: 00007f0697a98ae0 R10: 0000000000001000 R11: 0000000000000206 R12: 0000000000000004 R13: 00000000004c76d6 R14: 00000000004dd700 R15: 00000000ffffffff INFO: task syz-executor.2:3107 blocked for more than 144 seconds. Not tainted 5.1.0-rc5-next-20190418 #28 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D28384 3107 20826 0x00000004 Call Trace: context_switch kernel/sched/core.c:2817 [inline] __schedule+0x7d1/0x15c0 kernel/sched/core.c:3444 schedule+0xa8/0x260 kernel/sched/core.c:3508 schedule_timeout+0x717/0xc50 kernel/time/timer.c:1783 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+0x29c/0x440 kernel/sched/completion.c:136 __synchronize_srcu+0x197/0x250 kernel/rcu/srcutree.c:921 synchronize_srcu_expedited kernel/rcu/srcutree.c:946 [inline] synchronize_srcu+0x239/0x3e8 kernel/rcu/srcutree.c:997 tracepoint_synchronize_unregister include/linux/tracepoint.h:84 [inline] perf_trace_event_unreg.isra.0+0xcb/0x220 kernel/trace/trace_event_perf.c:163 perf_trace_destroy+0xc1/0x100 kernel/trace/trace_event_perf.c:238 tp_perf_event_destroy+0x16/0x20 kernel/events/core.c:8620 _free_event+0x356/0x13b0 kernel/events/core.c:4464 put_event+0x47/0x60 kernel/events/core.c:4550 perf_event_release_kernel+0x693/0xbe0 kernel/events/core.c:4656 perf_release+0x37/0x50 kernel/events/core.c:4666 __fput+0x2e5/0x8d0 fs/file_table.c:278 ____fput+0x16/0x20 fs/file_table.c:309 task_work_run+0x14a/0x1c0 kernel/task_work.c:113 tracehook_notify_resume include/linux/tracehook.h:188 [inline] exit_to_usermode_loop+0x273/0x2c0 arch/x86/entry/common.c:167 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline] syscall_return_slowpath arch/x86/entry/common.c:276 [inline] do_syscall_64+0x57e/0x670 arch/x86/entry/common.c:301 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4129e1 Code: bb c8 00 00 00 ff 15 1e 6e 24 00 85 c0 89 c5 0f 85 7e fc ff ff e9 32 ff ff ff 66 66 66 66 66 66 2e 0f 1f 84 00 00 00 00 00 48 <89> 5c 24 d0 4c 89 6c 24 e8 48 89 d3 48 89 6c 24 d8 4c 89 64 24 e0 RSP: 002b:00007ffd23c38a80 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000006 RCX: 00000000004129e1 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005 RBP: 0000000000743038 R08: 0000000061d03d2a R09: 0000000061d03d2e R10: 00007ffd23c38b50 R11: 0000000000000293 R12: 0000000000000001 R13: 0000000000132676 R14: 00000000001326a3 R15: 000000000073bf0c Showing all locks held in the system: 3 locks held by kworker/0:1/12: 2 locks held by kworker/u4:3/500: #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: __write_once_size include/linux/compiler.h:224 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2239 #1: 000000004de8630c (connector_reaper_work){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2243 1 lock held by khungtaskd/1043: #0: 00000000e1cb55c5 (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x27e kernel/locking/lockdep.c:5046 1 lock held by rsyslogd/7653: #0: 00000000d00e569c (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110 fs/file.c:801 2 locks held by getty/7743: #0: 0000000024b3aafe (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 000000008c7b1b4c (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by getty/7744: #0: 0000000036a3dc41 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000e1691344 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by getty/7745: #0: 000000004c0a17c1 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000dc5f04b9 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by getty/7746: #0: 000000001f55c491 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000d0e81fa4 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by getty/7747: #0: 00000000e02978cf (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000899a9282 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by getty/7748: #0: 00000000f1a383b0 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000c3adc5b4 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by getty/7749: #0: 00000000d9806665 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000110b8ace (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156 2 locks held by kworker/u4:15/12336: #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: __write_once_size include/linux/compiler.h:224 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline] #0: 00000000b4bb2a4e ((wq_completion)events_unbound){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2239 #1: 0000000050d22a29 ((reaper_work).work){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2243 1 lock held by syz-executor.2/32321: #0: 0000000085927d7f (event_mutex){+.+.}, at: perf_trace_destroy+0x28/0x100 kernel/trace/trace_event_perf.c:236 2 locks held by syz-executor.5/3085: #0: 00000000fcf9a453 (&type->i_mutex_dir_key#3){++++}, at: inode_lock include/linux/fs.h:772 [inline] #0: 00000000fcf9a453 (&type->i_mutex_dir_key#3){++++}, at: lock_mount+0x8c/0x2e0 fs/namespace.c:2112 #1: 00000000e9b9a8d8 (rcu_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:286 [inline] #1: 00000000e9b9a8d8 (rcu_state.exp_mutex){+.+.}, at: synchronize_rcu_expedited+0x4ab/0x5b0 kernel/rcu/tree_exp.h:790 1 lock held by syz-executor.2/3107: #0: 0000000085927d7f (event_mutex){+.+.}, at: perf_trace_destroy+0x28/0x100 kernel/trace/trace_event_perf.c:236 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1043 Comm: khungtaskd Not tainted 5.1.0-rc5-next-20190418 #28 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+0x172/0x1f0 lib/dump_stack.c:113 nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x1be/0x236 lib/nmi_backtrace.c:62 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:204 [inline] watchdog+0x9b7/0xec0 kernel/hung_task.c:288 kthread+0x357/0x430 kernel/kthread.c:254 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 Sending NMI from CPU 1 to CPUs 0: