syzbot


INFO: rcu detected stall in br_handle_frame (3)

Status: auto-closed as invalid on 2021/01/28 07:46
Reported-by: syzbot+afdc135ecfed8b91b823@syzkaller.appspotmail.com
First crash: 645d, last: 645d
similar bugs (8):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in br_handle_frame C done 341 1025d 1030d 14/22 fixed on 2019/10/09 10:54
upstream INFO: rcu detected stall in br_handle_frame (2) C done 2 930d 926d 16/22 fixed on 2020/02/18 14:31
upstream INFO: rcu detected stall in br_handle_frame (3) 1 355d 355d 0/22 auto-closed as invalid on 2021/10/15 13:41
linux-4.14 INFO: rcu detected stall in br_handle_frame (2) C done 1 930d 930d 1/1 fixed on 2020/01/19 15:05
linux-4.14 INFO: rcu detected stall in br_handle_frame C done 15 1022d 1034d 1/1 fixed on 2019/12/07 19:24
linux-4.19 INFO: rcu detected stall in br_handle_frame (2) C error 28 93d 630d 0/1 upstream: reported C repro on 2020/10/14 18:56
linux-4.19 INFO: rcu detected stall in br_handle_frame C done 41 1022d 1035d 1/1 fixed on 2019/12/07 19:18
upstream INFO: rcu detected stall in br_handle_frame (4) 1 194d 194d 0/22 closed as invalid on 2022/02/08 10:10

Sample crash report:
INFO: rcu_sched detected stalls on CPUs/tasks:
	1-...: (10499 ticks this GP) idle=af6/140000000000002/0 softirq=24922/24973 fqs=35 
	(detected by 0, t=10502 jiffies, g=2725, c=2724, q=34)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 12824 Comm: syz-executor.3 Not tainted 4.14.198-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff88805bc82440 task.stack: ffff88804c3b8000
RIP: 0010:preempt_count_add+0x35/0x170 kernel/sched/core.c:3099
RSP: 0018:ffff8880aeb06790 EFLAGS: 00000097
RAX: 0000000000000003 RBX: ffffffff8a5a8c00 RCX: 0000000000000000
RDX: 0000000000000004 RSI: 0000000000000000 RDI: 0000000000000001
RBP: 0000000000000001 R08: ffff88821fff7058 R09: ffff88821fff704f
R10: ffff88821fff7057 R11: 00000061aeb2c91b R12: 0000000000000086
R13: 1ffff11015d60cfb R14: ffff8880553306c8 R15: ffffffff8a728e48
FS:  00007fe1779f3700(0000) GS:ffff8880aeb00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000558ec51bcea8 CR3: 0000000007c6a000 CR4: 00000000001426e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:109 [inline]
 _raw_spin_lock_irqsave+0x70/0xc0 kernel/locking/spinlock.c:160
 debug_object_deactivate lib/debugobjects.c:502 [inline]
 debug_object_deactivate+0xdf/0x2e0 lib/debugobjects.c:491
 debug_hrtimer_deactivate kernel/time/hrtimer.c:406 [inline]
 debug_deactivate kernel/time/hrtimer.c:453 [inline]
 __run_hrtimer kernel/time/hrtimer.c:1193 [inline]
 __hrtimer_run_queues+0x15b/0xc80 kernel/time/hrtimer.c:1287
 hrtimer_interrupt+0x1e6/0x5e0 kernel/time/hrtimer.c:1321
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1075 [inline]
 smp_apic_timer_interrupt+0x117/0x5e0 arch/x86/kernel/apic/apic.c:1100
 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:793
RIP: 0010:unwind_next_frame+0x861/0x17d0 arch/x86/kernel/unwind_orc.c:363
RSP: 0018:ffff8880aeb06ae0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
RAX: dffffc0000000000 RBX: 1ffff11015d60d63 RCX: ffffffff88f1cbfc
RDX: 1ffff11015d60d7b RSI: 0000000000000001 RDI: 0000000000000001
RBP: 0000000000000001 R08: ffffffff88f1cc00 R09: ffffffff88f1cc01
R10: 000000000001dd04 R11: 0000000000058071 R12: ffff8880aeb06bd5
R13: ffff8880aeb06bd8 R14: ffff8880aeb06bf0 R15: ffff8880aeb06ba0
 __save_stack_trace+0x90/0x160 arch/x86/kernel/stacktrace.c:44
 save_stack mm/kasan/kasan.c:447 [inline]
 set_track mm/kasan/kasan.c:459 [inline]
 kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:551
 slab_post_alloc_hook mm/slab.h:442 [inline]
 slab_alloc mm/slab.c:3390 [inline]
 kmem_cache_alloc+0x111/0x3c0 mm/slab.c:3550
 skb_clone+0x126/0x9a0 net/core/skbuff.c:1282
 dev_queue_xmit_nit+0x2ee/0x950 net/core/dev.c:1939
 xmit_one net/core/dev.c:3001 [inline]
 dev_hard_start_xmit+0xaa/0x890 net/core/dev.c:3021
 __dev_queue_xmit+0x1d7f/0x2480 net/core/dev.c:3521
 br_dev_queue_push_xmit+0x191/0x540 net/bridge/br_forward.c:55
 br_nf_dev_queue_xmit+0x277/0x1330 net/bridge/br_netfilter_hooks.c:724
 NF_HOOK include/linux/netfilter.h:250 [inline]
 br_nf_post_routing+0x586/0xdb0 net/bridge/br_netfilter_hooks.c:825
 nf_hook_entry_hookfn include/linux/netfilter.h:108 [inline]
 nf_hook_slow+0xb0/0x1a0 net/netfilter/core.c:467
 nf_hook include/linux/netfilter.h:205 [inline]
 NF_HOOK include/linux/netfilter.h:248 [inline]
 br_forward_finish+0x1cf/0x2f0 net/bridge/br_forward.c:67
 br_nf_hook_thresh+0x214/0x290 net/bridge/br_netfilter_hooks.c:1008
 br_nf_forward_finish+0x2b6/0x5f0 net/bridge/br_netfilter_hooks.c:550
 NF_HOOK include/linux/netfilter.h:250 [inline]
 br_nf_forward_ip+0x5d3/0x10c0 net/bridge/br_netfilter_hooks.c:617
 nf_hook_entry_hookfn include/linux/netfilter.h:108 [inline]
 nf_hook_slow+0xb0/0x1a0 net/netfilter/core.c:467
 nf_hook include/linux/netfilter.h:205 [inline]
 NF_HOOK include/linux/netfilter.h:248 [inline]
 __br_forward+0x2c2/0x950 net/bridge/br_forward.c:111
 deliver_clone net/bridge/br_forward.c:127 [inline]
 br_flood+0x30d/0x410 net/bridge/br_forward.c:222
 br_handle_frame_finish+0xae8/0x15c0 net/bridge/br_input.c:210
 br_nf_hook_thresh+0x214/0x290 net/bridge/br_netfilter_hooks.c:1008
 br_nf_pre_routing_finish_ipv6+0x609/0xbe0 net/bridge/br_netfilter_ipv6.c:210
 NF_HOOK include/linux/netfilter.h:250 [inline]
 br_nf_pre_routing_ipv6+0x393/0x730 net/bridge/br_netfilter_ipv6.c:240
 br_nf_pre_routing+0xc72/0x1109 net/bridge/br_netfilter_hooks.c:491
 nf_hook_entry_hookfn include/linux/netfilter.h:108 [inline]
 nf_hook_slow+0xb0/0x1a0 net/netfilter/core.c:467
 nf_hook include/linux/netfilter.h:205 [inline]
 NF_HOOK include/linux/netfilter.h:248 [inline]
 br_handle_frame+0x757/0x10a0 net/bridge/br_input.c:348
 __netif_receive_skb_core+0x730/0x2a30 net/core/dev.c:4428
 __netif_receive_skb+0x27/0x1a0 net/core/dev.c:4512
 process_backlog+0x210/0x6f0 net/core/dev.c:5194
 napi_poll net/core/dev.c:5596 [inline]
 net_rx_action+0x466/0xfd0 net/core/dev.c:5662
 __do_softirq+0x254/0xa1d kernel/softirq.c:288
 invoke_softirq kernel/softirq.c:368 [inline]
 irq_exit+0x193/0x240 kernel/softirq.c:409
 exiting_irq arch/x86/include/asm/apic.h:648 [inline]
 smp_apic_timer_interrupt+0x141/0x5e0 arch/x86/kernel/apic/apic.c:1102
 apic_timer_interrupt+0x93/0xa0 arch/x86/entry/entry_64.S:793
 </IRQ>
RIP: 0010:zap_pte_range mm/memory.c:1338 [inline]
RIP: 0010:zap_pmd_range mm/memory.c:1444 [inline]
RIP: 0010:zap_pud_range mm/memory.c:1473 [inline]
RIP: 0010:zap_p4d_range mm/memory.c:1494 [inline]
RIP: 0010:unmap_page_range+0xe3e/0x1ce0 mm/memory.c:1515
RSP: 0018:ffff88804c3bf7c0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
RAX: 1ffff110140d155c RBX: dffffc0000000000 RCX: ffff88804c3bf9d0
RDX: 0000000000000000 RSI: ffff88804c3bf9d0 RDI: ffff8880a068aae0
RBP: ffff88809f83d948 R08: 0000000000000000 R09: 0000000000000001
R10: 0000000000000000 R11: ffff88805bc82440 R12: ffffea0002016e80
R13: ffffea0002016ea0 R14: 000000000052a000 R15: 0000000000000020
 unmap_single_vma+0x147/0x2b0 mm/memory.c:1560
 unmap_vmas+0x9d/0x160 mm/memory.c:1590
 exit_mmap+0x270/0x4d0 mm/mmap.c:3056
 __mmput kernel/fork.c:930 [inline]
 mmput kernel/fork.c:951 [inline]
 mmput+0xfa/0x420 kernel/fork.c:946
 exit_mm kernel/exit.c:545 [inline]
 do_exit+0x948/0x27f0 kernel/exit.c:852
 do_group_exit+0x100/0x2e0 kernel/exit.c:962
 get_signal+0x38d/0x1ca0 kernel/signal.c:2423
 do_signal+0x7c/0x1550 arch/x86/kernel/signal.c:814
 exit_to_usermode_loop+0x160/0x200 arch/x86/entry/common.c:160
 prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:270 [inline]
 do_syscall_64+0x4a3/0x640 arch/x86/entry/common.c:297
 entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x45dd99
RSP: 002b:00007fe1779f2c78 EFLAGS: 00000246 ORIG_RAX: 00000000000000d8
RAX: 0000000000000000 RBX: 0000000000027200 RCX: 000000000045dd99
RDX: 0000000000000000 RSI: 0000000000200000 RDI: 00000000202ec000
RBP: 000000000118c018 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000118bfd4
R13: 00007ffffab29d7f R14: 00007fe1779f39c0 R15: 000000000118bfd4
Code: 55 89 fd 53 48 c7 c3 00 8c 5a 8a 48 89 da 48 c1 ea 03 0f b6 14 02 48 89 d8 83 e0 07 83 c0 03 38 d0 7c 08 84 d2 0f 85 11 01 00 00 <8b> 0d 95 f5 1b 09 85 c9 75 07 65 8b 05 5a 57 c3 7e 48 b8 00 00 
rcu_sched kthread starved for 9810 jiffies! g2725 c2724 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=1
rcu_sched       R  running task    30008     9      2 0x80000000
Call Trace:
 context_switch kernel/sched/core.c:2808 [inline]
 __schedule+0x88b/0x1de0 kernel/sched/core.c:3384
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x4af/0xe90 kernel/time/timer.c:1754
 rcu_gp_kthread+0xc0a/0x1e60 kernel/rcu/tree.c:2255
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci2-linux-4-14 2020/09/30 07:46 linux-4.14.y cbfa1702aaf6 8516f6d3 .config log report info