syzbot


INFO: rcu detected stall in mrp_rcv

Status: closed as invalid on 2022/02/08 10:10
Reported-by: syzbot+@syzkaller.appspotmail.com
First crash: 388d, last: 388d

Sample crash report:
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 	0-...!: (10500 ticks this GP) idle=833/1/0x4000000000000000 softirq=57010/57010 fqs=15 
	(t=10500 jiffies g=76621 q=610)
rcu: rcu_preempt kthread starved for 10460 jiffies! g76621 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=1
rcu: 	Unless rcu_preempt kthread gets sufficient CPU time, OOM is now expected behavior.
rcu: RCU grace-period kthread stack dump:
task:rcu_preempt     state:R  running task     stack:26576 pid:   14 ppid:     2 flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:4969 [inline]
 __schedule+0xb72/0x1460 kernel/sched/core.c:6250
 schedule+0x12b/0x1f0 kernel/sched/core.c:6323
 schedule_timeout+0x1b9/0x300 kernel/time/timer.c:1881
 rcu_gp_fqs_loop+0x1fd/0x770 kernel/rcu/tree.c:1955
 rcu_gp_kthread+0xa5/0x350 kernel/rcu/tree.c:2128
 kthread+0x468/0x490 kernel/kthread.c:327
 ret_from_fork+0x1f/0x30
 </TASK>
rcu: Stack dump where RCU GP kthread last ran:
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 3821 Comm: syz-executor.2 Not tainted 5.15.0-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:arch_atomic_try_cmpxchg arch/x86/include/asm/atomic.h:202 [inline]
RIP: 0010:atomic_try_cmpxchg_acquire include/linux/atomic/atomic-instrumented.h:513 [inline]
RIP: 0010:queued_spin_lock include/asm-generic/qspinlock.h:82 [inline]
RIP: 0010:do_raw_spin_lock+0x559/0x8e0 kernel/locking/spinlock_debug.c:115
Code: ff df e9 ca fb ff ff 89 d9 80 e1 07 80 c1 03 38 c1 0f 8c e6 fb ff ff 48 89 df 4d 89 c6 e8 0f 90 68 00 4d 89 f0 e9 d3 fb ff ff <48> 8d 4c 24 60 80 e1 07 80 c1 03 38 c1 0f 8c f4 fb ff ff 48 8d 7c
RSP: 0018:ffffc90000dc00c0 EFLAGS: 00000002
RAX: 00000000ffffff04 RBX: ffffc90000dc0120 RCX: ffffffff81678ec1
RDX: dffffc0000000000 RSI: 0000000000000004 RDI: ffffc90000dc0120
RBP: ffffc90000dc0190 R08: dffffc0000000000 R09: 0000000000000003
R10: fffff520001b8025 R11: 0000000000000004 R12: dffffc0000000000
R13: 1ffffffff21dc089 R14: ffffffff90ee0440 R15: 1ffff920001b8024
FS:  00007f23f7d03700(0000) GS:ffff8880b9b00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f40f2df7c18 CR3: 00000000ae953000 CR4: 00000000003506e0
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:111 [inline]
 _raw_spin_lock_irqsave+0xdd/0x120 kernel/locking/spinlock.c:162
 debug_object_activate+0x97/0x6a0 lib/debugobjects.c:661
 debug_hrtimer_activate kernel/time/hrtimer.c:420 [inline]
 debug_activate kernel/time/hrtimer.c:475 [inline]
 enqueue_hrtimer kernel/time/hrtimer.c:1084 [inline]
 __run_hrtimer kernel/time/hrtimer.c:1702 [inline]
 __hrtimer_run_queues+0x594/0xa60 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x3b3/0x1040 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1086 [inline]
 __sysvec_apic_timer_interrupt+0xf9/0x270 arch/x86/kernel/apic/apic.c:1103
 sysvec_apic_timer_interrupt+0x3e/0xb0 arch/x86/kernel/apic/apic.c:1097
 asm_sysvec_apic_timer_interrupt+0x12/0x20
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0xd4/0x130 kernel/locking/spinlock.c:194
Code: 9c 8f 44 24 20 42 80 3c 23 00 74 08 4c 89 f7 e8 a2 5e cb f7 f6 44 24 21 02 75 4e 41 f7 c7 00 02 00 00 74 01 fb bf 01 00 00 00 <e8> 77 2f 55 f7 65 8b 05 d8 ab fd 75 85 c0 74 3f 48 c7 04 24 0e 36
RSP: 0018:ffffc90000dc0660 EFLAGS: 00000206
RAX: e5bbdd07e5d87300 RBX: 1ffff920001b80d0 RCX: ffffffff81663d31
RDX: dffffc0000000000 RSI: 0000000000000101 RDI: 0000000000000001
RBP: ffffc90000dc06e8 R08: dffffc0000000000 R09: fffffbfff1ff1be1
R10: fffffbfff1ff1be1 R11: 0000000000000000 R12: dffffc0000000000
R13: 1ffff920001b80cc R14: ffffc90000dc0680 R15: 0000000000000246
 __debug_check_no_obj_freed lib/debugobjects.c:1002 [inline]
 debug_check_no_obj_freed+0x5a2/0x650 lib/debugobjects.c:1023
 slab_free_hook mm/slub.c:1698 [inline]
 slab_free_freelist_hook+0xcf/0x1a0 mm/slub.c:1749
 slab_free mm/slub.c:3513 [inline]
 kmem_cache_free+0x95/0x190 mm/slub.c:3529
 mrp_rcv+0x1a80/0x1e00 net/802/mrp.c:822
 __netif_receive_skb_one_core net/core/dev.c:5462 [inline]
 __netif_receive_skb+0x1d1/0x500 net/core/dev.c:5576
 process_backlog+0x518/0x9a0 net/core/dev.c:6452
 __napi_poll+0xbd/0x520 net/core/dev.c:7020
 napi_poll net/core/dev.c:7087 [inline]
 net_rx_action+0x61c/0xf30 net/core/dev.c:7174
 __do_softirq+0x392/0x7a3 kernel/softirq.c:558
 __irq_exit_rcu+0xec/0x170 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:1097
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x12/0x20
RIP: 0010:dev_alloc_name_ns+0x50b/0x9f0 net/core/dev.c:1160
Code: 74 0d 48 89 df e8 c5 8b c0 f9 48 8b 4c 24 18 48 89 5c 24 50 4c 8b 33 49 8d 5e 10 48 89 d8 48 c1 e8 03 4d 89 e5 42 80 3c 20 00 <74> 0d 48 89 df e8 9b 8b c0 f9 48 8b 4c 24 18 48 8d 41 c0 48 89 44
RSP: 0018:ffffc90018546b20 EFLAGS: 00000246
RAX: 1ffff11012fcb702 RBX: ffff888097e5b810 RCX: ffff888030938040
RDX: ffffc9000d4c9000 RSI: 000000000000220c RDI: 000000000000220d
RBP: ffffc90018546c30 R08: ffffffff880f98f1 R09: ffffffff843d2c68
R10: 0000000000000002 R11: ffff8880973d1d00 R12: dffffc0000000000
R13: dffffc0000000000 R14: ffff888097e5b800 R15: ffff88808660d010
 dev_get_valid_name net/core/dev.c:1195 [inline]
 register_netdevice+0x555/0x1c00 net/core/dev.c:10252
 register_vlan_dev+0x36b/0x860 net/8021q/vlan.c:179
 vlan_newlink+0x43f/0x5f0 net/8021q/vlan_netlink.c:187
 __rtnl_newlink net/core/rtnetlink.c:3458 [inline]
 rtnl_newlink+0x14b3/0x1d10 net/core/rtnetlink.c:3506
 rtnetlink_rcv_msg+0x934/0xe60 net/core/rtnetlink.c:5571
 netlink_rcv_skb+0x200/0x470 net/netlink/af_netlink.c:2491
 netlink_unicast_kernel net/netlink/af_netlink.c:1319 [inline]
 netlink_unicast+0x814/0x9f0 net/netlink/af_netlink.c:1345
 netlink_sendmsg+0xa20/0xe40 net/netlink/af_netlink.c:1916
 sock_sendmsg_nosec net/socket.c:704 [inline]
 sock_sendmsg net/socket.c:724 [inline]
 ____sys_sendmsg+0x5b9/0x910 net/socket.c:2409
 ___sys_sendmsg net/socket.c:2463 [inline]
 __sys_sendmsg+0x280/0x370 net/socket.c:2492
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7f23fa7aeae9
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 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 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007f23f7d03188 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
RAX: ffffffffffffffda RBX: 00007f23fa8c2020 RCX: 00007f23fa7aeae9
RDX: 0000000000000000 RSI: 0000000020000140 RDI: 0000000000000007
RBP: 00007f23fa808f6d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffd4c7779ff R14: 00007f23f7d03300 R15: 0000000000022000
 </TASK>
NMI backtrace for cpu 0
CPU: 0 PID: 3814 Comm: syz-executor.5 Not tainted 5.15.0-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:88 [inline]
 dump_stack_lvl+0x1dc/0x2d8 lib/dump_stack.c:106
 nmi_cpu_backtrace+0x45f/0x490 lib/nmi_backtrace.c:105
 nmi_trigger_cpumask_backtrace+0x16a/0x280 lib/nmi_backtrace.c:62
 trigger_single_cpu_backtrace include/linux/nmi.h:164 [inline]
 rcu_dump_cpu_stacks+0x22d/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:3878 [inline]
 rcu_sched_clock_irq+0x1e33/0x2bc0 kernel/rcu/tree.c:2597
 update_process_times+0x197/0x200 kernel/time/timer.c:1785
 tick_sched_handle kernel/time/tick-sched.c:226 [inline]
 tick_sched_timer+0x27d/0x420 kernel/time/tick-sched.c:1421
 __run_hrtimer kernel/time/hrtimer.c:1685 [inline]
 __hrtimer_run_queues+0x4cb/0xa60 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x3b3/0x1040 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1086 [inline]
 __sysvec_apic_timer_interrupt+0xf9/0x270 arch/x86/kernel/apic/apic.c:1103
 sysvec_apic_timer_interrupt+0x3e/0xb0 arch/x86/kernel/apic/apic.c:1097
 asm_sysvec_apic_timer_interrupt+0x12/0x20
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0xd4/0x130 kernel/locking/spinlock.c:194
Code: 9c 8f 44 24 20 42 80 3c 23 00 74 08 4c 89 f7 e8 a2 5e cb f7 f6 44 24 21 02 75 4e 41 f7 c7 00 02 00 00 74 01 fb bf 01 00 00 00 <e8> 77 2f 55 f7 65 8b 05 d8 ab fd 75 85 c0 74 3f 48 c7 04 24 0e 36
RSP: 0018:ffffc900000079e0 EFLAGS: 00000206
RAX: 4b5623ffb0e24300 RBX: 1ffff92000000f40 RCX: ffffffff81663d31
RDX: dffffc0000000000 RSI: 0000000000000102 RDI: 0000000000000001
RBP: ffffc90000007a70 R08: dffffc0000000000 R09: fffffbfff1ff1bf8
R10: fffffbfff1ff1bf8 R11: 0000000000000000 R12: dffffc0000000000
R13: 1ffff92000000f3c R14: ffffc90000007a00 R15: 0000000000000246
 __mod_timer+0x98d/0xd20 kernel/time/timer.c:1065
 call_timer_fn+0xf6/0x210 kernel/time/timer.c:1421
 expire_timers kernel/time/timer.c:1466 [inline]
 __run_timers+0x71a/0x910 kernel/time/timer.c:1734
 run_timer_softirq+0x63/0xf0 kernel/time/timer.c:1747
 __do_softirq+0x392/0x7a3 kernel/softirq.c:558
 __irq_exit_rcu+0xec/0x170 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:1097
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x12/0x20
RIP: 0010:orc_ip arch/x86/kernel/unwind_orc.c:30 [inline]
RIP: 0010:__orc_find arch/x86/kernel/unwind_orc.c:52 [inline]
RIP: 0010:orc_find arch/x86/kernel/unwind_orc.c:173 [inline]
RIP: 0010:unwind_next_frame+0x511/0x1fc0 arch/x86/kernel/unwind_orc.c:443
Code: 89 c1 48 c1 f9 02 48 c1 e8 3f 48 01 c8 48 83 e0 fe 49 8d 1c 47 48 89 d8 48 c1 e8 03 48 b9 00 00 00 00 00 fc ff df 0f b6 04 08 <84> c0 75 27 48 63 03 48 01 d8 48 8d 4b 04 4c 39 e0 4c 0f 46 f9 48
RSP: 0018:ffffc90018527760 EFLAGS: 00000a06
RAX: 0000000000000000 RBX: ffffffff8e588740 RCX: dffffc0000000000
RDX: ffffffff8ee77b1e RSI: ffffffff8806e4f4 RDI: ffffffff813a83c9
RBP: ffffffff8e58873c R08: 0000000000000012 R09: ffffc90018527910
R10: fffff520030a4f10 R11: 0000000000000000 R12: ffffffff8806e4f3
R13: ffffffff8e588740 R14: ffffffff8e5886fc R15: ffffffff8e588740
 arch_stack_walk+0x112/0x140 arch/x86/kernel/stacktrace.c:25
 stack_trace_save+0x11b/0x1e0 kernel/stacktrace.c:122
 kasan_save_stack mm/kasan/common.c:38 [inline]
 kasan_set_track mm/kasan/common.c:46 [inline]
 set_alloc_info mm/kasan/common.c:434 [inline]
 __kasan_slab_alloc+0xb6/0xf0 mm/kasan/common.c:467
 kasan_slab_alloc include/linux/kasan.h:259 [inline]
 slab_post_alloc_hook mm/slab.h:519 [inline]
 slab_alloc_node mm/slub.c:3234 [inline]
 slab_alloc mm/slub.c:3242 [inline]
 kmem_cache_alloc+0x1c9/0x310 mm/slub.c:3247
 kmem_cache_zalloc include/linux/slab.h:714 [inline]
 __alloc_file+0x26/0x2f0 fs/file_table.c:101
 alloc_empty_file+0xa9/0x1b0 fs/file_table.c:150
 alloc_file+0x58/0x4f0 fs/file_table.c:192
 alloc_file_pseudo+0x272/0x300 fs/file_table.c:232
 sock_alloc_file+0xb4/0x240 net/socket.c:462
 sock_map_fd net/socket.c:486 [inline]
 __sys_socket+0x198/0x380 net/socket.c:1561
 __do_sys_socket net/socket.c:1566 [inline]
 __se_sys_socket net/socket.c:1564 [inline]
 __x64_sys_socket+0x76/0x80 net/socket.c:1564
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x44/0xd0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x7fab13a17ae9
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 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 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fab10f6c188 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 00007fab13b2b020 RCX: 00007fab13a17ae9
RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000010
RBP: 00007fab13a71f6d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffed722584f R14: 00007fab10f6c300 R15: 0000000000022000
 </TASK>
----------------
Code disassembly (best guess), 1 bytes skipped:
   0:	df e9                	fucomip %st(1),%st
   2:	ca fb ff             	lret   $0xfffb
   5:	ff 89 d9 80 e1 07    	decl   0x7e180d9(%rcx)
   b:	80 c1 03             	add    $0x3,%cl
   e:	38 c1                	cmp    %al,%cl
  10:	0f 8c e6 fb ff ff    	jl     0xfffffbfc
  16:	48 89 df             	mov    %rbx,%rdi
  19:	4d 89 c6             	mov    %r8,%r14
  1c:	e8 0f 90 68 00       	callq  0x689030
  21:	4d 89 f0             	mov    %r14,%r8
  24:	e9 d3 fb ff ff       	jmpq   0xfffffbfc
* 29:	48 8d 4c 24 60       	lea    0x60(%rsp),%rcx <-- trapping instruction
  2e:	80 e1 07             	and    $0x7,%cl
  31:	80 c1 03             	add    $0x3,%cl
  34:	38 c1                	cmp    %al,%cl
  36:	0f 8c f4 fb ff ff    	jl     0xfffffc30
  3c:	48                   	rex.W
  3d:	8d                   	.byte 0x8d
  3e:	7c                   	.byte 0x7c

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce-smack-root 2021/11/13 02:41 upstream 7246f4dcaccc 83f5c9b5 .config log report info INFO: rcu detected stall in mrp_rcv
* Struck through repros no longer work on HEAD.