syzbot


INFO: rcu detected stall in security_file_open (5)

Status: closed as invalid on 2022/02/08 09:50
Subsystems: tomoyo
[Documentation on labels]
First crash: 1031d, last: 921d
Similar bugs (6)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in security_file_open (3) afs C done done 2 1342d 1341d 15/26 fixed on 2020/11/16 12:12
upstream INFO: rcu detected stall in security_file_open kernel 5 1642d 1643d 0/26 closed as invalid on 2019/12/04 14:04
upstream INFO: rcu detected stall in security_file_open (2) kernel 2 1608d 1608d 0/26 closed as invalid on 2020/01/08 05:23
linux-6.1 INFO: rcu detected stall in security_file_open 1 257d 257d 0/3 auto-obsoleted due to no activity on 2023/12/28 22:15
upstream INFO: rcu detected stall in security_file_open (4) tomoyo 1 1140d 1140d 0/26 auto-closed as invalid on 2021/06/18 10:54
upstream INFO: rcu detected stall in security_file_open (6) tomoyo C error 19 34d 341d 0/26 closed as dup on 2023/06/27 10:07

Sample crash report:
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 	0-...!: (0 ticks this GP) idle=c8f/1/0x4000000000000000 softirq=50847/50847 fqs=0 
	(detected by 1, t=10502 jiffies, g=82513, q=30)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 25935 Comm: syz-executor.4 Not tainted 5.15.0-rc3-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:lock_is_held_type+0x125/0x190 kernel/locking/lockdep.c:5670
Code: 48 c7 c7 e0 01 4f 8a e8 d9 10 00 00 b8 ff ff ff ff 65 0f c1 05 2c ef 08 76 83 f8 01 75 40 48 c7 04 24 00 00 00 00 9c 8f 04 24 <f7> 04 24 00 02 00 00 75 46 41 f7 c4 00 02 00 00 74 01 fb 65 48 8b
RSP: 0018:ffffc90000007998 EFLAGS: 00000046
RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000010003
RDX: 0000000000000000 RSI: 0000000000010003 RDI: 0000000000000000
RBP: 0000000000000002 R08: dffffc0000000000 R09: ffffed10173864a2
R10: ffffed10173864a2 R11: 0000000000000000 R12: 0000000000000046
R13: ffff888059973900 R14: 00000000ffffffff R15: ffffffff8c91c0e0
FS:  00007f465f20a700(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f465f207ff8 CR3: 000000006f202000 CR4: 00000000003506f0
Call Trace:
 <IRQ>
 lock_is_held include/linux/lockdep.h:283 [inline]
 rcu_read_lock_sched_held+0x89/0x130 kernel/rcu/update.c:125
 trace_lock_acquire+0x59/0x190 include/trace/events/lock.h:13
 lock_acquire+0xa5/0x4d0 kernel/locking/lockdep.c:5596
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
 _raw_spin_lock_irqsave+0xd1/0x120 kernel/locking/spinlock.c:162
 debug_object_deactivate+0x61/0x400 lib/debugobjects.c:735
 debug_hrtimer_deactivate kernel/time/hrtimer.c:425 [inline]
 debug_deactivate kernel/time/hrtimer.c:481 [inline]
 __run_hrtimer kernel/time/hrtimer.c:1653 [inline]
 __hrtimer_run_queues+0x2f9/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+0x8c/0xb0 arch/x86/kernel/apic/apic.c:1097
 </IRQ>
 asm_sysvec_apic_timer_interrupt+0x12/0x20
RIP: 0010:tomoyo_check_acl+0xb1/0x430 security/tomoyo/domain.c:173
Code: 85 05 03 00 00 48 8b 1c 24 4c 8b 23 49 39 dc 0f 84 14 02 00 00 0f 1f 40 00 49 8d 6c 24 18 48 89 e8 48 c1 e8 03 42 0f b6 04 28 <84> c0 0f 85 1d 01 00 00 0f b6 6d 00 31 ff 89 ee e8 6a 2b aa fd 85
RSP: 0018:ffffc900161a74f8 EFLAGS: 00000a02
RAX: 0000000000000000 RBX: ffff888019969090 RCX: 0000000000040000
RDX: ffffc90011bfa000 RSI: 0000000000000eaf RDI: 0000000000000eb0
RBP: ffff88801af55098 R08: ffffffff83d89ea5 R09: ffffffff83d9e692
R10: 0000000000000002 R11: ffff888059973900 R12: ffff88801af55080
R13: dffffc0000000000 R14: ffff888019969080 R15: 0000000000000000
 tomoyo_path_permission+0x1af/0x370 security/tomoyo/file.c:586
 tomoyo_check_open_permission+0x2e9/0x490 security/tomoyo/file.c:777
 security_file_open+0x50/0x570 security/security.c:1634
 do_dentry_open+0x36c/0x1020 fs/open.c:809
 do_open fs/namei.c:3428 [inline]
 path_openat+0x28b4/0x3670 fs/namei.c:3561
 do_filp_open+0x277/0x4f0 fs/namei.c:3588
 do_sys_openat2+0x13b/0x500 fs/open.c:1200
 do_sys_open fs/open.c:1216 [inline]
 __do_sys_openat fs/open.c:1232 [inline]
 __se_sys_openat fs/open.c:1227 [inline]
 __x64_sys_openat+0x243/0x290 fs/open.c:1227
 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:0x7f4661c467f4
Code: 84 00 00 00 00 00 44 89 54 24 0c e8 96 f9 ff ff 44 8b 54 24 0c 44 89 e2 48 89 ee 41 89 c0 bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 34 44 89 c7 89 44 24 0c e8 c8 f9 ff ff 8b 44
RSP: 002b:00007f465f208040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 00007f4661d97f60 RCX: 00007f4661c467f4
RDX: 0000000000000002 RSI: 00007f4661ceca91 RDI: 00000000ffffff9c
RBP: 00007f4661ceca91 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002
R13: 0000000000000000 R14: 0000000020000000 R15: 0000000000022000
rcu: rcu_preempt kthread timer wakeup didn't happen for 10501 jiffies! g82513 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x402
rcu: 	Possible timer handling issue on cpu=0 timer-softirq=28921
rcu: rcu_preempt kthread starved for 10502 jiffies! g82513 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:26768 pid:   14 ppid:     2 flags:0x00004000
Call Trace:
 context_switch kernel/sched/core.c:4940 [inline]
 __schedule+0xc8d/0x1270 kernel/sched/core.c:6287
 schedule+0x14b/0x210 kernel/sched/core.c:6366
 schedule_timeout+0x1b9/0x300 kernel/time/timer.c:1881
 rcu_gp_fqs_loop+0x1fd/0x770 kernel/rcu/tree.c:1957
 rcu_gp_kthread+0xa5/0x350 kernel/rcu/tree.c:2130
 kthread+0x453/0x480 kernel/kthread.c:319
 ret_from_fork+0x1f/0x30
rcu: Stack dump where RCU GP kthread last ran:
Sending NMI from CPU 1 to CPUs 0:
----------------
Code disassembly (best guess):
   0:	48 c7 c7 e0 01 4f 8a 	mov    $0xffffffff8a4f01e0,%rdi
   7:	e8 d9 10 00 00       	callq  0x10e5
   c:	b8 ff ff ff ff       	mov    $0xffffffff,%eax
  11:	65 0f c1 05 2c ef 08 	xadd   %eax,%gs:0x7608ef2c(%rip)        # 0x7608ef45
  18:	76
  19:	83 f8 01             	cmp    $0x1,%eax
  1c:	75 40                	jne    0x5e
  1e:	48 c7 04 24 00 00 00 	movq   $0x0,(%rsp)
  25:	00
  26:	9c                   	pushfq
  27:	8f 04 24             	popq   (%rsp)
* 2a:	f7 04 24 00 02 00 00 	testl  $0x200,(%rsp) <-- trapping instruction
  31:	75 46                	jne    0x79
  33:	41 f7 c4 00 02 00 00 	test   $0x200,%r12d
  3a:	74 01                	je     0x3d
  3c:	fb                   	sti
  3d:	65                   	gs
  3e:	48                   	rex.W
  3f:	8b                   	.byte 0x8b

Crashes (4):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2021/10/02 05:12 upstream 53d5fc89d66a db0f5787 .config console log report info ci-upstream-kasan-gce-smack-root INFO: rcu detected stall in security_file_open
2021/09/05 01:37 upstream f1583cb1be35 d236a457 .config console log report info ci-upstream-kasan-gce-smack-root INFO: rcu detected stall in security_file_open
2021/08/06 14:33 upstream 902e7f373fff f9e341e3 .config console log report info ci-upstream-kasan-gce-smack-root INFO: rcu detected stall in security_file_open
2021/11/24 08:21 bpf-next fa721d4f0b91 545ab074 .config console log report info ci-upstream-bpf-next-kasan-gce INFO: rcu detected stall in security_file_open
* Struck through repros no longer work on HEAD.