syzbot


INFO: rcu detected stall in timerqueue_add

Status: auto-closed as invalid on 2019/02/22 10:34
Subsystems: rtc
[Documentation on labels]
Reported-by: syzbot+d4d83cb6819f75d81ca8@syzkaller.appspotmail.com
First crash: 2089d, last: 2089d

Sample crash report:
xprt_adjust_timeout: rq_timeout = 0!
rcu: INFO: rcu_sched self-detected stall on CPU
rcu: 	0-....: (104999 ticks this GP) idle=5a6/1/0x4000000000000002 softirq=55015/55015 fqs=26147 
rcu: 	 (t=105000 jiffies g=95665 q=3484)
NMI backtrace for cpu 0
CPU: 0 PID: 11903 Comm: kworker/0:4 Not tainted 4.18.0-rc7-next-20180803+ #31
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: events rtc_timer_do_work
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1c9/0x2b4 lib/dump_stack.c:113
 nmi_cpu_backtrace.cold.3+0x48/0x88 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x151/0x192 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_single_cpu_backtrace include/linux/nmi.h:162 [inline]
 rcu_dump_cpu_stacks+0x175/0x1c2 kernel/rcu/tree.c:1340
 print_cpu_stall.cold.78+0x2fb/0x59c kernel/rcu/tree.c:1478
 check_cpu_stall kernel/rcu/tree.c:1550 [inline]
 __rcu_pending kernel/rcu/tree.c:3276 [inline]
 rcu_pending kernel/rcu/tree.c:3319 [inline]
 rcu_check_callbacks+0xd93/0x1660 kernel/rcu/tree.c:2665
 update_process_times+0x2d/0x70 kernel/time/timer.c:1636
 tick_sched_handle+0x9f/0x180 kernel/time/tick-sched.c:164
 tick_sched_timer+0x45/0x130 kernel/time/tick-sched.c:1274
 __run_hrtimer kernel/time/hrtimer.c:1398 [inline]
 __hrtimer_run_queues+0x3eb/0x10c0 kernel/time/hrtimer.c:1460
 hrtimer_interrupt+0x2f3/0x750 kernel/time/hrtimer.c:1518
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1028 [inline]
 smp_apic_timer_interrupt+0x165/0x730 arch/x86/kernel/apic/apic.c:1053
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:867
 </IRQ>
RIP: 0010:rb_insert_color+0xb2/0x1480 lib/rbtree.c:451
Code: c7 40 1c 00 f2 f2 f2 c7 40 20 f2 f2 f2 f2 c7 40 24 00 f2 f2 f2 c7 40 28 f2 f2 f2 f2 c7 40 2c 00 f2 f2 f2 c7 40 30 f2 f2 f2 f2 <c7> 40 34 00 f2 f2 f2 c7 40 38 f2 f2 f2 f2 c7 40 3c 00 f2 f2 f2 c7
RSP: 0018:ffff8801968b6f38 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff13
RAX: ffffed0032d16ded RBX: 1ffff10032d16ded RCX: ffff8801968b6f68
RDX: dffffc0000000000 RSI: ffff8801ce4558e0 RDI: ffff8801ce455930
RBP: ffff8801968b7330 R08: ffff88019f48c4c0 R09: ffffed0039c8ab11
R10: ffffed0039c8ab11 R11: ffff8801ce45588b R12: dffffc0000000000
R13: 0000000000000000 R14: ffff8801968b7308 R15: ffff8801ce455930
 timerqueue_add+0x173/0x2b0 lib/timerqueue.c:58
 rtc_timer_do_work+0xaba/0x11d0 drivers/rtc/interface.c:926
 process_one_work+0xc73/0x1ba0 kernel/workqueue.c:2153
 worker_thread+0x189/0x13c0 kernel/workqueue.c:2296
 kthread+0x35a/0x420 kernel/kthread.c:246
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:415
rcu: INFO: rcu_bh self-detected stall on CPU
rcu: 	0-....: (112277 ticks this GP) idle=5a6/1/0x4000000000000002 softirq=51566/55015 fqs=25968 
rcu: 	 (t=105000 jiffies g=-915 q=1)
NMI backtrace for cpu 0
CPU: 0 PID: 11903 Comm: kworker/0:4 Not tainted 4.18.0-rc7-next-20180803+ #31
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: events rtc_timer_do_work
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1c9/0x2b4 lib/dump_stack.c:113
 nmi_cpu_backtrace.cold.3+0x48/0x88 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x151/0x192 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_single_cpu_backtrace include/linux/nmi.h:162 [inline]
 rcu_dump_cpu_stacks+0x175/0x1c2 kernel/rcu/tree.c:1340
 print_cpu_stall.cold.78+0x2fb/0x59c kernel/rcu/tree.c:1478
 check_cpu_stall kernel/rcu/tree.c:1550 [inline]
 __rcu_pending kernel/rcu/tree.c:3276 [inline]
 rcu_pending kernel/rcu/tree.c:3319 [inline]
 rcu_check_callbacks+0xd93/0x1660 kernel/rcu/tree.c:2665
 update_process_times+0x2d/0x70 kernel/time/timer.c:1636
 tick_sched_handle+0x9f/0x180 kernel/time/tick-sched.c:164
 tick_sched_timer+0x45/0x130 kernel/time/tick-sched.c:1274
 __run_hrtimer kernel/time/hrtimer.c:1398 [inline]
 __hrtimer_run_queues+0x3eb/0x10c0 kernel/time/hrtimer.c:1460
 hrtimer_interrupt+0x2f3/0x750 kernel/time/hrtimer.c:1518
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1028 [inline]
 smp_apic_timer_interrupt+0x165/0x730 arch/x86/kernel/apic/apic.c:1053
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:867
 </IRQ>
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:783 [inline]
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0xa1/0xc0 kernel/locking/spinlock.c:184
Code: e8 b0 f1 87 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 75 21 48 83 3d be 3c 39 01 00 74 0e 48 89 df 57 9d <0f> 1f 44 00 00 eb bb 0f 0b 0f 0b e8 bf 25 03 fb eb 97 e8 b8 25 03
RSP: 0018:ffff8801968b7320 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: dffffc0000000000 RBX: 0000000000000286 RCX: ffffffff81606487
RDX: 1ffffffff0fe361d RSI: 0000000000000004 RDI: 0000000000000286
RBP: ffff8801968b7330 R08: ffffed0039c8ab0b R09: ffffed0039c8ab0a
R10: ffffed0039c8ab0a R11: ffff8801ce455853 R12: ffff8801ce455850
R13: 0000000000000090 R14: ffff8801ce455850 R15: dffffc0000000000
 spin_unlock_irqrestore include/linux/spinlock.h:384 [inline]
 rtc_handle_legacy_irq+0x76/0xd0 drivers/rtc/interface.c:608
 rtc_uie_update_irq+0x1f/0x30 drivers/rtc/interface.c:637
 rtc_timer_do_work+0x287/0x11d0 drivers/rtc/interface.c:918
 process_one_work+0xc73/0x1ba0 kernel/workqueue.c:2153
 worker_thread+0x189/0x13c0 kernel/workqueue.c:2296
 kthread+0x35a/0x420 kernel/kthread.c:246
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:415

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2018/08/06 00:13 linux-next 116b181bb646 1beb8136 .config console log report ci-upstream-linux-next-kasan-gce-root
* Struck through repros no longer work on HEAD.