syzbot


INFO: rcu detected stall in sys_socket

Status: auto-closed as invalid on 2022/08/20 07:08
Reported-by: syzbot+c7c41da2082042154d6a@syzkaller.appspotmail.com
First crash: 732d, last: 732d
Similar bugs (12)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in sys_socket (4) fs 1 1090d 1090d 0/26 auto-closed as invalid on 2021/07/27 17:27
linux-5.15 INFO: rcu detected stall in sys_socket 1 203d 203d 0/3 auto-obsoleted due to no activity on 2024/01/11 06:48
upstream INFO: rcu detected stall in sys_socket (5) net 2 928d 953d 0/26 auto-closed as invalid on 2022/01/06 01:08
upstream INFO: rcu detected stall in sys_socket kernel 11 1602d 1603d 0/26 closed as invalid on 2019/12/04 14:04
upstream INFO: rcu detected stall in sys_socket (10) net apparmor C done 8 1d15h 144d 0/26 upstream: reported C repro on 2023/11/30 15:24
upstream INFO: rcu detected stall in sys_socket (6) cgroups mm 2 587d 631d 0/26 auto-obsoleted due to no activity on 2022/12/12 22:48
upstream INFO: rcu detected stall in sys_socket (7) kernel 2 446d 479d 0/26 auto-obsoleted due to no activity on 2023/05/02 14:50
upstream INFO: rcu detected stall in sys_socket (9) kasan mm 2 264d 274d 0/26 closed as invalid on 2023/09/07 14:25
upstream INFO: rcu detected stall in sys_socket (2) kernel 3 1567d 1567d 0/26 closed as invalid on 2020/01/08 05:23
linux-5.15 INFO: rcu detected stall in sys_socket (2) origin:upstream C 2 13d 51d 0/3 upstream: reported C repro on 2024/03/02 17:55
upstream INFO: rcu detected stall in sys_socket (3) kernel 4 1567d 1567d 0/26 closed as invalid on 2020/01/09 08:13
android-5-15 BUG: soft lockup in sys_socket origin:lts C 9 2d16h 12d 0/2 upstream: reported C repro on 2024/04/10 16:23

Sample crash report:
netlink: 12 bytes leftover after parsing attributes in process `syz-executor.1'.
bridge1: port 1(ipvlan2) entered blocking state
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 	Tasks blocked on level-0 rcu_node (CPUs 0-1): P22178
rcu: 	1-...!: (1 GPs behind) idle=ff2/1/0x4000000000000002 softirq=37945/37950 fqs=0 
rcu: 	(detected by 0, t=216692 jiffies, g=37461, q=52)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 6160 Comm: systemd-timesyn Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:check_kcov_mode kernel/kcov.c:67 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x14/0x50 kernel/kcov.c:101
Code: 35 00 e9 23 fe ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 48 8b 34 24 65 48 8b 04 25 c0 df 01 00 65 8b 15 cc 59 9f 7e <81> e2 00 01 1f 00 75 2b 8b 90 60 13 00 00 83 fa 02 75 20 48 8b 88
RSP: 0018:ffff8880ba107cb8 EFLAGS: 00000006
RAX: ffff888096422340 RBX: 002f7f65b2800000 RCX: ffffffff8154b046
RDX: 0000000080010002 RSI: ffffffff8154b050 RDI: 0000000000000006
RBP: ffff8880ba107d80 R08: 00989a03ffc6ae6f R09: 002f7f65b2800000
R10: 0000000000000006 R11: ffffffff8c66505b R12: 7459e5d5c5eb061e
R13: 00004c4d0203db80 R14: 0000000062669f0a R15: 001dcd6500000000
FS:  00007fa63f6d48c0(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f0382e27074 CR3: 00000000b32e0000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 logarithmic_accumulation kernel/time/timekeeping.c:2034 [inline]
 timekeeping_advance+0x310/0x9b0 kernel/time/timekeeping.c:2097
 tick_do_update_jiffies64.part.0+0x188/0x290 kernel/time/tick-sched.c:101
 tick_do_update_jiffies64 kernel/time/tick-sched.c:67 [inline]
 tick_sched_do_timer kernel/time/tick-sched.c:139 [inline]
 tick_sched_timer+0x220/0x290 kernel/time/tick-sched.c:1271
 __run_hrtimer kernel/time/hrtimer.c:1465 [inline]
 __hrtimer_run_queues+0x3f6/0xe60 kernel/time/hrtimer.c:1527
 hrtimer_interrupt+0x326/0x9e0 kernel/time/hrtimer.c:1585
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1071 [inline]
 smp_apic_timer_interrupt+0x10c/0x550 arch/x86/kernel/apic/apic.c:1096
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
 </IRQ>
RIP: 0010:unwind_next_frame+0x43c/0x1400 arch/x86/kernel/unwind_orc.c:427
Code: 00 00 00 00 00 fc ff df 4c 89 c2 48 c1 ea 03 0f b6 04 02 4c 89 c2 83 e2 07 38 d0 7f 08 84 c0 0f 85 23 0c 00 00 41 0f b6 47 04 <a8> 0f 0f 85 aa 00 00 00 49 8d 7f 05 48 b8 00 00 00 00 00 fc ff df
RSP: 0018:ffff88809649f948 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000015 RBX: 1ffff11012c93f32 RCX: ffffffff866de4fe
RDX: 0000000000000000 RSI: ffffffff8bf53c92 RDI: ffffffff8b75eac0
RBP: 0000000000000001 R08: ffffffff8bf53cd8 R09: ffffffff8bf53c92
R10: ffff88809649fad7 R11: 0000000000074071 R12: ffff88809649fac0
R13: ffff88809649faad R14: ffff88809649fa78 R15: ffffffff8bf53cd4
 __save_stack_trace+0x9f/0x190 arch/x86/kernel/stacktrace.c:44
 save_stack mm/kasan/kasan.c:448 [inline]
 set_track mm/kasan/kasan.c:460 [inline]
 kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:553
 slab_post_alloc_hook mm/slab.h:445 [inline]
 slab_alloc mm/slab.c:3397 [inline]
 kmem_cache_alloc+0x110/0x370 mm/slab.c:3557
 sock_alloc_inode+0x19/0x250 net/socket.c:244
 alloc_inode+0x5d/0x180 fs/inode.c:211
 new_inode_pseudo+0x14/0xe0 fs/inode.c:911
 sock_alloc+0x3c/0x260 net/socket.c:567
 __sock_create+0xba/0x740 net/socket.c:1329
 sock_create net/socket.c:1416 [inline]
 __sys_socket+0xef/0x200 net/socket.c:1458
 __do_sys_socket net/socket.c:1467 [inline]
 __se_sys_socket net/socket.c:1465 [inline]
 __x64_sys_socket+0x6f/0xb0 net/socket.c:1465
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fa63eac9f17
Code: 73 01 c3 48 8b 0d 81 ef 2a 00 f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 b8 29 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 51 ef 2a 00 f7 d8 64 89 01 48
RSP: 002b:00007ffebc438c38 EFLAGS: 00000246 ORIG_RAX: 0000000000000029
RAX: ffffffffffffffda RBX: 00007fa63f3dbc05 RCX: 00007fa63eac9f17
RDX: 0000000000000000 RSI: 0000000000080002 RDI: 0000000000000001
RBP: 00007ffebc438d70 R08: 0000000000000000 R09: 00000000ffffffff
R10: 00007ffebc43af55 R11: 0000000000000246 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffebc438c70
syz-executor.3  R  running task    28648 22178   8136 0x00000000
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 preempt_schedule_irq+0xb0/0x140 kernel/sched/core.c:3744
 retint_kernel+0x1b/0x2d
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3c0 kernel/locking/lockdep.c:3911
Code: 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 b7 01 00 00 48 83 3d 09 2e a6 08 00 0f 84 2a 01 00 00 48 8b 7c 24 08 57 9d <0f> 1f 44 00 00 48 83 c4 18 5b 5d 41 5c 41 5d 41 5e 41 5f c3 65 8b
RSP: 0018:ffff88809b9d7a90 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff13e3051 RBX: ffff88804ee945c0 RCX: 5b1b0b0e508b94a2
RDX: dffffc0000000000 RSI: 000000003c0b0293 RDI: 0000000000000286
RBP: ffffffff89f85fa0 R08: 000000007a1a9409 R09: 0000000000000002
R10: ffff88804ee94e98 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000002 R15: 0000000000000000
 rcu_lock_acquire include/linux/rcupdate.h:242 [inline]
 rcu_read_lock include/linux/rcupdate.h:627 [inline]
 ext4_get_group_desc+0x11c/0x4e0 fs/ext4/balloc.c:284
 recently_deleted fs/ext4/ialloc.c:680 [inline]
 find_inode_bit+0x1a0/0x520 fs/ext4/ialloc.c:724
 __ext4_new_inode+0x160c/0x5a20 fs/ext4/ialloc.c:916
 ext4_symlink+0x3f5/0xc00 fs/ext4/namei.c:3176
 vfs_symlink+0x453/0x6c0 fs/namei.c:4129
 do_symlinkat+0x258/0x2c0 fs/namei.c:4156
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f135f3119f7
Code: Bad RIP value.
RSP: 002b:00007ffdf05297f8 EFLAGS: 00000206 ORIG_RAX: 000000000000010a
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f135f3119f7
RDX: 00007f135f36c12e RSI: 00000000ffffff9c RDI: 00007f135f36ae13
RBP: 0000000000000000 R08: 0000000000000000 R09: 00007ffdf0529270
R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000001
R13: 0000000000000000 R14: 0000000000000001 R15: 00007ffdf05298c0
syz-executor.3  R  running task    28648 22178   8136 0x00000000
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 preempt_schedule_irq+0xb0/0x140 kernel/sched/core.c:3744
 retint_kernel+0x1b/0x2d
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3c0 kernel/locking/lockdep.c:3911
Code: 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 b7 01 00 00 48 83 3d 09 2e a6 08 00 0f 84 2a 01 00 00 48 8b 7c 24 08 57 9d <0f> 1f 44 00 00 48 83 c4 18 5b 5d 41 5c 41 5d 41 5e 41 5f c3 65 8b
RSP: 0018:ffff88809b9d7a90 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff13e3051 RBX: ffff88804ee945c0 RCX: 5b1b0b0e508b94a2
RDX: dffffc0000000000 RSI: 000000003c0b0293 RDI: 0000000000000286
RBP: ffffffff89f85fa0 R08: 000000007a1a9409 R09: 0000000000000002
R10: ffff88804ee94e98 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000002 R15: 0000000000000000
 rcu_lock_acquire include/linux/rcupdate.h:242 [inline]
 rcu_read_lock include/linux/rcupdate.h:627 [inline]
 ext4_get_group_desc+0x11c/0x4e0 fs/ext4/balloc.c:284
 recently_deleted fs/ext4/ialloc.c:680 [inline]
 find_inode_bit+0x1a0/0x520 fs/ext4/ialloc.c:724
 __ext4_new_inode+0x160c/0x5a20 fs/ext4/ialloc.c:916
 ext4_symlink+0x3f5/0xc00 fs/ext4/namei.c:3176
 vfs_symlink+0x453/0x6c0 fs/namei.c:4129
 do_symlinkat+0x258/0x2c0 fs/namei.c:4156
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f135f3119f7
Code: Bad RIP value.
RSP: 002b:00007ffdf05297f8 EFLAGS: 00000206 ORIG_RAX: 000000000000010a
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f135f3119f7
RDX: 00007f135f36c12e RSI: 00000000ffffff9c RDI: 00007f135f36ae13
RBP: 0000000000000000 R08: 0000000000000000 R09: 00007ffdf0529270
R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000001
R13: 0000000000000000 R14: 0000000000000001 R15: 00007ffdf05298c0
rcu: rcu_preempt kthread starved for 216692 jiffies! g37461 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=0
rcu: RCU grace-period kthread stack dump:
rcu_preempt     I29208    10      2 0x80000000
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_timeout+0x4cf/0xfe0 kernel/time/timer.c:1818
 rcu_gp_kthread+0xdad/0x21c0 kernel/rcu/tree.c:2202
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
bridge1: port 1(ipvlan2) entered disabled state
systemd[1]: Started Cleanup of Temporary Directories.
systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT
systemd[1]: systemd-journald.service: Unit entered failed state.
systemd[1]: systemd-journald.service: Failed with result 'watchdog'.
systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart.
systemd[1]: Stopped Flush Journal to Persistent Storage.
systemd[1]: Stopping Flush Journal to Persistent Storage...
systemd[1]: Stopped Journal Service.
systemd[1]: Starting Journal Service...
----------------
Code disassembly (best guess), 2 bytes skipped:
   0:	e9 23 fe ff ff       	jmpq   0xfffffe28
   5:	90                   	nop
   6:	90                   	nop
   7:	90                   	nop
   8:	90                   	nop
   9:	90                   	nop
   a:	90                   	nop
   b:	90                   	nop
   c:	90                   	nop
   d:	90                   	nop
   e:	90                   	nop
   f:	90                   	nop
  10:	90                   	nop
  11:	90                   	nop
  12:	90                   	nop
  13:	90                   	nop
  14:	48 8b 34 24          	mov    (%rsp),%rsi
  18:	65 48 8b 04 25 c0 df 	mov    %gs:0x1dfc0,%rax
  1f:	01 00
  21:	65 8b 15 cc 59 9f 7e 	mov    %gs:0x7e9f59cc(%rip),%edx        # 0x7e9f59f4
* 28:	81 e2 00 01 1f 00    	and    $0x1f0100,%edx <-- trapping instruction
  2e:	75 2b                	jne    0x5b
  30:	8b 90 60 13 00 00    	mov    0x1360(%rax),%edx
  36:	83 fa 02             	cmp    $0x2,%edx
  39:	75 20                	jne    0x5b
  3b:	48                   	rex.W
  3c:	8b                   	.byte 0x8b
  3d:	88                   	.byte 0x88

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2022/04/22 07:07 linux-4.19.y 3f8a27f9e27b 2738b391 .config console log report info ci2-linux-4-19 INFO: rcu detected stall in sys_socket
* Struck through repros no longer work on HEAD.