INFO: rcu detected stall in sys_perf_event_open

10 views
Skip to first unread message

syzbot

unread,
Sep 17, 2018, 5:23:05 PM9/17/18
to syzkaller-upst...@googlegroups.com
Hello,

syzbot found the following crash on:

HEAD commit: 70e88c758a6b selftests/bpf: fix bpf_flow.c build
git tree: bpf-next
console output: https://syzkaller.appspot.com/x/log.txt?x=1288a44e400000
kernel config: https://syzkaller.appspot.com/x/.config?x=8f59875069d721b6
dashboard link: https://syzkaller.appspot.com/bug?extid=d2b5c4f404959013eb50
compiler: gcc (GCC) 8.0.1 20180413 (experimental)
CC: [ac...@kernel.org alexander...@linux.intel.com
jo...@redhat.com linux-...@vger.kernel.org mi...@redhat.com
namh...@kernel.org pet...@infradead.org]

Unfortunately, I don't have any reproducer for this crash yet.

IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+d2b5c4...@syzkaller.appspotmail.com

rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
rcu: 0-....: (10501 ticks this GP) idle=c86/1/0x4000000000000002
softirq=126005/126006 fqs=5249
rcu: (detected by 1, t=10502 jiffies, g=50541, q=2)
Sending NMI from CPU 1 to CPUs 0:
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 2.253
msecs
NMI backtrace for cpu 0
CPU: 0 PID: 2425 Comm: syz-executor2 Not tainted 4.19.0-rc2+ #96
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
RIP: 0010:arch_atomic_inc arch/x86/include/asm/atomic.h:98 [inline]
RIP: 0010:atomic_inc include/asm-generic/atomic-instrumented.h:110 [inline]
RIP: 0010:__lock_acquire+0x2a8/0x4ec0 kernel/locking/lockdep.c:3304
Code: 08 48 85 db 0f 84 27 ff ff ff 48 8d bb 38 01 00 00 be 04 00 00 00 44
89 84 24 88 00 00 00 e8 ff 8e 61 00 f0 ff 83 38 01 00 00 <49> 8d 86 78 08
00 00 44 8b 84 24 88 00 00 00 48 89 c2 48 89 44 24
RSP: 0018:ffff8801dac074b0 EFLAGS: 00000006
RAX: fffffbfff161b758 RBX: ffffffff8b0db980 RCX: ffffffff816083b1
RDX: 0000000000000001 RSI: 0000000000000004 RDI: ffffffff8b0dbab8
RBP: ffff8801dac07838 R08: fffffbfff161b758 R09: fffffbfff161b757
R10: fffffbfff161b757 R11: ffffffff8b0dbabb R12: 0000000000000001
R13: ffffffff8b6e3700 R14: ffff88019d5242c0 R15: 0000000000000000
FS: 00007f1b67b74700(0000) GS:ffff8801dac00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000100000000 CR3: 00000001bc44c000 CR4: 00000000001406f0
DR0: 0000000020000080 DR1: 0000000020000080 DR2: 0000000020000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
<IRQ>
lock_acquire+0x1ed/0x520 kernel/locking/lockdep.c:3901
__raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
_raw_spin_lock_irqsave+0x99/0xd0 kernel/locking/spinlock.c:152
debug_object_activate+0x1d4/0x600 lib/debugobjects.c:473
debug_hrtimer_activate kernel/time/hrtimer.c:416 [inline]
debug_activate kernel/time/hrtimer.c:465 [inline]
enqueue_hrtimer+0x97/0x560 kernel/time/hrtimer.c:954
__run_hrtimer kernel/time/hrtimer.c:1413 [inline]
__hrtimer_run_queues+0xcdc/0x10d0 kernel/time/hrtimer.c:1460
hrtimer_interrupt+0x313/0x780 kernel/time/hrtimer.c:1518
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1029 [inline]
smp_apic_timer_interrupt+0x1a1/0x760 arch/x86/kernel/apic/apic.c:1054
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:864
</IRQ>
RIP: 0010:write_comp_data+0x19/0x70 kernel/kcov.c:116
Code: ef c9 ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 55 65 4c 8b 04 25
40 ee 01 00 65 8b 05 ff 1e 83 7e a9 00 01 1f 00 48 89 e5 <75> 51 41 8b 80
d0 12 00 00 83 f8 03 75 45 49 8b 80 d8 12 00 00 45
RSP: 0018:ffff8801a2ad7098 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000001 RBX: 0000000000000001 RCX: ffffffff83904d6a
RDX: 0000000000000001 RSI: 0000000000000000 RDI: 0000000000000005
RBP: ffff8801a2ad7098 R08: ffff88019d5242c0 R09: ffffed0034752ce3
R10: ffffed0034752ce3 R11: ffff8801a3a9671b R12: 0000000000000000
R13: ffffffff8864a0c0 R14: ffffffff8831e5e0 R15: 0000000000000000
__sanitizer_cov_trace_const_cmp4+0x16/0x20 kernel/kcov.c:188
check_preemption_disabled+0x3a/0x200 lib/smp_processor_id.c:15
__this_cpu_preempt_check+0x1c/0x1f lib/smp_processor_id.c:62
__perf_event_account_interrupt+0x28/0x2d0 kernel/events/core.c:7696
__perf_event_overflow+0x164/0x4c0 kernel/events/core.c:7748
perf_swevent_overflow+0xad/0x150 kernel/events/core.c:7839
perf_swevent_event+0x153/0x2e0 kernel/events/core.c:7877
perf_tp_event+0x4dc/0xbe0 kernel/events/core.c:8295
perf_trace_run_bpf_submit+0x267/0x330 kernel/events/core.c:8269
perf_trace_kmem_alloc+0xe6/0x110 include/trace/events/kmem.h:12
trace_kmem_cache_alloc include/trace/events/kmem.h:54 [inline]
kmem_cache_alloc+0x25f/0x730 mm/slab.c:3555
kmem_cache_zalloc include/linux/slab.h:697 [inline]
__alloc_file+0xa8/0x470 fs/file_table.c:100
alloc_empty_file+0x72/0x170 fs/file_table.c:150
alloc_file+0x5e/0x4d0 fs/file_table.c:192
alloc_file_pseudo+0x261/0x3f0 fs/file_table.c:231
anon_inode_getfile+0xd7/0x1f0 fs/anon_inodes.c:87
__do_sys_perf_event_open+0x177e/0x2f30 kernel/events/core.c:10620
__se_sys_perf_event_open kernel/events/core.c:10389 [inline]
__x64_sys_perf_event_open+0xbe/0x150 kernel/events/core.c:10389
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4572d9
Code: fd b4 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7
48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff
ff 0f 83 cb b4 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007f1b67b73c78 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007f1b67b746d4 RCX: 00000000004572d9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000000
RBP: 00000000009300a0 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 00000000ffffffff
R13: 00000000004d4050 R14: 00000000004c8b05 R15: 0000000000000000
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 0-....: (10584 ticks this GP) idle=c86/1/0x4000000000000000
softirq=126006/126006 fqs=5248
rcu: (detected by 1, t=10502 jiffies, g=187809, q=797)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 2425 Comm: syz-executor2 Not tainted 4.19.0-rc2+ #96
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS
Google 01/01/2011
RIP: 0010:check_preemption_disabled+0x48/0x200 lib/smp_processor_id.c:51
Code: 65 8b 1d 7b a0 71 7c 81 e3 ff ff ff 7f 89 de 65 44 8b 25 cb 33 71 7c
e8 d6 82 ee fd 85 db 74 1c e8 bd 81 ee fd e8 b8 81 ee fd <44> 89 e0 48 83
c4 08 5b 41 5c 41 5d 41 5e 41 5f 5d c3 e8 a1 81 ee
RSP: 0018:ffff8801dac07e18 EFLAGS: 00000046
RAX: 0000000000040000 RBX: 0000000000000001 RCX: ffffc90002262000
RDX: 0000000000040000 RSI: ffffffff83904d78 RDI: 0000000000000005
RBP: ffff8801dac07e48 R08: ffff88019d5242c0 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: ffffffff8864a080 R14: ffffffff8864a040 R15: 0000000000000000
FS: 00007f1b67b74700(0000) GS:ffff8801dac00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000100000000 CR3: 00000001bc44c000 CR4: 00000000001406f0
DR0: 0000000020000080 DR1: 0000000020000080 DR2: 0000000020000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
<IRQ>
debug_smp_processor_id+0x1c/0x20 lib/smp_processor_id.c:56
rcu_irq_enter+0xa/0x100 kernel/rcu/tree.c:987
irq_enter+0xa/0xe0 kernel/softirq.c:346
entering_irq arch/x86/include/asm/apic.h:517 [inline]
entering_ack_irq arch/x86/include/asm/apic.h:523 [inline]
smp_apic_timer_interrupt+0xaf/0x760 arch/x86/kernel/apic/apic.c:1052
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:864
</IRQ>
RIP: 0010:__sanitizer_cov_trace_const_cmp8+0x13/0x20 kernel/kcov.c:195
Code: 00 48 89 e5 48 8b 4d 08 e8 6a fe ff ff 5d c3 0f 1f 84 00 00 00 00 00
55 48 89 f2 48 89 fe bf 07 00 00 00 48 89 e5 48 8b 4d 08 <e8> 48 fe ff ff
5d c3 66 0f 1f 44 00 00 55 48 89 e5 41 57 41 56 41
RSP: 0018:ffff8801a2ad6bf0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: 0000000000040000 RBX: 0000000000000001 RCX: ffffffff819a1305
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000007
RBP: ffff8801a2ad6bf0 R08: ffff88019d5242c0 R09: 1ffffffff12f43cd
R10: ffffed003b584732 R11: ffff8801dac23993 R12: 0000000000000088
R13: 0000000000000001 R14: 0000000000000000 R15: ffff8801a2ad7380
__perf_output_begin kernel/events/ring_buffer.c:139 [inline]
perf_output_begin_forward+0x235/0xdf0 kernel/events/ring_buffer.c:237
__perf_event_output kernel/events/core.c:6503 [inline]
perf_event_output_forward+0x156/0x2d0 kernel/events/core.c:6519
__perf_event_overflow+0x239/0x4c0 kernel/events/core.c:7763
perf_swevent_overflow+0xad/0x150 kernel/events/core.c:7839
perf_swevent_event+0x153/0x2e0 kernel/events/core.c:7877
perf_tp_event+0x4dc/0xbe0 kernel/events/core.c:8295
perf_trace_run_bpf_submit+0x267/0x330 kernel/events/core.c:8269
perf_trace_kmem_alloc+0xe6/0x110 include/trace/events/kmem.h:12
trace_kmem_cache_alloc include/trace/events/kmem.h:54 [inline]
kmem_cache_alloc+0x25f/0x730 mm/slab.c:3555
kmem_cache_zalloc include/linux/slab.h:697 [inline]
__alloc_file+0xa8/0x470 fs/file_table.c:100
alloc_empty_file+0x72/0x170 fs/file_table.c:150
alloc_file+0x5e/0x4d0 fs/file_table.c:192
alloc_file_pseudo+0x261/0x3f0 fs/file_table.c:231
anon_inode_getfile+0xd7/0x1f0 fs/anon_inodes.c:87
__do_sys_perf_event_open+0x177e/0x2f30 kernel/events/core.c:10620
__se_sys_perf_event_open kernel/events/core.c:10389 [inline]
__x64_sys_perf_event_open+0xbe/0x150 kernel/events/core.c:10389
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4572d9
Code: fd b4 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7
48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff
ff 0f 83 cb b4 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007f1b67b73c78 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007f1b67b746d4 RCX: 00000000004572d9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000000
RBP: 00000000009300a0 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 00000000ffffffff
R13: 00000000004d4050 R14: 00000000004c8b05 R15: 0000000000000000


---
This bug is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this bug report. See:
https://goo.gl/tpsmEJ#bug-status-tracking for how to communicate with
syzbot.

syzbot

unread,
Mar 14, 2019, 1:19:03 PM3/14/19
to syzkaller-upst...@googlegroups.com
Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.
Reply all
Reply to author
Forward
0 new messages