syzbot


INFO: rcu detected stall in fq_pie_timer

Status: upstream: reported C repro on 2023/05/22 23:26
Bug presence: origin:lts-only
[Documentation on labels]
Reported-by: syzbot+d7d5aab0ec13e6e303c5@syzkaller.appspotmail.com
First crash: 509d, last: 418d
Fix commit to backport (bisect log) :
tree: upstream
commit f85fa45d4a9408d98c46c8fa45ba2e3b2f4bf219
Author: Peilin Ye <peilin.ye@bytedance.com>
Date: Mon May 29 19:54:03 2023 +0000

  net/sched: Reserve TC_H_INGRESS (TC_H_CLSACT) for ingress (clsact) Qdiscs

[report pending]
  
Bug presence (2)
Date Name Commit Repro Result
2023/05/23 linux-5.15.y (ToT) 9d6bde853685 C [report] INFO: rcu detected stall in fq_pie_timer
2023/05/23 upstream (ToT) ae8373a5add4 C Didn't crash
Similar bugs (7)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in fq_pie_timer (2) net C done 33 21d 290d 0/28 upstream: reported C repro on 2023/12/27 13:54
upstream INFO: rcu detected stall in fq_pie_timer net C error 35 990d 1207d 20/28 fixed on 2022/03/08 16:11
upstream BUG: soft lockup in fq_pie_timer (2) net C error done 3 536d 602d 0/28 closed as invalid on 2023/05/26 05:46
upstream BUG: soft lockup in fq_pie_timer net C error error 10 731d 769d 0/28 closed as invalid on 2022/11/18 11:06
linux-6.1 BUG: soft lockup in fq_pie_timer (2) origin:upstream C inconclusive 5 176d 410d 0/3 upstream: reported C repro on 2023/08/29 01:49
upstream BUG: soft lockup in fq_pie_timer (4) net syz 5 396d 446d 23/28 fixed on 2023/10/12 12:48
linux-6.1 BUG: soft lockup in fq_pie_timer C done 7 490d 498d 3/3 fixed on 2023/07/22 07:09
Last patch testing requests (5)
Created Duration User Patch Repo Result
2024/10/01 15:11 20m retest repro linux-5.15.y OK log
2024/10/01 15:11 18m retest repro linux-5.15.y OK log
2024/10/01 15:11 22m retest repro linux-5.15.y OK log
2024/10/01 15:11 22m retest repro linux-5.15.y OK log
2024/10/01 15:11 27m retest repro linux-5.15.y OK log
Fix bisection attempts (2)
Created Duration User Patch Repo Result
2023/12/03 15:21 6h40m fix candidate upstream OK (1) job log
2023/10/02 20:05 3h23m fix candidate upstream error job log

Sample crash report:
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 	0-....: (1 GPs behind) idle=27f/1/0x4000000000000000 softirq=5557/5560 fqs=5214 
	(t=10502 jiffies g=5861 q=2014)
NMI backtrace for cpu 0
CPU: 0 PID: 3649 Comm: syz-executor.0 Not tainted 5.15.126-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/26/2023
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2cb lib/dump_stack.c:106
 nmi_cpu_backtrace+0x46a/0x4a0 lib/nmi_backtrace.c:111
 nmi_trigger_cpumask_backtrace+0x181/0x2a0 lib/nmi_backtrace.c:62
 trigger_single_cpu_backtrace include/linux/nmi.h:166 [inline]
 rcu_dump_cpu_stacks+0x223/0x390 kernel/rcu/tree_stall.h:343
 print_cpu_stall kernel/rcu/tree_stall.h:627 [inline]
 check_cpu_stall kernel/rcu/tree_stall.h:711 [inline]
 rcu_pending kernel/rcu/tree.c:3911 [inline]
 rcu_sched_clock_irq+0xf26/0x1770 kernel/rcu/tree.c:2606
 update_process_times+0x196/0x200 kernel/time/timer.c:1788
 tick_sched_handle kernel/time/tick-sched.c:237 [inline]
 tick_sched_timer+0x22d/0x3c0 kernel/time/tick-sched.c:1441
 __run_hrtimer kernel/time/hrtimer.c:1685 [inline]
 __hrtimer_run_queues+0x55b/0xcf0 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x392/0x980 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1085 [inline]
 __sysvec_apic_timer_interrupt+0x139/0x470 arch/x86/kernel/apic/apic.c:1102
 sysvec_apic_timer_interrupt+0x3e/0xb0 arch/x86/kernel/apic/apic.c:1096
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:check_kcov_mode kernel/kcov.c:163 [inline]
RIP: 0010:write_comp_data kernel/kcov.c:218 [inline]
RIP: 0010:__sanitizer_cov_trace_const_cmp8+0x1a/0x80 kernel/kcov.c:291
Code: 18 4c 89 44 0a 20 49 ff c1 4c 89 09 c3 0f 1f 00 4c 8b 04 24 65 48 8b 0d d4 59 82 7e 65 8b 05 d5 59 82 7e a9 00 01 ff 00 74 10 <a9> 00 01 00 00 74 57 83 b9 34 16 00 00 00 74 4e 8b 81 10 16 00 00
RSP: 0018:ffffc90000007a88 EFLAGS: 00000206
RAX: 0000000000000102 RBX: 00028f5c28f5c28f RCX: ffff88807d993b80
RDX: ffff88807d993b80 RSI: 0000000000000000 RDI: 00028f5c28f5c28f
RBP: 0019999999999998 R08: ffffffff88698b03 R09: 0000000000000003
R10: ffffffffffffffff R11: dffffc0000000001 R12: 0000000225c17d04
R13: 0000000000000000 R14: 00000015798ee228 R15: 1ffff1100f21df63
 pie_calculate_probability+0x223/0x820 net/sched/sch_pie.c:348
 fq_pie_timer+0x132/0x260 net/sched/sch_fq_pie.c:389
 call_timer_fn+0x16d/0x560 kernel/time/timer.c:1421
 expire_timers kernel/time/timer.c:1466 [inline]
 __run_timers+0x67c/0x890 kernel/time/timer.c:1737
 run_timer_softirq+0x63/0xf0 kernel/time/timer.c:1750
 __do_softirq+0x3b3/0x93a kernel/softirq.c:558
 invoke_softirq kernel/softirq.c:432 [inline]
 __irq_exit_rcu+0x155/0x240 kernel/softirq.c:636
 irq_exit_rcu+0x5/0x20 kernel/softirq.c:648
 sysvec_apic_timer_interrupt+0x91/0xb0 arch/x86/kernel/apic/apic.c:1096
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:638
RIP: 0010:finish_lock_switch+0x91/0x100 kernel/sched/core.c:4781
Code: 45 31 c9 68 47 63 59 81 e8 fc 19 09 00 48 83 c4 08 4c 89 ff e8 40 d9 fe ff 66 90 4c 89 ff e8 46 55 cc 08 e8 f1 24 2d 00 fb 5b <41> 5c 41 5d 41 5e 41 5f c3 44 89 f1 80 e1 07 80 c1 03 38 c1 7c 87
RSP: 0018:ffffc900040cea58 EFLAGS: 00000282
RAX: ce59a8076f677600 RBX: ffff888014de8034 RCX: ffffffff8162eaf8
RDX: dffffc0000000000 RSI: ffffffff8a8b0c60 RDI: ffffffff8ad876c0
RBP: ffffc900040cead0 R08: dffffc0000000000 R09: fffffbfff1f7ae22
R10: 0000000000000000 R11: dffffc0000000001 R12: dffffc0000000000
R13: 1ffff11017347483 R14: ffff8880b9a3a418 R15: ffff8880b9a396c0
 finish_task_switch+0x134/0x630 kernel/sched/core.c:4898
 context_switch kernel/sched/core.c:5029 [inline]
 __schedule+0x12cc/0x4590 kernel/sched/core.c:6372
 preempt_schedule_common+0x83/0xd0 kernel/sched/core.c:6548
 preempt_schedule+0xd9/0xe0 kernel/sched/core.c:6573
 preempt_schedule_thunk+0x16/0x18 arch/x86/entry/thunk_64.S:34
 __raw_spin_unlock include/linux/spinlock_api_smp.h:152 [inline]
 _raw_spin_unlock+0x36/0x40 kernel/locking/spinlock.c:186
 spin_unlock include/linux/spinlock.h:403 [inline]
 __cond_resched_lock+0x7c/0xe0 kernel/sched/core.c:8286
 __purge_vmap_area_lazy+0x1623/0x1740 mm/vmalloc.c:1710
 try_purge_vmap_area_lazy+0x34/0x50 mm/vmalloc.c:1723
 free_unmap_vmap_area mm/vmalloc.c:1778 [inline]
 remove_vm_area+0x1ae/0x1d0 mm/vmalloc.c:2517
 vm_remove_mappings mm/vmalloc.c:2546 [inline]
 __vunmap+0x325/0xa20 mm/vmalloc.c:2611
 qdisc_destroy+0x114/0x3b0 net/sched/sch_generic.c:1025
 notify_and_destroy net/sched/sch_api.c:1012 [inline]
 qdisc_graft+0x113b/0x1460 net/sched/sch_api.c:1089
 tc_modify_qdisc+0xee9/0x1710 net/sched/sch_api.c:1700
 rtnetlink_rcv_msg+0x993/0xee0 net/core/rtnetlink.c:5603
 netlink_rcv_skb+0x1cf/0x410 net/netlink/af_netlink.c:2505
 netlink_unicast_kernel net/netlink/af_netlink.c:1330 [inline]
 netlink_unicast+0x7b6/0x980 net/netlink/af_netlink.c:1356
 netlink_sendmsg+0xa30/0xd60 net/netlink/af_netlink.c:1924
 sock_sendmsg_nosec net/socket.c:704 [inline]
 sock_sendmsg net/socket.c:724 [inline]
 ____sys_sendmsg+0x59e/0x8f0 net/socket.c:2412
 ___sys_sendmsg+0x252/0x2e0 net/socket.c:2466
 __sys_sendmsg net/socket.c:2495 [inline]
 __do_sys_sendmsg net/socket.c:2504 [inline]
 __se_sys_sendmsg+0x19a/0x260 net/socket.c:2502
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x3d/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x61/0xcb
RIP: 0033:0x7f6e37f7cae9
Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 e1 20 00 00 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 73 01 c3 48 c7 c1 b0 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f6e372fe0c8 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
RAX: ffffffffffffffda RBX: 00007f6e3809bf80 RCX: 00007f6e37f7cae9
RDX: 0000000000000000 RSI: 00000000200007c0 RDI: 0000000000000003
RBP: 00007f6e37fc847a R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000000b R14: 00007f6e3809bf80 R15: 00007fff83143f48
 </TASK>

Crashes (23):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2023/08/15 00:24 linux-5.15.y 24c4de4069cb 39990d51 .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan INFO: rcu detected stall in fq_pie_timer
2023/08/21 11:22 linux-5.15.y f6f7927ac664 d216d8a0 .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/08/18 05:34 linux-5.15.y f6f7927ac664 74b106b6 .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/08/16 03:18 linux-5.15.y 24c4de4069cb 39990d51 .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/08/16 00:01 linux-5.15.y 24c4de4069cb 39990d51 .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/08/06 21:24 linux-5.15.y 38d4ca22a528 4ffcc9ef .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/28 00:47 linux-5.15.y f67653019430 4cd5bb25 .config console log report syz [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/08/20 04:47 linux-5.15.y f6f7927ac664 d216d8a0 .config console log report syz C [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan INFO: rcu detected stall in fq_pie_timer
2023/07/29 03:36 linux-5.15.y 09996673e313 92476829 .config console log report syz C [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan INFO: rcu detected stall in fq_pie_timer
2023/05/22 23:26 linux-5.15.y 9d6bde853685 4bce1a3e .config console log report syz C [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan INFO: rcu detected stall in fq_pie_timer
2023/07/17 00:15 linux-5.15.y d54cfc420586 35d9ecc5 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan INFO: rcu detected stall in fq_pie_timer
2023/06/09 17:26 linux-5.15.y 7349e40704a0 9018a337 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan INFO: rcu detected stall in fq_pie_timer
2023/06/07 08:58 linux-5.15.y d7af3e5ba454 a4ae4f42 .config console log report syz C [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/06 15:09 linux-5.15.y d7af3e5ba454 a4ae4f42 .config console log report syz C [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/08 08:29 linux-5.15.y d7af3e5ba454 058b3a5a .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan BUG: soft lockup in fq_pie_timer
2023/06/14 02:32 linux-5.15.y 7349e40704a0 d2ee9228 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/14 01:57 linux-5.15.y 7349e40704a0 d2ee9228 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/13 18:00 linux-5.15.y 7349e40704a0 749afb64 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/09 20:59 linux-5.15.y 7349e40704a0 9018a337 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/09 15:26 linux-5.15.y 7349e40704a0 9018a337 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/06 09:15 linux-5.15.y d7af3e5ba454 a4ae4f42 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/05 19:37 linux-5.15.y d7af3e5ba454 a4ae4f42 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
2023/06/03 18:37 linux-5.15.y 0ab06468cbd1 a4ae4f42 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-5-15-kasan-arm64 BUG: soft lockup in fq_pie_timer
* Struck through repros no longer work on HEAD.