syzbot


INFO: task hung in perf_trace_destroy

Status: closed as invalid on 2018/09/05 12:51
Subsystems: trace
[Documentation on labels]
First crash: 2640d, last: 2640d

Sample crash report:
INFO: task syz-executor5:11463 blocked for more than 140 seconds.
      Not tainted 4.18.0-rc4+ #27
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor5   D60208 11463   4566 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2857 [inline]
 __schedule+0x652/0x780 kernel/sched/core.c:3507
 schedule kernel/sched/core.c:3551 [inline]
 schedule_preempt_disabled+0x1cc/0x300 kernel/sched/core.c:3609
 __mutex_lock_common kernel/locking/mutex.c:834 [inline]
 __mutex_lock+0xba4/0x1b60 kernel/locking/mutex.c:894
 __mutex_lock_slowpath+0x2c/0x30 kernel/locking/mutex.c:1155
 mutex_lock+0xc2/0x110 kernel/locking/mutex.c:242
 perf_trace_destroy+0x3e/0x150 kernel/trace/trace_event_perf.c:235
 tp_perf_event_destroy+0x37/0x40 kernel/events/core.c:8323
 _free_event+0x1425/0x1dd0 kernel/events/core.c:4445
 put_event kernel/events/core.c:4531 [inline]
 perf_event_release_kernel+0x19a8/0x1b70 kernel/events/core.c:4637
 perf_release+0x5d/0x90 kernel/events/core.c:4647
 __fput+0x458/0xa30 fs/file_table.c:209
 ____fput+0x37/0x40 fs/file_table.c:243
 task_work_run+0x22e/0x2b0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:192 [inline]
 exit_to_usermode_loop arch/x86/entry/common.c:166 [inline]
 prepare_exit_to_usermode+0x35d/0x430 arch/x86/entry/common.c:197
 syscall_return_slowpath+0x108/0x800 arch/x86/entry/common.c:268
 do_syscall_64+0x1ad/0x230 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x63/0xe7
RIP: 0033:0x40fb91
Code: 4a 00 48 89 04 24 48 c7 44 24 08 11 00 00 00 e8 55 bd 01 00 0f 0b e8 ce a3 04 00 e9 59 ff ff ff cc cc cc cc cc cc cc cc cc 64 <48> 8b 0c 25 f8 ff ff ff 48 8d 44 24 e0 48 3b 41 10 0f 86 0f 05 00 
RSP: 002b:0000000000a3eab0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000014 RCX: 000000000040fb91
RDX: 0000000000000000 RSI: 00000000007310e8 RDI: 0000000000000013
RBP: 0000000000000013 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000a3e930 R11: 0000000000000293 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000a3f160 R15: 00000000007034c0
INFO: task syz-executor5:11467 blocked for more than 140 seconds.
      Not tainted 4.18.0-rc4+ #27
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor5   D59184 11467   4566 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2857 [inline]
 __schedule+0x652/0x780 kernel/sched/core.c:3507
 schedule kernel/sched/core.c:3551 [inline]
 schedule_preempt_disabled+0x1cc/0x300 kernel/sched/core.c:3609
 __mutex_lock_common kernel/locking/mutex.c:834 [inline]
 __mutex_lock+0xba4/0x1b60 kernel/locking/mutex.c:894
 __mutex_lock_slowpath+0x2c/0x30 kernel/locking/mutex.c:1155
 mutex_lock+0xc2/0x110 kernel/locking/mutex.c:242
 perf_trace_init+0x75/0x430 kernel/trace/trace_event_perf.c:217
 perf_tp_event_init+0xde/0x1e0 kernel/events/core.c:8339
 perf_try_init_event+0x4f9/0x690 kernel/events/core.c:9733
 perf_init_event kernel/events/core.c:9771 [inline]
 perf_event_alloc+0x2081/0x3d80 kernel/events/core.c:10037
 __do_sys_perf_event_open kernel/events/core.c:10494 [inline]
 __se_sys_perf_event_open+0xc8d/0x5bc0 kernel/events/core.c:10383
 __x64_sys_perf_event_open+0x15d/0x1b0 kernel/events/core.c:10383
 do_syscall_64+0x15b/0x230 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x63/0xe7
RIP: 0033:0x455ab9
Code: e0 1f 48 89 04 24 e8 b6 6f fd ff e8 81 6a fd ff e8 5c 68 fd ff 48 8d 05 23 cd 48 00 48 89 04 24 48 c7 44 24 08 1d 00 00 00 e8 <13> 5e fd ff 0f 0b e8 8c 44 00 00 e9 07 f0 ff ff cc cc cc cc cc cc 
RSP: 002b:00007f0d6ff9dc68 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007f0d6ff9e6d4 RCX: 0000000000455ab9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 000000002001d000
RBP: 000000000072bea0 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 00000000ffffffff
R13: 00000000004c0825 R14: 00000000004d0900 R15: 0000000000000000
INFO: task syz-executor4:11488 blocked for more than 140 seconds.
      Not tainted 4.18.0-rc4+ #27
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor4   D59920 11488   4567 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2857 [inline]
 __schedule+0x652/0x780 kernel/sched/core.c:3507
 schedule+0x1cc/0x300 kernel/sched/core.c:3551
 schedule_timeout+0x9e/0x5b0 kernel/time/timer.c:1777
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common+0x459/0x9a0 kernel/sched/completion.c:115
 wait_for_completion+0x3e/0x50 kernel/sched/completion.c:136
 __wait_rcu_gp+0x599/0x600 kernel/rcu/update.c:366
 synchronize_sched+0x151/0x170 kernel/rcu/tree.c:3097
 tracepoint_synchronize_unregister include/linux/tracepoint.h:80 [inline]
 perf_trace_event_unreg+0x16d/0x3f0 kernel/trace/trace_event_perf.c:162
 perf_trace_destroy+0xfd/0x150 kernel/trace/trace_event_perf.c:237
 tp_perf_event_destroy+0x37/0x40 kernel/events/core.c:8323
 _free_event+0x1425/0x1dd0 kernel/events/core.c:4445
 put_event kernel/events/core.c:4531 [inline]
 perf_event_release_kernel+0x19a8/0x1b70 kernel/events/core.c:4637
 perf_release+0x5d/0x90 kernel/events/core.c:4647
 __fput+0x458/0xa30 fs/file_table.c:209
 ____fput+0x37/0x40 fs/file_table.c:243
 task_work_run+0x22e/0x2b0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:192 [inline]
 exit_to_usermode_loop arch/x86/entry/common.c:166 [inline]
 prepare_exit_to_usermode+0x35d/0x430 arch/x86/entry/common.c:197
 syscall_return_slowpath+0x108/0x800 arch/x86/entry/common.c:268
 do_syscall_64+0x1ad/0x230 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x63/0xe7
RIP: 0033:0x40fb91
Code: 4a 00 48 89 04 24 48 c7 44 24 08 11 00 00 00 e8 55 bd 01 00 0f 0b e8 ce a3 04 00 e9 59 ff ff ff cc cc cc cc cc cc cc cc cc 64 <48> 8b 0c 25 f8 ff ff ff 48 8d 44 24 e0 48 3b 41 10 0f 86 0f 05 00 
RSP: 002b:0000000000a3eab0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000015 RCX: 000000000040fb91
RDX: 0000000000000000 RSI: 00000000007309e0 RDI: 0000000000000014
RBP: 0000000000000013 R08: 0000000000000000 R09: 0000000000000010
R10: 0000000000a3e930 R11: 0000000000000293 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000a3f160 R15: 00000000007034c0
NMI backtrace for cpu 0
CPU: 0 PID: 808 Comm: khungtaskd Not tainted 4.18.0-rc4+ #27
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+0x185/0x1e0 lib/dump_stack.c:113
 nmi_cpu_backtrace lib/nmi_backtrace.c:103 [inline]
 nmi_trigger_cpumask_backtrace+0x26f/0x4e0 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x2c/0x40 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_all_cpu_backtrace+0x2b/0x30 include/linux/nmi.h:138
 check_hung_uninterruptible_tasks kernel/hung_task.c:196 [inline]
 watchdog+0x1107/0x1130 kernel/hung_task.c:252
 kthread+0x473/0x4b0 kernel/kthread.c:247
 ret_from_fork+0x35/0x40 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 0 to CPUs 1:
------------[ cut here ]------------
kernel BUG at mm/kmsan/kmsan_entry.c:81!
invalid opcode: 0000 [#1] SMP PTI
CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.18.0-rc4+ #27
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:kmsan_nmi_enter+0x42/0x70 mm/kmsan/kmsan_entry.c:80
Code: 00 74 27 65 8b 04 25 80 90 03 00 83 c0 01 83 f8 08 7d 27 65 89 04 25 80 90 03 00 65 c6 04 25 15 6e 0b 00 ff c3 0f 0b 90 eb fe <0f> 0b 66 90 66 2e 0f 1f 84 00 00 00 00 00 eb fe 0f 0b 66 90 66 2e 
RSP: 0018:fffffe0000049ea8 EFLAGS: 00010046
RAX: 0000000080000000 RBX: 0000000000000001 RCX: 00000000c0000101
RDX: 00000000ffff8802 RSI: ffffffff8a40136c RDI: ffffea0000439620
RBP: fffffe0000049ef9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000196ef8000 R15: 0000000000000000
FS:  0000000000000000(0000) GS:ffff88021fd00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 0000000196ef8000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
Modules linked in:
Dumping ftrace buffer:
   (ftrace buffer empty)
---[ end trace e2f073613c618bc8 ]---
RIP: 0010:kmsan_nmi_enter+0x42/0x70 mm/kmsan/kmsan_entry.c:80
Code: 00 74 27 65 8b 04 25 80 90 03 00 83 c0 01 83 f8 08 7d 27 65 89 04 25 80 90 03 00 65 c6 04 25 15 6e 0b 00 ff c3 0f 0b 90 eb fe <0f> 0b 66 90 66 2e 0f 1f 84 00 00 00 00 00 eb fe 0f 0b 66 90 66 2e 
RSP: 0018:fffffe0000049ea8 EFLAGS: 00010046
RAX: 0000000080000000 RBX: 0000000000000001 RCX: 00000000c0000101
RDX: 00000000ffff8802 RSI: ffffffff8a40136c RDI: ffffea0000439620
RBP: fffffe0000049ef9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000196ef8000 R15: 0000000000000000
FS:  0000000000000000(0000) GS:ffff88021fd00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 0000000196ef8000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2018/07/18 14:36 https://github.com/google/kmsan.git master 80ecacc456c1 809256c3 .config console log report ci-upstream-kmsan-gce
* Struck through repros no longer work on HEAD.