INFO: rcu detected stall in garp_join

INFO: rcu detected stall in garp_join_timer
Status: auto-closed as invalid on 2020/06/07 13:30
Reported-by: syzbot+bd714e8a7cb90f3f553a@syzkaller.appspotmail.com
First crash: 486d, last: 454d

similar bugs (5):
Kernel	Title	Count	Last	Reported	Patched	Status
upstream	INFO: rcu detected stall in garp_join_timer	1	526d	525d	0/22	closed as invalid on 2019/11/29 14:24
upstream	INFO: rcu detected stall in garp_join_timer (2)	14	96d	446d	0/22	upstream: reported on 2020/02/16 10:50
linux-4.14	INFO: rcu detected stall in garp_join_timer	4	449d	465d	0/1	auto-closed as invalid on 2020/06/12 11:13
linux-4.19	INFO: rcu detected stall in garp_join_timer (2)	1	321d	321d	0/1	auto-closed as invalid on 2020/10/18 11:34
linux-4.19	INFO: rcu detected stall in garp_join_timer (3)	1	114d	114d	0/1	upstream: reported on 2021/01/13 13:34

Sample crash report:

TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
ucma_write: process 220 (syz-executor.0) changed security contexts after opening file descriptor, this is not allowed.
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 	1-...!: (10499 ticks this GP) idle=4b6/1/0x4000000000000004 softirq=152264/152264 fqs=3 
rcu: 	 (t=10500 jiffies g=224237 q=644)
rcu: rcu_preempt kthread starved for 10493 jiffies! g224237 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0
rcu: RCU grace-period kthread stack dump:
rcu_preempt     I29104    10      2 0x80000000
Call Trace:
 context_switch kernel/sched/core.c:2826 [inline]
 __schedule+0x866/0x1dc0 kernel/sched/core.c:3515
 schedule+0x92/0x1c0 kernel/sched/core.c:3559
 schedule_timeout+0x4db/0xfc0 kernel/time/timer.c:1806
 rcu_gp_kthread+0xd5c/0x2190 kernel/rcu/tree.c:2202
 kthread+0x354/0x420 kernel/kthread.c:246
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
NMI backtrace for cpu 1
CPU: 1 PID: 21070 Comm: syz-executor.0 Not tainted 4.19.102-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:77 [inline]
 dump_stack+0x197/0x210 lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1b0/0x1f8 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+0x189/0x1d5 kernel/rcu/tree.c:1340
 print_cpu_stall kernel/rcu/tree.c:1478 [inline]
 check_cpu_stall kernel/rcu/tree.c:1550 [inline]
 __rcu_pending kernel/rcu/tree.c:3293 [inline]
 rcu_pending kernel/rcu/tree.c:3336 [inline]
 rcu_check_callbacks.cold+0x5e3/0xd90 kernel/rcu/tree.c:2682
 update_process_times+0x32/0x80 kernel/time/timer.c:1638
 tick_sched_handle+0xa2/0x190 kernel/time/tick-sched.c:168
 tick_sched_timer+0x47/0x130 kernel/time/tick-sched.c:1278
 __run_hrtimer kernel/time/hrtimer.c:1401 [inline]
 __hrtimer_run_queues+0x33b/0xdc0 kernel/time/hrtimer.c:1463
 hrtimer_interrupt+0x314/0x770 kernel/time/hrtimer.c:1521
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1067 [inline]
 smp_apic_timer_interrupt+0x111/0x550 arch/x86/kernel/apic/apic.c:1092
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:893
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0x95/0xe0 kernel/locking/spinlock.c:184
Code: 48 c7 c0 48 57 f2 88 48 ba 00 00 00 00 00 fc ff df 48 c1 e8 03 80 3c 10 00 75 39 48 83 3d 7a 4c 9d 01 00 74 24 48 89 df 57 9d <0f> 1f 44 00 00 bf 01 00 00 00 e8 3c c4 f3 f9 65 8b 05 e5 e2 ac 78
RSP: 0018:ffff8880ae907bc8 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff11e4ae9 RBX: 0000000000000286 RCX: 1ffff1100959b1e9
RDX: dffffc0000000000 RSI: ffff88804acd8f28 RDI: 0000000000000286
RBP: ffff8880ae907bd8 R08: ffff88804acd8680 R09: ffff88804acd8f48
R10: 0000000000000000 R11: 0000000000000000 R12: ffff8880ae923a00
R13: 0000000000000000 R14: 0000000000000000 R15: ffff8880ae923a00
 __mod_timer kernel/time/timer.c:1059 [inline]
 mod_timer+0x571/0x1030 kernel/time/timer.c:1102
 garp_join_timer_arm+0x5e/0x80 net/802/garp.c:401
 garp_join_timer+0x73/0x80 net/802/garp.c:414
 call_timer_fn+0x18d/0x720 kernel/time/timer.c:1326
 expire_timers kernel/time/timer.c:1363 [inline]
 __run_timers kernel/time/timer.c:1684 [inline]
 __run_timers kernel/time/timer.c:1652 [inline]
 run_timer_softirq+0x64f/0x16a0 kernel/time/timer.c:1697
 __do_softirq+0x25c/0x921 kernel/softirq.c:292
 invoke_softirq kernel/softirq.c:372 [inline]
 irq_exit+0x180/0x1d0 kernel/softirq.c:412
 exiting_irq arch/x86/include/asm/apic.h:536 [inline]
 smp_apic_timer_interrupt+0x13b/0x550 arch/x86/kernel/apic/apic.c:1094
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:893
 </IRQ>
RIP: 0010:on_stack arch/x86/include/asm/stacktrace.h:46 [inline]
RIP: 0010:update_stack_state+0x210/0x5f0 arch/x86/kernel/unwind_frame.c:238
Code: 48 39 cb 49 8b 75 10 0f 82 5d ff ff ff 48 39 f3 0f 83 54 ff ff ff 48 8b 95 70 ff ff ff 48 8d 3c 13 48 39 f9 0f 83 40 ff ff ff <48> 39 fe 0f 82 37 ff ff ff 4d 8d 7d 40 48 b9 00 00 00 00 00 fc ff
RSP: 0018:ffff888097db7468 EFLAGS: 00000283 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000001 RBX: ffff888097db7920 RCX: ffff888097db0000
RDX: 0000000000000010 RSI: ffff888097db8000 RDI: ffff888097db7930
RBP: ffff888097db7530 R08: 0000000000000001 R09: ffff888097db7670
R10: ffff888097db7640 R11: ffff888097db7650 R12: ffffed1012fb6ec4
R13: ffff888097db7618 R14: 1ffff11012fb6e95 R15: dffffc0000000000
 unwind_next_frame.part.0+0x1a9/0xa20 arch/x86/kernel/unwind_frame.c:333
 unwind_next_frame+0x3b/0x50 arch/x86/kernel/unwind_frame.c:287
 __save_stack_trace+0x83/0x100 arch/x86/kernel/stacktrace.c:44
 save_stack_trace+0x1a/0x20 arch/x86/kernel/stacktrace.c:60
 save_stack+0x45/0xd0 mm/kasan/kasan.c:448
 set_track mm/kasan/kasan.c:460 [inline]
 kasan_kmalloc mm/kasan/kasan.c:553 [inline]
 kasan_kmalloc+0xce/0xf0 mm/kasan/kasan.c:531
 kasan_slab_alloc+0xf/0x20 mm/kasan/kasan.c:490
 slab_post_alloc_hook mm/slab.h:445 [inline]
 slab_alloc mm/slab.c:3397 [inline]
 kmem_cache_alloc+0x11b/0x700 mm/slab.c:3557
 kmem_cache_zalloc include/linux/slab.h:699 [inline]
 ebitmap_cpy+0xcd/0x270 security/selinux/ss/ebitmap.c:60
 mls_context_cpy_low security/selinux/ss/context.h:71 [inline]
 mls_compute_sid+0x5d0/0xf60 security/selinux/ss/mls.c:566
 security_compute_sid.part.0+0xace/0x15c0 security/selinux/ss/services.c:1785
 security_compute_sid security/selinux/ss/services.c:1825 [inline]
 security_transition_sid+0x126/0x190 security/selinux/ss/services.c:1825
 socket_sockcreate_sid security/selinux/hooks.c:4558 [inline]
 socket_sockcreate_sid security/selinux/hooks.c:4550 [inline]
 selinux_socket_post_create+0x675/0x830 security/selinux/hooks.c:4611
 security_socket_post_create+0x83/0xd0 security/security.c:1381
 __sock_create+0x5b1/0x730 net/socket.c:1292
 sock_create net/socket.c:1316 [inline]
 __sys_socket+0x103/0x220 net/socket.c:1346
 __do_sys_socket net/socket.c:1355 [inline]
 __se_sys_socket net/socket.c:1353 [inline]
 __x64_sys_socket+0x73/0xb0 net/socket.c:1353
 do_syscall_64+0xfd/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45b399
Code: ad b6 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 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 0f 83 7b b6 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007fd3f8362c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 00007fd3f83636d4 RCX: 000000000045b399
RDX: 0000000000000000 RSI: 0000000000000002 RDI: 0000000000000002
RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000000000000b4d R14: 00000000004cc390 R15: 000000000075bfd4
rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
rcu: 	(detected by 0, t=10585 jiffies, g=1645, q=0)
rcu: All QSes seen, last rcu_sched kthread activity 85 (4295103998-4295103913), jiffies_till_next_fqs=1, root ->qsmask 0x0
syz-executor.1  R  running task    23592  8012   8009 0x00000008
Call Trace:
 <IRQ>
 sched_show_task kernel/sched/core.c:5338 [inline]
 sched_show_task.cold+0x2ee/0x35d kernel/sched/core.c:5313
 print_other_cpu_stall kernel/rcu/tree.c:1430 [inline]
 check_cpu_stall kernel/rcu/tree.c:1557 [inline]
 __rcu_pending kernel/rcu/tree.c:3293 [inline]
 rcu_pending kernel/rcu/tree.c:3336 [inline]
 rcu_check_callbacks.cold+0xaa1/0xd90 kernel/rcu/tree.c:2682
 update_process_times+0x32/0x80 kernel/time/timer.c:1638
 tick_sched_handle+0xa2/0x190 kernel/time/tick-sched.c:168
 tick_sched_timer+0x47/0x130 kernel/time/tick-sched.c:1278
 __run_hrtimer kernel/time/hrtimer.c:1401 [inline]
 __hrtimer_run_queues+0x33b/0xdc0 kernel/time/hrtimer.c:1463
 hrtimer_interrupt+0x314/0x770 kernel/time/hrtimer.c:1521
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1067 [inline]
 smp_apic_timer_interrupt+0x111/0x550 arch/x86/kernel/apic/apic.c:1092
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:893
 </IRQ>
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0x95/0xe0 kernel/locking/spinlock.c:184
Code: 48 c7 c0 48 57 f2 88 48 ba 00 00 00 00 00 fc ff df 48 c1 e8 03 80 3c 10 00 75 39 48 83 3d 7a 4c 9d 01 00 74 24 48 89 df 57 9d <0f> 1f 44 00 00 bf 01 00 00 00 e8 3c c4 f3 f9 65 8b 05 e5 e2 ac 78
RSP: 0018:ffff88808c3ffb18 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff11e4ae9 RBX: 0000000000000286 RCX: 0000000000000000
RDX: dffffc0000000000 RSI: ffff8880a982aa40 RDI: 0000000000000286
RBP: ffff88808c3ffb28 R08: ffff8880a982a1c0 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: ffff888095f7f5d8
R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
 spin_unlock_irqrestore include/linux/spinlock.h:384 [inline]
 __wake_up_common_lock+0xfe/0x190 kernel/sched/wait.c:122
 __wake_up+0xe/0x10 kernel/sched/wait.c:145
 fsnotify_add_event+0x379/0x4a0 fs/notify/notification.c:139
 inotify_handle_event+0x341/0x5b0 fs/notify/inotify/inotify_fsnotify.c:128
 send_to_group fs/notify/fsnotify.c:238 [inline]
 fsnotify+0x6a6/0xf00 fs/notify/fsnotify.c:374
 fsnotify_close include/linux/fsnotify.h:238 [inline]
 __fput+0x53c/0x8b0 fs/file_table.c:264
 ____fput+0x16/0x20 fs/file_table.c:309
 task_work_run+0x145/0x1c0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:193 [inline]
 exit_to_usermode_loop+0x273/0x2c0 arch/x86/entry/common.c:167
 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
 do_syscall_64+0x53d/0x620 arch/x86/entry/common.c:296
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x414f10
Code: 01 f0 ff ff 0f 83 30 1b 00 00 c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 83 3d 5d 35 66 00 00 75 14 b8 03 00 00 00 0f 05 <48> 3d 01 f0 ff ff 0f 83 04 1b 00 00 c3 48 83 ec 08 e8 0a fc ff ff
RSP: 002b:00007ffee2d65fd8 EFLAGS: 00000246 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000414f10
RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000003
RBP: 0000000000002faa R08: 0000000000000000 R09: 000000000000000a
R10: 0000000000000075 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffee2d66010 R14: 000000000017cec8 R15: 00007ffee2d66020

Crashes (7):
Manager	Time	Kernel	Commit	Syzkaller	Config	Log	Report
ci2-linux-4-19	2020/02/08 13:29	linux-4.19.y	b499cf4b	06150bf1	.config	log	report
ci2-linux-4-19	2020/02/07 19:37	linux-4.19.y	b499cf4b	06150bf1	.config	log	report
ci2-linux-4-19	2020/02/06 09:19	linux-4.19.y	b499cf4b	662cf49a	.config	log	report
ci2-linux-4-19	2020/02/05 07:44	linux-4.19.y	32ee7492	93e5e335	.config	log	report
ci2-linux-4-19	2020/01/25 06:56	linux-4.19.y	d183c8e2	2e95ab33	.config	log	report
ci2-linux-4-19	2020/01/13 19:03	linux-4.19.y	dcd88898	99565c1a	.config	log	report
ci2-linux-4-19	2020/01/08 07:29	linux-4.19.y	3d40d711	6738e0b3	.config	log	report