syzbot


INFO: rcu detected stall in br_multicast_group_expired

Status: auto-closed as invalid on 2020/07/25 13:48
Reported-by: syzbot+58e430e5d7e908ef0c40@syzkaller.appspotmail.com
First crash: 819d, last: 819d
similar bugs (2):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in br_multicast_group_expired 4 1311d 1346d 0/22 auto-closed as invalid on 2019/05/20 09:57
upstream INFO: rcu detected stall in br_multicast_group_expired (2) 1 539d 539d 0/22 auto-closed as invalid on 2021/04/01 12:38

Sample crash report:
IPv6: ADDRCONF(NETDEV_UP): veth1_virt_wifi: link is not ready
IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready
IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready
INFO: rcu_preempt self-detected stall on CPU
	1-...: (1 GPs behind) idle=6c6/140000000000002/0 softirq=23002/23004 fqs=6 
	 (t=10500 jiffies g=5076 c=5075 q=1152)
rcu_preempt kthread starved for 10421 jiffies! g5076 c5075 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_preempt     R  running task    29920     8      2 0x80000000
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
 rcu_gp_kthread+0xb8e/0x1ce0 kernel/rcu/tree.c:2255
 kthread+0x30d/0x420 kernel/kthread.c:232
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
NMI backtrace for cpu 1
CPU: 1 PID: 10730 Comm: syz-executor.4 Not tainted 4.14.174-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:17 [inline]
 dump_stack+0x13e/0x194 lib/dump_stack.c:58
 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x139/0x17e lib/nmi_backtrace.c:62
 trigger_single_cpu_backtrace include/linux/nmi.h:158 [inline]
 rcu_dump_cpu_stacks+0x16d/0x1b8 kernel/rcu/tree.c:1396
 print_cpu_stall kernel/rcu/tree.c:1542 [inline]
 check_cpu_stall kernel/rcu/tree.c:1610 [inline]
 __rcu_pending kernel/rcu/tree.c:3390 [inline]
 rcu_pending kernel/rcu/tree.c:3452 [inline]
 rcu_check_callbacks.cold+0x490/0xdaa kernel/rcu/tree.c:2792
 update_process_times+0x29/0x60 kernel/time/timer.c:1590
 tick_sched_handle+0x7d/0x150 kernel/time/tick-sched.c:165
 tick_sched_timer+0x3e/0x120 kernel/time/tick-sched.c:1223
 __run_hrtimer kernel/time/hrtimer.c:1223 [inline]
 __hrtimer_run_queues+0x25c/0xbb0 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+0x8f/0xa0 arch/x86/entry/entry_64.S:792
RIP: 0010:unwind_next_frame+0xa52/0x17a0 arch/x86/kernel/unwind_orc.c:432
RSP: 0018:ffff8880aeb07748 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff10
RAX: dffffc0000000000 RBX: ffff8880aeb077f8 RCX: 1ffff11015d60f01
RDX: 1ffff11015d60f09 RSI: ffff8880aeb07700 RDI: ffff88806bf4f720
RBP: 1ffff11015d60ef0 R08: ffffffff88f18ff6 R09: ffff88806bf4f6b0
R10: ffff8880aeb0782d R11: 0000000000058071 R12: ffffffff88f18ff2
R13: ffff8880aeb07830 R14: ffff88806bf4f728 R15: 0000000000000001
 __save_stack_trace+0x6b/0xd0 arch/x86/kernel/stacktrace.c:44
 save_stack+0x32/0xa0 mm/kasan/kasan.c:447
 set_track mm/kasan/kasan.c:459 [inline]
 kasan_kmalloc mm/kasan/kasan.c:551 [inline]
 kasan_kmalloc+0xbf/0xe0 mm/kasan/kasan.c:529
 kmem_cache_alloc+0x127/0x770 mm/slab.c:3552
 kmem_cache_zalloc include/linux/slab.h:651 [inline]
 fill_pool lib/debugobjects.c:110 [inline]
 __debug_object_init+0x5b7/0x830 lib/debugobjects.c:341
 debug_object_init lib/debugobjects.c:393 [inline]
 debug_object_activate+0x274/0x450 lib/debugobjects.c:474
 debug_rcu_head_queue kernel/rcu/rcu.h:152 [inline]
 __call_rcu.constprop.0+0x31/0x7e0 kernel/rcu/tree.c:3050
 br_multicast_group_expired+0x303/0x390 net/bridge/br_multicast.c:262
 call_timer_fn+0x14a/0x650 kernel/time/timer.c:1279
 expire_timers kernel/time/timer.c:1318 [inline]
 __run_timers kernel/time/timer.c:1636 [inline]
 __run_timers kernel/time/timer.c:1604 [inline]
 run_timer_softirq+0x52a/0x1390 kernel/time/timer.c:1649
 __do_softirq+0x254/0x9bf kernel/softirq.c:288
 invoke_softirq kernel/softirq.c:368 [inline]
 irq_exit+0x15b/0x1a0 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+0x8f/0xa0 arch/x86/entry/entry_64.S:792
 </IRQ>
RIP: 0010:preempt_count_add+0x64/0x170 kernel/sched/core.c:3107
RSP: 0018:ffff88806bf4f2f8 EFLAGS: 00000282 ORIG_RAX: ffffffffffffff10
RAX: dffffc0000000000 RBX: 0000000000000003 RCX: 0000000000000000
RDX: 1ffffffff14b0f60 RSI: ffffffff857e0f83 RDI: 0000000000000001
RBP: 0000000000000001 R08: 0000000000000001 R09: ffff88806bf4f788
R10: ffff88806bf4f3f5 R11: 0000000000058071 R12: 0000000000000000
R13: ffff88806bf4f3f8 R14: ffff88808f5f4100 R15: 0000000000000001
 unwind_next_frame+0xc6/0x17a0 arch/x86/kernel/unwind_orc.c:336
 __save_stack_trace+0x6b/0xd0 arch/x86/kernel/stacktrace.c:44
 save_stack+0x32/0xa0 mm/kasan/kasan.c:447
 set_track mm/kasan/kasan.c:459 [inline]
 kasan_kmalloc mm/kasan/kasan.c:551 [inline]
 kasan_kmalloc+0xbf/0xe0 mm/kasan/kasan.c:529
 slab_post_alloc_hook mm/slab.h:442 [inline]
 slab_alloc mm/slab.c:3390 [inline]
 kmem_cache_alloc+0x114/0x770 mm/slab.c:3550
 dst_alloc+0xe6/0x190 net/core/dst.c:107
 __ip6_dst_alloc+0x2e/0x50 net/ipv6/route.c:357
 ip6_rt_pcpu_alloc net/ipv6/route.c:1036 [inline]
 rt6_make_pcpu_route net/ipv6/route.c:1066 [inline]
 ip6_pol_route+0x1055/0x1e30 net/ipv6/route.c:1191
 fib6_rule_lookup+0xc3/0x3e0 net/ipv6/fib6_rules.c:83
 ip6_route_output_flags+0x250/0x2d0 net/ipv6/route.c:1327
 ip6_route_output include/net/ip6_route.h:81 [inline]
 ip6_dst_lookup_tail+0xdb6/0x1750 net/ipv6/ip6_output.c:975
 ip6_dst_lookup_flow+0x9c/0x1f0 net/ipv6/ip6_output.c:1098
 tcp_v6_connect+0xd4e/0x1ac0 net/ipv6/tcp_ipv6.c:255
 __inet_stream_connect+0x6ad/0xb90 net/ipv4/af_inet.c:618
 inet_stream_connect+0x53/0xa0 net/ipv4/af_inet.c:682
 SYSC_connect+0x1c6/0x250 net/socket.c:1655
 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
 entry_SYSCALL_64_after_hwframe+0x42/0xb7
RIP: 0033:0x45c849
RSP: 002b:00007f130d0d9c78 EFLAGS: 00000246 ORIG_RAX: 000000000000002a
RAX: ffffffffffffffda RBX: 00007f130d0da6d4 RCX: 000000000045c849
RDX: 000000000000001c RSI: 0000000020000080 RDI: 0000000000000004
RBP: 000000000076bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000082 R14: 00000000004c31a7 R15: 000000000076bf0c
INFO: rcu_sched detected stalls on CPUs/tasks:
	1-...: (10502 ticks this GP) idle=6c6/140000000000002/0 softirq=23004/23004 fqs=6 
	(detected by 0, t=10594 jiffies, g=3507, c=3506, q=6)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 10730 Comm: syz-executor.4 Not tainted 4.14.174-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
task: ffff88808f5f4100 task.stack: ffff88806bf48000
RIP: 0010:arch_local_save_flags arch/x86/include/asm/paravirt.h:774 [inline]
RIP: 0010:arch_local_irq_save arch/x86/include/asm/paravirt.h:796 [inline]
RIP: 0010:lock_acquire+0x97/0x3f0 kernel/locking/lockdep.c:3989
RSP: 0018:ffff8880aeb06e20 EFLAGS: 00000082
RAX: 1ffffffff0fa2cd0 RBX: ffff88808f5f4100 RCX: 0000000000000002
RDX: dffffc0000000000 RSI: 0000000000000000 RDI: ffff88808f5f497c
RBP: ffffffff87d84360 R08: 0000000000000000 R09: 0000000000000000
R10: ffff8880aeb071b0 R11: ffff88808f5f4100 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002
FS:  00007f130d0da700(0000) GS:ffff8880aeb00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000001b2c023000 CR3: 000000008fc6b000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 </IRQ>
Code: 80 66 d1 87 48 ba 00 00 00 00 00 fc ff df 48 c1 e8 03 80 3c 10 00 0f 85 3d 03 00 00 48 83 3d 80 2f 8b 06 00 0f 84 95 02 00 00 9c <58> 0f 1f 44 00 00 48 ba 00 00 00 00 00 fc ff df 48 89 44 24 08 
rcu_sched kthread starved for 10515 jiffies! g3507 c3506 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0
rcu_sched       R  running task    29920     9      2 0x80000000
Call Trace:
 schedule+0x8d/0x1b0 kernel/sched/core.c:3428
 schedule_timeout+0x43c/0xe40 kernel/time/timer.c:1746
 rcu_gp_kthread+0xb8e/0x1ce0 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/03/27 13:47 linux-4.14.y 01364dad1d45 831e9a81 .config log report