syzbot


INFO: rcu detected stall in trace_timer_expire_exit

Status: auto-closed as invalid on 2020/04/15 20:53
Subsystems: trace
[Documentation on labels]
First crash: 1554d, last: 1554d
Similar bugs (1)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in trace_timer_expire_exit (2) kernel 1 1377d 1377d 0/26 auto-closed as invalid on 2020/10/09 21:20

Sample crash report:
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 	0-...!: (10500 ticks this GP) idle=e3e/1/0x4000000000000004 softirq=165039/165039 fqs=0 
	(t=10501 jiffies g=192617 q=239)
rcu: rcu_preempt kthread starved for 10502 jiffies! g192617 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
rcu: RCU grace-period kthread stack dump:
rcu_preempt     R  running task    29016    10      2 0x80004000
Call Trace:
 context_switch kernel/sched/core.c:3385 [inline]
 __schedule+0x87f/0xcd0 kernel/sched/core.c:4081
 schedule+0x188/0x210 kernel/sched/core.c:4155
 schedule_timeout+0x14f/0x240 kernel/time/timer.c:1895
 rcu_gp_fqs_loop kernel/rcu/tree.c:1661 [inline]
 rcu_gp_kthread+0xe0d/0x1770 kernel/rcu/tree.c:1821
 kthread+0x332/0x350 kernel/kthread.c:255
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
NMI backtrace for cpu 0
CPU: 0 PID: 26812 Comm: syz-executor.3 Not tainted 5.5.0-rc6-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1fb/0x318 lib/dump_stack.c:118
 nmi_cpu_backtrace+0xaa/0x190 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x16f/0x290 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x10/0x20 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_single_cpu_backtrace include/linux/nmi.h:164 [inline]
 rcu_dump_cpu_stacks+0x15a/0x220 kernel/rcu/tree_stall.h:254
 print_cpu_stall kernel/rcu/tree_stall.h:455 [inline]
 check_cpu_stall kernel/rcu/tree_stall.h:529 [inline]
 rcu_pending kernel/rcu/tree.c:2827 [inline]
 rcu_sched_clock_irq+0x1521/0x1ab0 kernel/rcu/tree.c:2271
 update_process_times+0x12d/0x180 kernel/time/timer.c:1726
 tick_sched_handle kernel/time/tick-sched.c:167 [inline]
 tick_sched_timer+0x263/0x420 kernel/time/tick-sched.c:1310
 __run_hrtimer kernel/time/hrtimer.c:1517 [inline]
 __hrtimer_run_queues+0x3f3/0x840 kernel/time/hrtimer.c:1579
 hrtimer_interrupt+0x37c/0xda0 kernel/time/hrtimer.c:1641
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1110 [inline]
 smp_apic_timer_interrupt+0x109/0x280 arch/x86/kernel/apic/apic.c:1135
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
RIP: 0010:debug_lockdep_rcu_enabled+0x68/0xe0 kernel/rcu/update.c:252
Code: 04 38 84 c0 75 59 83 3d a5 b8 e1 07 00 74 28 65 4c 8b 34 25 c0 1d 02 00 49 81 c6 94 08 00 00 4c 89 f0 48 c1 e8 03 42 8a 04 38 <84> c0 75 59 31 db 41 83 3e 00 0f 94 c3 89 d8 5b 41 5e 41 5f 5d c3
RSP: 0018:ffffc90000007cf8 EFLAGS: 00000a03 ORIG_RAX: ffffffffffffff13
RAX: 1ffff1100d0e2900 RBX: 0000000000000000 RCX: 0000000000000100
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: ffffc90000007d10 R08: ffffffff816688f7 R09: fffffbfff12891d5
R10: fffffbfff12891d5 R11: 0000000000000000 R12: 00000001000148f0
R13: 0000000000000100 R14: ffff888068714a94 R15: dffffc0000000000
 trace_timer_expire_exit+0x1b9/0x280 include/trace/events/timer.h:125
 call_timer_fn+0x9d/0x170 kernel/time/timer.c:1405
 expire_timers kernel/time/timer.c:1449 [inline]
 __run_timers+0x776/0x970 kernel/time/timer.c:1773
 run_timer_softirq+0x4a/0x90 kernel/time/timer.c:1786
 __do_softirq+0x283/0x7bd arch/x86/include/asm/paravirt.h:762
 invoke_softirq kernel/softirq.c:373 [inline]
 irq_exit+0x227/0x230 kernel/softirq.c:413
 exiting_irq arch/x86/include/asm/apic.h:536 [inline]
 smp_apic_timer_interrupt+0x113/0x280 arch/x86/kernel/apic/apic.c:1137
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
 </IRQ>
RIP: 0010:perf_trace_init+0xab/0x220 kernel/trace/trace_event_perf.c:225
Code: 0f 84 26 01 00 00 49 c7 c4 80 75 0e 89 66 90 49 8d 5d 40 48 89 d8 48 c1 e8 03 42 0f b6 04 30 84 c0 0f 85 e1 00 00 00 48 63 1b <4c> 89 ff 48 89 de e8 6a f0 f8 ff 49 39 df 0f 85 81 00 00 00 49 8d
RSP: 0018:ffffc90007bffba8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000000 RBX: 000000000000068a RCX: 0000000000040000
RDX: ffffc900104db000 RSI: 000000000000302d RDI: 000000000000302e
RBP: ffffc90007bffbd8 R08: ffffffff817d8f56 R09: fffffbfff121cea1
R10: fffffbfff121cea1 R11: 0000000000000000 R12: ffffffff890e7580
R13: ffffffff891bed78 R14: dffffc0000000000 R15: 00000000000002b8
 perf_tp_event_init+0x8b/0x110 kernel/events/core.c:9011
 perf_try_init_event+0x14e/0x3c0 kernel/events/core.c:10462
 perf_init_event kernel/events/core.c:10514 [inline]
 perf_event_alloc+0x1032/0x2810 kernel/events/core.c:10794
 __do_sys_perf_event_open kernel/events/core.c:11277 [inline]
 __se_sys_perf_event_open+0x6e3/0x41c0 kernel/events/core.c:11151
 __x64_sys_perf_event_open+0xbf/0xd0 kernel/events/core.c:11151
 do_syscall_64+0xf7/0x1c0 arch/x86/entry/common.c:294
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45aff9
Code: ad b6 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 7b b6 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007fd5d21dec78 EFLAGS: 00000246 ORIG_RAX: 000000000000012a
RAX: ffffffffffffffda RBX: 00007fd5d21df6d4 RCX: 000000000045aff9
RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 0000000020000440
RBP: 000000000075bf20 R08: 0000000000000000 R09: 0000000000000000
R10: ffffffffffffffff R11: 0000000000000246 R12: 00000000ffffffff
R13: 00000000000007e5 R14: 00000000004c9135 R15: 000000000075bf2c

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2020/01/16 20:45 upstream f5ae2ea6347a 3de7aabb .config console log report ci-upstream-kasan-gce-smack-root
* Struck through repros no longer work on HEAD.