syzbot


INFO: rcu detected stall in newlstat (3)

Status: auto-closed as invalid on 2022/10/05 01:09
Subsystems: tomoyo
[Documentation on labels]
First crash: 659d, last: 659d
Similar bugs (4)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in newlstat (5) tomoyo 1 322d 322d 0/26 auto-obsoleted due to no activity on 2023/09/06 16:16
upstream INFO: rcu detected stall in newlstat tomoyo 7 878d 941d 0/26 closed as invalid on 2022/02/08 10:00
upstream INFO: rcu detected stall in newlstat (4) tomoyo 1 421d 421d 0/26 auto-obsoleted due to no activity on 2023/05/30 04:31
upstream INFO: rcu detected stall in newlstat (2) fs 1 786d 786d 0/26 auto-closed as invalid on 2022/04/30 11:07

Sample crash report:
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 	0-...!: (0 ticks this GP) idle=63b/1/0x4000000000000000 softirq=213175/213175 fqs=0 
	(detected by 1, t=10502 jiffies, g=310481, q=230 ncpus=2)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 3645 Comm: syz-executor.2 Not tainted 5.19.0-rc5-syzkaller-00105-g9f09069cde34 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022
RIP: 0010:__lock_release kernel/locking/lockdep.c:5351 [inline]
RIP: 0010:lock_release+0x265/0x780 kernel/locking/lockdep.c:5685
Code: 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 0f b6 14 02 48 89 f8 83 e0 07 83 c0 01 38 d0 7c 08 84 d2 0f 85 82 04 00 00 <66> f7 43 22 f0 ff 74 36 48 8d 7b 20 48 b8 00 00 00 00 00 fc ff df
RSP: 0018:ffffc90000007d08 EFLAGS: 00000046
RAX: 0000000000000003 RBX: ffff88802af96bc8 RCX: ffffc90000007d58
RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff88802af96bea
RBP: 1ffff92000000fa3 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000001 R12: ffffffff8bd83b60
R13: 0000000000000002 R14: ffff88802af96b98 R15: ffff88802af96140
FS:  0000555556432400(0000) GS:ffff8880b9a00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000055555643b848 CR3: 000000002c845000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 rcu_lock_release include/linux/rcupdate.h:274 [inline]
 rcu_read_unlock include/linux/rcupdate.h:728 [inline]
 advance_sched+0x507/0x9a0 net/sched/sch_taprio.c:770
 __run_hrtimer kernel/time/hrtimer.c:1685 [inline]
 __hrtimer_run_queues+0x609/0xe50 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x31c/0x790 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0x146/0x530 arch/x86/kernel/apic/apic.c:1112
 sysvec_apic_timer_interrupt+0x8e/0xc0 arch/x86/kernel/apic/apic.c:1106
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1b/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:kfree+0x145/0x310 mm/slab.c:3798
Code: 4c 89 f2 4c 89 ef e8 6a f8 ff ff 4d 85 e4 0f 85 c7 00 00 00 9c 58 f6 c4 02 0f 85 7f 01 00 00 4d 85 e4 74 01 fb 48 8b 44 24 08 <65> 48 2b 04 25 28 00 00 00 0f 85 ab 01 00 00 48 83 c4 10 5b 5d 41
RSP: 0018:ffffc900031379e0 EFLAGS: 00000206
RAX: 0293fd0bada25500 RBX: ffffea0000e60180 RCX: 1ffffffff1b75879
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: ffff888039806000 R08: 0000000000000001 R09: 0000000000000001
R10: 0000000080000000 R11: 000000000008a07a R12: 0000000000000200
R13: ffff888011840900 R14: ffffffff83cab9d1 R15: 0000000000001000
 tomoyo_realpath_from_path+0x191/0x620 security/tomoyo/realpath.c:291
 tomoyo_get_realpath security/tomoyo/file.c:151 [inline]
 tomoyo_path_perm+0x21b/0x400 security/tomoyo/file.c:822
 security_inode_getattr+0xcf/0x140 security/security.c:1344
 vfs_getattr fs/stat.c:157 [inline]
 vfs_statx+0x16a/0x390 fs/stat.c:232
 vfs_fstatat+0x8c/0xb0 fs/stat.c:255
 vfs_lstat include/linux/fs.h:3144 [inline]
 __do_sys_newlstat+0x8b/0x110 fs/stat.c:411
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x46/0xb0
RIP: 0033:0x7f66786881a6
Code: ff ff ff 64 c7 00 16 00 00 00 b8 ff ff ff ff c3 0f 1f 40 00 41 89 f8 48 89 f7 48 89 d6 41 83 f8 01 77 29 b8 06 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 02 c3 90 48 c7 c2 b8 ff ff ff f7 d8 64 89 02
RSP: 002b:00007ffd15eef8f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000006
RAX: ffffffffffffffda RBX: 00000000000028ef RCX: 00007f66786881a6
RDX: 00007ffd15eef930 RSI: 00007ffd15eef930 RDI: 00007ffd15eef9c0
RBP: 00007ffd15eef9c0 R08: 0000000000000001 R09: 00007ffd15eef790
R10: 0000555556433853 R11: 0000000000000246 R12: 00007f66786e21c8
R13: 00007ffd15ef0a80 R14: 0000555556433810 R15: 00007ffd15ef0ac0
 </TASK>
rcu: rcu_preempt kthread timer wakeup didn't happen for 10501 jiffies! g310481 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402
rcu: 	Possible timer handling issue on cpu=0 timer-softirq=156101
rcu: rcu_preempt kthread starved for 10502 jiffies! g310481 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402 ->cpu=0
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:I stack:28824 pid:   16 ppid:     2 flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5146 [inline]
 __schedule+0xa00/0x4b50 kernel/sched/core.c:6458
 schedule+0xd2/0x1f0 kernel/sched/core.c:6530
 schedule_timeout+0x14a/0x2a0 kernel/time/timer.c:1935
 rcu_gp_fqs_loop+0x186/0x810 kernel/rcu/tree.c:1999
 rcu_gp_kthread+0x1de/0x320 kernel/rcu/tree.c:2187
 kthread+0x2e9/0x3a0 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:302
 </TASK>
rcu: Stack dump where RCU GP kthread last ran:
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 3645 Comm: syz-executor.2 Not tainted 5.19.0-rc5-syzkaller-00105-g9f09069cde34 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022
RIP: 0010:do_raw_spin_lock+0xc/0x2a0 kernel/locking/spinlock_debug.c:113
Code: e8 a9 c1 67 00 48 8b 74 24 08 4c 8b 0c 24 eb b3 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 48 b8 00 00 00 00 00 fc ff df 41 56 <41> 55 41 54 55 48 89 fd 48 83 c7 04 53 48 83 ec 68 48 c7 44 24 08
RSP: 0018:ffffc90000007cf8 EFLAGS: 00000082
RAX: dffffc0000000000 RBX: 00000000000770a0 RCX: ffffffff815d30ee
RDX: 1ffff110055f2d73 RSI: 0000000000010002 RDI: ffffffff9115c2a8
RBP: ffffffff9115c2a8 R08: 0000000000000000 R09: ffffffff9067c907
R10: fffffbfff20cf920 R11: 0000000000000001 R12: 0000000000000006
R13: dffffc0000000000 R14: 1ffff92000000fa7 R15: ffffffff89cec240
FS:  0000555556432400(0000) GS:ffff8880b9a00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000055555643b848 CR3: 000000002c845000 CR4: 00000000003506f0
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+0x41/0x50 kernel/locking/spinlock.c:162
 debug_object_activate+0x12e/0x3e0 lib/debugobjects.c:658
 debug_hrtimer_activate kernel/time/hrtimer.c:420 [inline]
 debug_activate kernel/time/hrtimer.c:475 [inline]
 enqueue_hrtimer+0x27/0x3e0 kernel/time/hrtimer.c:1084
 __run_hrtimer kernel/time/hrtimer.c:1702 [inline]
 __hrtimer_run_queues+0xb02/0xe50 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x31c/0x790 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0x146/0x530 arch/x86/kernel/apic/apic.c:1112
 sysvec_apic_timer_interrupt+0x8e/0xc0 arch/x86/kernel/apic/apic.c:1106
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1b/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:kfree+0x145/0x310 mm/slab.c:3798
Code: 4c 89 f2 4c 89 ef e8 6a f8 ff ff 4d 85 e4 0f 85 c7 00 00 00 9c 58 f6 c4 02 0f 85 7f 01 00 00 4d 85 e4 74 01 fb 48 8b 44 24 08 <65> 48 2b 04 25 28 00 00 00 0f 85 ab 01 00 00 48 83 c4 10 5b 5d 41
RSP: 0018:ffffc900031379e0 EFLAGS: 00000206
RAX: 0293fd0bada25500 RBX: ffffea0000e60180 RCX: 1ffffffff1b75879
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: ffff888039806000 R08: 0000000000000001 R09: 0000000000000001
R10: 0000000080000000 R11: 000000000008a07a R12: 0000000000000200
R13: ffff888011840900 R14: ffffffff83cab9d1 R15: 0000000000001000
 tomoyo_realpath_from_path+0x191/0x620 security/tomoyo/realpath.c:291
 tomoyo_get_realpath security/tomoyo/file.c:151 [inline]
 tomoyo_path_perm+0x21b/0x400 security/tomoyo/file.c:822
 security_inode_getattr+0xcf/0x140 security/security.c:1344
 vfs_getattr fs/stat.c:157 [inline]
 vfs_statx+0x16a/0x390 fs/stat.c:232
 vfs_fstatat+0x8c/0xb0 fs/stat.c:255
 vfs_lstat include/linux/fs.h:3144 [inline]
 __do_sys_newlstat+0x8b/0x110 fs/stat.c:411
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x46/0xb0
RIP: 0033:0x7f66786881a6
Code: ff ff ff 64 c7 00 16 00 00 00 b8 ff ff ff ff c3 0f 1f 40 00 41 89 f8 48 89 f7 48 89 d6 41 83 f8 01 77 29 b8 06 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 02 c3 90 48 c7 c2 b8 ff ff ff f7 d8 64 89 02
RSP: 002b:00007ffd15eef8f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000006
RAX: ffffffffffffffda RBX: 00000000000028ef RCX: 00007f66786881a6
RDX: 00007ffd15eef930 RSI: 00007ffd15eef930 RDI: 00007ffd15eef9c0
RBP: 00007ffd15eef9c0 R08: 0000000000000001 R09: 00007ffd15eef790
R10: 0000555556433853 R11: 0000000000000246 R12: 00007f66786e21c8
R13: 00007ffd15ef0a80 R14: 0000555556433810 R15: 00007ffd15ef0ac0
 </TASK>
----------------
Code disassembly (best guess):
   0:	48 b8 00 00 00 00 00 	movabs $0xdffffc0000000000,%rax
   7:	fc ff df
   a:	48 89 fa             	mov    %rdi,%rdx
   d:	48 c1 ea 03          	shr    $0x3,%rdx
  11:	0f b6 14 02          	movzbl (%rdx,%rax,1),%edx
  15:	48 89 f8             	mov    %rdi,%rax
  18:	83 e0 07             	and    $0x7,%eax
  1b:	83 c0 01             	add    $0x1,%eax
  1e:	38 d0                	cmp    %dl,%al
  20:	7c 08                	jl     0x2a
  22:	84 d2                	test   %dl,%dl
  24:	0f 85 82 04 00 00    	jne    0x4ac
* 2a:	66 f7 43 22 f0 ff    	testw  $0xfff0,0x22(%rbx) <-- trapping instruction
  30:	74 36                	je     0x68
  32:	48 8d 7b 20          	lea    0x20(%rbx),%rdi
  36:	48 b8 00 00 00 00 00 	movabs $0xdffffc0000000000,%rax
  3d:	fc ff df

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2022/07/07 01:08 upstream 9f09069cde34 bff65f44 .config console log report info ci-upstream-kasan-gce-selinux-root INFO: rcu detected stall in newlstat
* Struck through repros no longer work on HEAD.