syzbot


INFO: task hung in tp_perf_event_destroy

Status: auto-closed as invalid on 2019/12/20 20:11
Subsystems: trace
[Documentation on labels]
Reported-by: syzbot+4ed603af5e796994bab9@syzkaller.appspotmail.com
First crash: 2055d, last: 1677d
Similar bugs (1)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
android-49 INFO: task hung in tp_perf_event_destroy 1 1971d 1837d 0/3 auto-closed as invalid on 2019/05/30 05:39

Sample crash report:
INFO: task syz-executor.0:31417 blocked for more than 143 seconds.
      Not tainted 5.1.0-rc2+ #37
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.0  D25864 31417   7785 0x80000002
Call Trace:
 context_switch kernel/sched/core.c:2877 [inline]
 __schedule+0x817/0x1cc0 kernel/sched/core.c:3518
 schedule+0x92/0x180 kernel/sched/core.c:3562
 schedule_timeout+0x8ca/0xfd0 kernel/time/timer.c:1779
 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:925
 synchronize_srcu_expedited kernel/rcu/srcutree.c:950 [inline]
 synchronize_srcu+0x239/0x3e8 kernel/rcu/srcutree.c:1001
 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:8577
 _free_event+0x356/0x13b0 kernel/events/core.c:4453
 put_event+0x47/0x60 kernel/events/core.c:4539
 perf_event_release_kernel+0x693/0xc60 kernel/events/core.c:4645
 perf_release+0x37/0x50 kernel/events/core.c:4655
 __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
 exit_task_work include/linux/task_work.h:22 [inline]
 do_exit+0x90a/0x2fa0 kernel/exit.c:876
 do_group_exit+0x135/0x370 kernel/exit.c:980
 get_signal+0x399/0x1d50 kernel/signal.c:2577
 do_signal+0x87/0x1940 arch/x86/kernel/signal.c:816
 exit_to_usermode_loop+0x244/0x2c0 arch/x86/entry/common.c:162
 prepare_exit_to_usermode arch/x86/entry/common.c:197 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:268 [inline]
 do_syscall_64+0x52d/0x610 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x458209
Code: Bad RIP value.
RSP: 002b:00007fe62e40ecf8 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca
RAX: fffffffffffffe00 RBX: 000000000073bf08 RCX: 0000000000458209
RDX: 0000000000000000 RSI: 0000000000000080 RDI: 000000000073bf08
RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000073bf0c
R13: 00007ffec330895f R14: 00007fe62e40f9c0 R15: 000000000073bf0c

Showing all locks held in the system:
1 lock held by khungtaskd/1043:
 #0: 00000000004512d2 (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x27e kernel/locking/lockdep.c:5061
5 locks held by kworker/u4:5/3087:
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline]
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline]
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline]
 #0: 00000000a374eb4c ((wq_completion)netns){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2240
 #1: 0000000041972c49 (net_cleanup_work){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2244
 #2: 00000000a90d6367 (pernet_ops_rwsem){++++}, at: cleanup_net+0xae/0x960 net/core/net_namespace.c:518
 #3: 00000000a8eff872 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76
 #4: 0000000028aa5641 (rcu_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:285 [inline]
 #4: 0000000028aa5641 (rcu_state.exp_mutex){+.+.}, at: synchronize_rcu_expedited+0x4ab/0x5b0 kernel/rcu/tree_exp.h:758
1 lock held by rsyslogd/7658:
 #0: 00000000ab439477 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110 fs/file.c:801
2 locks held by getty/7748:
 #0: 00000000ac1c33ac (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000eb34449b (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
2 locks held by getty/7749:
 #0: 000000005d78f2dd (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 000000008e3e0775 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
2 locks held by getty/7750:
 #0: 0000000088b4fdff (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000480fada5 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
2 locks held by getty/7751:
 #0: 00000000cfd893e0 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000691159f6 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
2 locks held by getty/7752:
 #0: 000000001160b6cc (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 0000000087d7d149 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
2 locks held by getty/7753:
 #0: 0000000083bef771 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 0000000053528c46 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
2 locks held by getty/7754:
 #0: 00000000f2f92846 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000f2fbd1b4 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2156
3 locks held by kworker/0:4/7799:
1 lock held by syz-executor.0/31417:
 #0: 000000005b3a9423 (event_mutex){+.+.}, at: perf_trace_destroy+0x28/0x100 kernel/trace/trace_event_perf.c:236
3 locks held by kworker/1:4/31968:
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline]
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:855 [inline]
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:40 [inline]
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: set_work_data kernel/workqueue.c:619 [inline]
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:646 [inline]
 #0: 00000000ffb37993 ((wq_completion)events){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2240
 #1: 00000000b2fbac16 ((linkwatch_work).work){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2244
 #2: 00000000a8eff872 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76
1 lock held by syz-executor.0/32212:
 #0: 00000000a8eff872 (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:76 [inline]
 #0: 00000000a8eff872 (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x40a/0xb00 net/core/rtnetlink.c:5189

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

NMI backtrace for cpu 1
CPU: 1 PID: 1043 Comm: khungtaskd Not tainted 5.1.0-rc2+ #37
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:253
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 1 to CPUs 0:

Crashes (8):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2019/03/26 07:36 upstream a3ac7917b730 55684ce1 .config console log report ci-upstream-kasan-gce-root
2019/03/15 04:37 upstream 3b319ee220a8 d72db19b .config console log report ci-upstream-kasan-gce-selinux-root
2019/03/12 11:16 upstream ea295481b6e3 12365b99 .config console log report ci-upstream-kasan-gce-smack-root
2019/01/18 11:23 upstream a3a80255d58d 5bf17c30 .config console log report ci-upstream-kasan-gce
2018/09/08 00:44 upstream a49a9dcce802 69cfeb80 .config console log report ci-upstream-kasan-gce-root
2019/09/21 20:10 upstream 227c3e9eb5cf d96e88f3 .config console log report ci-upstream-kasan-gce-386
2019/04/09 08:38 linux-next cfd24a53adb8 995065ff .config console log report ci-upstream-linux-next-kasan-gce-root
2019/03/12 14:55 linux-next cf08baa29613 a71bfb62 .config console log report ci-upstream-linux-next-kasan-gce-root
* Struck through repros no longer work on HEAD.