syzbot


INFO: rcu detected stall in call_timer_fn (2)
Status: closed as invalid on 2022/02/08 10:46
Reported-by: syzbot+f33388c0f89e31abe109@syzkaller.appspotmail.com
First crash: 988d, last: 128d

Cause bisection: the issue happens on the oldest tested release (bisect log)
Crash: INFO: rcu detected stall in corrupted (log)
Repro: C syz .config

Fix bisection: failed (bisect log)
similar bugs (7):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 INFO: rcu detected stall in call_timer_fn (2) 16 568d 842d 0/1 auto-closed as invalid on 2021/03/04 12:30
linux-4.14 INFO: rcu detected stall in call_timer_fn (2) 1 631d 631d 0/1 auto-closed as invalid on 2020/12/31 22:00
upstream INFO: rcu detected stall in call_timer_fn 2 1321d 1334d 0/22 closed as dup on 2019/01/02 16:26
linux-4.19 INFO: rcu detected stall in call_timer_fn 1 982d 982d 0/1 auto-closed as invalid on 2020/01/15 18:43
linux-4.14 INFO: rcu detected stall in call_timer_fn 5 755d 875d 0/1 auto-closed as invalid on 2020/08/29 09:07
linux-4.19 BUG: soft lockup in call_timer_fn C error 24 41d 321d 0/1 upstream: reported C repro on 2021/07/10 02:48
linux-4.14 BUG: soft lockup in call_timer_fn 1 866d 866d 0/1 auto-closed as invalid on 2020/05/10 07:38

Sample crash report:
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 	1-...!: (10499 ticks this GP) idle=f02/1/0x4000000000000002 softirq=28069/28069 fqs=0 
	(t=10501 jiffies g=43153 q=3)
rcu: rcu_preempt kthread starved for 10502 jiffies! g43153 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
rcu: RCU grace-period kthread stack dump:
rcu_preempt     R  running task    29392    10      2 0x80004000
Call Trace:
 context_switch kernel/sched/core.c:3254 [inline]
 __schedule+0x755/0x1580 kernel/sched/core.c:3880
 schedule+0xd9/0x260 kernel/sched/core.c:3947
 schedule_timeout+0x486/0xc50 kernel/time/timer.c:1807
 rcu_gp_fqs_loop kernel/rcu/tree.c:1611 [inline]
 rcu_gp_kthread+0x9b2/0x18c0 kernel/rcu/tree.c:1768
 kthread+0x361/0x430 kernel/kthread.c:255
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 23362 Comm: syz-executor241 Not tainted 5.3.0-rc8+ #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:arch_static_branch arch/x86/include/asm/jump_label.h:34 [inline]
RIP: 0010:static_key_false include/linux/jump_label.h:200 [inline]
RIP: 0010:native_write_msr+0xb/0x30 arch/x86/include/asm/msr.h:164
Code: c3 0f 21 f0 c3 0f 0b 0f 1f 84 00 00 00 00 00 0f 0b 0f 1f 40 00 66 2e 0f 1f 84 00 00 00 00 00 89 f9 89 f0 0f 30 0f 1f 44 00 00 <c3> 55 48 c1 e2 20 89 f6 48 89 e5 48 09 d6 31 d2 e8 90 24 1f 02 5d
RSP: 0018:ffff8880ae809ac8 EFLAGS: 00000046
RAX: 000000000000003e RBX: 0000000000000838 RCX: 0000000000000838
RDX: 0000000000000000 RSI: 000000000000003e RDI: 0000000000000838
RBP: ffff8880ae809ae0 R08: ffff888095c62500 R09: fffffbfff134afb0
R10: fffffbfff134afaf R11: ffffffff89a57d7f R12: ffff8880ae820180
R13: 000000000000003e R14: 0000000000000000 R15: 0000000000000000
FS:  00007fc6756a4700(0000) GS:ffff8880ae800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000000 CR3: 0000000096b61000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 apic_write arch/x86/include/asm/apic.h:398 [inline]
 lapic_next_event+0x51/0x80 arch/x86/kernel/apic/apic.c:468
 clockevents_program_event+0x25c/0x370 kernel/time/clockevents.c:334
 tick_program_event+0xb4/0x130 kernel/time/tick-oneshot.c:44
 hrtimer_interrupt+0x369/0x770 kernel/time/hrtimer.c:1522
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1106 [inline]
 smp_apic_timer_interrupt+0x160/0x610 arch/x86/kernel/apic/apic.c:1131
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:830
RIP: 0010:__preempt_count_dec_and_test arch/x86/include/asm/preempt.h:94 [inline]
RIP: 0010:rcu_read_unlock_sched_notrace include/linux/rcupdate.h:730 [inline]
RIP: 0010:trace_timer_expire_entry include/trace/events/timer.h:90 [inline]
RIP: 0010:call_timer_fn+0x607/0x780 kernel/time/timer.c:1321
Code: 28 10 00 44 0f b6 35 7d 3a 3f 08 31 ff 44 89 f6 e8 5e 29 10 00 45 84 f6 0f 84 de 00 00 00 e8 10 28 10 00 65 ff 0d 21 b1 9f 7e <41> 0f 94 c6 31 ff 44 89 f6 e8 3b 29 10 00 45 84 f6 0f 84 7d fb ff
RSP: 0018:ffff8880ae809d10 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff13
RAX: ffff888095c62500 RBX: ffff88805ed1a800 RCX: ffffffff81624cb6
RDX: 0000000000000100 RSI: ffffffff81624ce0 RDI: 0000000000000005
RBP: ffff8880ae809dd8 R08: ffff888095c62500 R09: fffffbfff134afb0
R10: fffffbfff134afaf R11: ffffffff89a57d7f R12: 0000000000000101
R13: ffff8880ae809db0 R14: 0000000000000000 R15: dffffc0000000000
 expire_timers kernel/time/timer.c:1366 [inline]
 __run_timers kernel/time/timer.c:1685 [inline]
 __run_timers kernel/time/timer.c:1653 [inline]
 run_timer_softirq+0x697/0x17a0 kernel/time/timer.c:1698
 __do_softirq+0x262/0x98c kernel/softirq.c:292
 invoke_softirq kernel/softirq.c:373 [inline]
 irq_exit+0x19b/0x1e0 kernel/softirq.c:413
 exiting_irq arch/x86/include/asm/apic.h:537 [inline]
 smp_apic_timer_interrupt+0x1a3/0x610 arch/x86/kernel/apic/apic.c:1133
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:830
 </IRQ>
RIP: 0010:__read_once_size include/linux/compiler.h:199 [inline]
RIP: 0010:check_kcov_mode kernel/kcov.c:70 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x20/0x50 kernel/kcov.c:102
Code: ff 90 90 90 90 90 90 90 90 90 55 48 89 e5 65 48 8b 04 25 40 fe 01 00 65 8b 15 04 89 8f 7e 81 e2 00 01 1f 00 48 8b 75 08 75 2b <8b> 90 f0 12 00 00 83 fa 02 75 20 48 8b 88 f8 12 00 00 8b 80 f4 12
RSP: 0018:ffff88805fa87948 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: ffff888095c62500 RBX: ffff88805fa87a98 RCX: ffffffff8166d913
RDX: 0000000000000000 RSI: ffffffff8166d91d RDI: 0000000000000004
RBP: ffff88805fa87948 R08: ffff888095c62500 R09: ffffed100bf50f19
R10: ffffed100bf50f18 R11: 0000000000000003 R12: 0000000000000001
R13: ffffed100bf50f53 R14: 0000000000000000 R15: 0000000000000000
 futex_wait_setup+0x2ad/0x390 kernel/futex.c:2700
 futex_wait+0x1fc/0x5e0 kernel/futex.c:2730
 do_futex+0x175/0x1dc0 kernel/futex.c:3646
 __do_sys_futex kernel/futex.c:3707 [inline]
 __se_sys_futex kernel/futex.c:3675 [inline]
 __x64_sys_futex+0x3f7/0x590 kernel/futex.c:3675
 do_syscall_64+0xfd/0x6a0 arch/x86/entry/common.c:296
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44cd49
Code: e8 0c d4 02 00 48 83 c4 18 c3 0f 1f 80 00 00 00 00 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 1b cb fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007fc6756a3cf8 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca
RAX: ffffffffffffffda RBX: 00000000006dec68 RCX: 000000000044cd49
RDX: 0000000000000000 RSI: 0000000000000080 RDI: 00000000006dec68
RBP: 00000000006dec60 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dec6c
R13: 00007fffc43033df R14: 00007fc6756a49c0 R15: 000000000000002d
NMI backtrace for cpu 1
CPU: 1 PID: 23361 Comm: syz-executor241 Not tainted 5.3.0-rc8+ #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+0x172/0x1f0 lib/dump_stack.c:113
 nmi_cpu_backtrace.cold+0x70/0xb2 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x23b/0x28b 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:164 [inline]
 rcu_dump_cpu_stacks+0x183/0x1cf 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:2736 [inline]
 rcu_sched_clock_irq.cold+0x4dd/0xc13 kernel/rcu/tree.c:2183
 update_process_times+0x32/0x80 kernel/time/timer.c:1639
 tick_sched_handle+0xa2/0x190 kernel/time/tick-sched.c:167
 tick_sched_timer+0x53/0x140 kernel/time/tick-sched.c:1296
 __run_hrtimer kernel/time/hrtimer.c:1389 [inline]
 __hrtimer_run_queues+0x364/0xe40 kernel/time/hrtimer.c:1451
 hrtimer_interrupt+0x314/0x770 kernel/time/hrtimer.c:1509
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1106 [inline]
 smp_apic_timer_interrupt+0x160/0x610 arch/x86/kernel/apic/apic.c:1131
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:830
 </IRQ>
RIP: 0010:check_memory_region_inline mm/kasan/generic.c:176 [inline]
RIP: 0010:check_memory_region+0x9/0x1a0 mm/kasan/generic.c:192
Code: 00 55 48 89 f2 be f8 00 00 00 48 89 e5 e8 0f bd 8f 05 5d c3 0f 1f 00 66 2e 0f 1f 84 00 00 00 00 00 48 85 f6 0f 84 34 01 00 00 <48> b8 ff ff ff ff ff 7f ff ff 55 0f b6 d2 48 39 c7 48 89 e5 41 55
RSP: 0018:ffff888064fbf960 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000000 RBX: ffffc90000c72388 RCX: ffffffff81595bd7
RDX: 0000000000000000 RSI: 0000000000000004 RDI: ffffc90000c72388
RBP: ffff888064fbf968 R08: 1ffff9200018e471 R09: fffff5200018e472
R10: fffff5200018e471 R11: ffffc90000c7238b R12: 0000000000000001
R13: 0000000000000003 R14: fffff5200018e471 R15: 0000000000000001
 atomic_read include/asm-generic/atomic-instrumented.h:26 [inline]
 virt_spin_lock arch/x86/include/asm/qspinlock.h:83 [inline]
 native_queued_spin_lock_slowpath+0xb7/0x9f0 kernel/locking/qspinlock.c:325
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:654 [inline]
 queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:50 [inline]
 queued_spin_lock include/asm-generic/qspinlock.h:81 [inline]
 do_raw_spin_lock+0x20e/0x2e0 kernel/locking/spinlock_debug.c:113
 __raw_spin_lock include/linux/spinlock_api_smp.h:143 [inline]
 _raw_spin_lock+0x37/0x40 kernel/locking/spinlock.c:151
 spin_lock include/linux/spinlock.h:338 [inline]
 futex_wake+0x1dc/0x4d0 kernel/futex.c:1618
 do_futex+0x358/0x1dc0 kernel/futex.c:3651
 __do_sys_futex kernel/futex.c:3707 [inline]
 __se_sys_futex kernel/futex.c:3675 [inline]
 __x64_sys_futex+0x3f7/0x590 kernel/futex.c:3675
 do_syscall_64+0xfd/0x6a0 arch/x86/entry/common.c:296
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44cd49
Code: e8 0c d4 02 00 48 83 c4 18 c3 0f 1f 80 00 00 00 00 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 1b cb fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007fffc4303458 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca
RAX: ffffffffffffffda RBX: 00007fffc43034f0 RCX: 000000000044cd49
RDX: 000000000044cd49 RSI: 0000000000000081 RDI: 00000000006dec68
RBP: 00007fffc4303490 R08: 00007fff00000015 R09: 00007fff00000015
R10: 00007fff00000015 R11: 0000000000000246 R12: 00000000000ff0e0
R13: 00000000006dec60 R14: 00000000006dec6c R15: 000000000000002d

Crashes (47):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce-root 2019/09/11 14:56 upstream 3120b9a6a3f7 a60cb4cd .config log report syz C
ci-upstream-bpf-kasan-gce 2020/09/01 08:18 bpf 3168c158ad35 d5a3ae1f .config log report syz C
ci-upstream-kasan-gce-smack-root 2022/01/19 03:23 upstream 99613159ad74 731a2d23 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/12/30 18:44 upstream eec4df26e24e 2e49f10d .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/12/21 17:38 upstream 6e0567b73052 a938f0b8 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/12/20 11:37 upstream a7904a538933 021b36cb .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/12/13 06:13 upstream 90d9fbc16b69 49ca1f59 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-root 2021/11/27 08:09 upstream c5c17547b778 63eeac02 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/11/26 13:42 upstream a4849f6000e2 63eeac02 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce 2021/11/16 01:59 upstream 8ab774587903 83f5c9b5 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/11/06 16:51 upstream fe91c4725aee 4c1be0be .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-smack-root 2021/10/06 11:31 upstream f6274b06e326 0a63fd36 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/09/27 07:58 upstream 996148ee05d0 78494d16 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/09/16 05:38 upstream b7213ffa0e58 07e953c1 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-selinux-root 2021/08/19 11:53 upstream d6d09a694205 a2fe1cb5 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-smack-root 2021/07/30 03:08 upstream 7e96bf476270 c585c7b0 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce 2021/04/21 16:57 upstream 1fe5501ba1ab 95777977 .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce 2021/02/10 18:19 upstream e0756cfc7d7c 2bd9619f .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce 2021/02/05 03:15 upstream 5c279c4cf206 23a562df .config log report info INFO: rcu detected stall in call_timer_fn
ci-upstream-kasan-gce-root 2021/01/04 03:35 upstream e71ba9452f0b 79264ae3 .config log report info
ci-upstream-kasan-gce-root 2020/12/08 00:46 upstream cd796ed33450 51a9082e .config log report info
ci-upstream-kasan-gce-root 2020/12/03 09:36 upstream 34816d20f173 e6b0d314 .config log report info
ci-upstream-kasan-gce-selinux-root 2020/11/22 15:46 upstream a349e4c65960 0d27f508 .config log report info
ci-upstream-kasan-gce 2020/10/07 14:55 upstream c85fb28b6f99 1880b4a9 .config log report info
ci-upstream-kasan-gce-root 2020/09/23 04:33 upstream eff48ddeab78 3e8f6c27 .config log report info
ci-upstream-kasan-gce-selinux-root 2020/09/04 13:36 upstream e28f0104343d abf9ba4f .config log report
ci-upstream-kasan-gce 2020/08/29 21:59 upstream 4d41ead6ead9 d5a3ae1f .config log report
ci-upstream-kasan-gce-root 2020/08/27 18:29 upstream 15bc20c6af4c 816e0689 .config log report
ci-upstream-kasan-gce 2020/08/25 15:05 upstream 6a9dc5fd6170 344da168 .config log report
ci-upstream-kasan-gce-smack-root 2020/08/23 19:04 upstream c3d8f220d012 cef5ae68 .config log report
ci-upstream-kasan-gce-root 2020/07/10 01:17 upstream 0bddd227f3dc bc238812 .config log report
ci-upstream-kasan-gce-root 2020/07/09 13:27 upstream 0bddd227f3dc bc238812 .config log report
ci-upstream-kasan-gce-selinux-root 2020/06/07 08:45 upstream 7ae77150d94d e6b89e4e .config log report
ci-upstream-kasan-gce-smack-root 2020/05/15 08:12 upstream 8c1684bb81f1 2d572622 .config log report
ci-upstream-kasan-gce-smack-root 2020/03/24 21:07 upstream 76ccd234269b 68660b21 .config log report
ci-upstream-kasan-gce-smack-root 2020/03/14 14:34 upstream fffb08b37df9 749688d2 .config log report
ci-upstream-kasan-gce-smack-root 2020/02/18 10:23 upstream 11a48a5a18c6 1ce142dc .config log report
ci-upstream-kasan-gce-smack-root 2020/02/04 16:24 upstream 322bf2d3446a 93e5e335 .config log report
ci-upstream-kasan-gce-selinux-root 2020/02/03 08:30 upstream 46d6b7becb1d 93e5e335 .config log report
ci-upstream-kasan-gce 2020/01/26 06:11 upstream 2821e26f3a0a f4e7270e .config log report
ci-upstream-kasan-gce-selinux-root 2020/01/13 01:36 upstream 040a3c33623b 53faa9fe .config log report
ci-qemu-upstream 2019/11/29 10:50 upstream 81b6b96475ac 4f7e1d0f .config log report
ci-upstream-kasan-gce-root 2019/09/28 08:47 upstream 8f744bdee4fe d8074e0b .config log report
ci-qemu2-arm64 2021/09/29 03:17 upstream d33bec7b3dfa d82cb927 .config log report info BUG: soft lockup in call_timer_fn
ci-upstream-kasan-gce-386 2020/01/30 22:10 upstream 39bed42de2e7 5ed23f9a .config log report
ci-upstream-linux-next-kasan-gce-root 2021/11/05 07:36 linux-next b477ae38e815 4c1be0be .config log report info BUG: soft lockup in call_timer_fn