syzbot


INFO: rcu detected stall in wait4 (4)

Status: upstream: reported C repro on 2023/07/18 06:12
Subsystems: kernel
[Documentation on labels]
Reported-by: syzbot+8ee0140c3f5eab8a8d4f@syzkaller.appspotmail.com
First crash: 333d, last: 24d
Cause bisection: failed (error log, bisect log)
  
Fix bisection: fixed by (bisect log) :
commit 8542f1712074f070ae90b64e6082d10d8e912e32
Author: Amir Goldstein <amir73il@gmail.com>
Date: Mon Oct 2 10:04:45 2023 +0000

  ovl: fix file reference leak when submitting aio

  
Discussions (1)
Title Replies (including bot) Last reply
[syzbot] [kernel?] INFO: rcu detected stall in wait4 (4) 0 (2) 2023/10/13 03:44
Similar bugs (3)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in wait4 (3) kernel 2 438d 443d 0/26 auto-obsoleted due to no activity on 2023/05/14 12:43
upstream INFO: rcu detected stall in wait4 kernel 2 1055d 1112d 0/26 auto-closed as invalid on 2021/09/04 10:15
upstream INFO: rcu detected stall in wait4 (2) kernel 2 640d 722d 0/26 auto-obsoleted due to no activity on 2022/10/24 16:24
Last patch testing requests (5)
Created Duration User Patch Repo Result
2024/04/20 10:37 16m retest repro upstream report log
2024/01/10 22:44 20m retest repro upstream report log
2023/10/13 11:11 21m hdanton@sina.com patch https://git.kernel.org/pub/scm/linux/kernel/git/netdev/net.git 9d23aac8a85f OK log
2023/09/24 16:21 28m retest repro upstream OK log
2023/09/24 12:37 22m retest repro net OK log

Sample crash report:
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 	1-...!: (1 GPs behind) idle=43ec/1/0x4000000000000000 softirq=6856/6858 fqs=3
rcu: 	(detected by 0, t=10505 jiffies, g=8145, q=149 ncpus=2)
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 5082 Comm: syz-executor243 Not tainted 6.7.0-rc7-syzkaller-00003-gfbafc3e621c3 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/17/2023
RIP: 0010:check_preemption_disabled+0x27/0xe0 lib/smp_processor_id.c:56
Code: 0f 1f 00 41 54 55 53 48 83 ec 08 65 8b 1d 2d cc 83 75 65 8b 05 22 cc 83 75 a9 ff ff ff 7f 74 0b 48 83 c4 08 89 d8 5b 5d 41 5c <c3> 9c 58 f6 c4 02 74 ee 65 48 8b 04 25 c0 bc 03 00 f6 40 2f 04 48
RSP: 0018:ffffc900001f0c18 EFLAGS: 00000096
RAX: 0000000000000001 RBX: 1ffff9200003e189 RCX: ffffffff81680477
RDX: fffffbfff1e3276b RSI: ffffffff8b2efba0 RDI: ffffffff8b2efbe0
RBP: 0000000000000001 R08: 0000000000000000 R09: fffffbfff1e3276a
R10: ffffffff8f193b57 R11: 0000000000000001 R12: 0000000000000001
R13: 0000000000000000 R14: ffffffff92a6cfc0 R15: 0000000000000000
FS:  0000555556dc5380(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007ffff79a3c88 CR3: 000000007c133000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 rcu_dynticks_curr_cpu_in_eqs include/linux/context_tracking.h:122 [inline]
 rcu_is_watching+0x12/0xb0 kernel/rcu/tree.c:700
 trace_lock_acquire include/trace/events/lock.h:24 [inline]
 lock_acquire+0x464/0x520 kernel/locking/lockdep.c:5725
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
 _raw_spin_lock_irqsave+0x3a/0x50 kernel/locking/spinlock.c:162
 debug_object_deactivate lib/debugobjects.c:783 [inline]
 debug_object_deactivate+0x113/0x320 lib/debugobjects.c:771
 debug_hrtimer_deactivate kernel/time/hrtimer.c:427 [inline]
 debug_deactivate kernel/time/hrtimer.c:483 [inline]
 __run_hrtimer kernel/time/hrtimer.c:1656 [inline]
 __hrtimer_run_queues+0x470/0xc20 kernel/time/hrtimer.c:1752
 hrtimer_interrupt+0x31b/0x800 kernel/time/hrtimer.c:1814
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1065 [inline]
 __sysvec_apic_timer_interrupt+0x105/0x400 arch/x86/kernel/apic/apic.c:1082
 sysvec_apic_timer_interrupt+0x90/0xb0 arch/x86/kernel/apic/apic.c:1076
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:__raw_write_unlock_irq include/linux/rwlock_api_smp.h:274 [inline]
RIP: 0010:_raw_write_unlock_irq+0x29/0x50 kernel/locking/spinlock.c:358
Code: 90 f3 0f 1e fa 53 48 8b 74 24 08 48 89 fb 48 83 c7 18 e8 4a 87 e4 f6 48 89 df e8 82 08 e5 f6 e8 5d ba 0a f7 fb bf 01 00 00 00 <e8> f2 b7 d6 f6 65 8b 05 23 4f 80 75 85 c0 74 02 5b c3 e8 f0 12 7d
RSP: 0018:ffffc90003a6f9f0 EFLAGS: 00000202
RAX: 00000000000415a1 RBX: ffffffff8cc0a080 RCX: 1ffffffff1e32119
RDX: 0000000000000000 RSI: ffffffff8accaec0 RDI: 0000000000000001
RBP: ffff88802082bb80 R08: 0000000000000001 R09: 0000000000000001
R10: ffffffff8f193b57 R11: 0000000000000001 R12: 1ffff9200074df4b
R13: ffff8880269faa58 R14: ffff88823bdc6740 R15: ffff8880269fa890
 release_task+0xe50/0x1b10 kernel/exit.c:278
 wait_task_zombie kernel/exit.c:1184 [inline]
 wait_consider_task+0x17c4/0x40a0 kernel/exit.c:1411
 do_wait_thread kernel/exit.c:1474 [inline]
 __do_wait+0x1e3/0x890 kernel/exit.c:1592
 do_wait+0x212/0x530 kernel/exit.c:1626
 kernel_wait4+0x16d/0x280 kernel/exit.c:1785
 __do_sys_wait4+0x15b/0x170 kernel/exit.c:1813
 do_syscall_x64 arch/x86/entry/common.c:52 [inline]
 do_syscall_64+0x40/0x110 arch/x86/entry/common.c:83
 entry_SYSCALL_64_after_hwframe+0x63/0x6b
RIP: 0033:0x7f8c603ac893
Code: fe ff e9 41 ff ff ff 31 c9 e9 09 00 00 00 66 0f 1f 84 00 00 00 00 00 80 3d 11 f8 07 00 00 49 89 ca 74 14 b8 3d 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5d c3 0f 1f 40 00 48 83 ec 28 89 54 24 14 48
RSP: 002b:00007ffff79a3d88 EFLAGS: 00000202 ORIG_RAX: 000000000000003d
RAX: ffffffffffffffda RBX: 0000000000000148 RCX: 00007f8c603ac893
RDX: 0000000040000001 RSI: 00007ffff79a3dac RDI: 00000000ffffffff
RBP: 00000000000f4240 R08: 000000000000007e R09: 0000000000000001
R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffff79a3de0
R13: 000000000001ef52 R14: 00007ffff79a3dac R15: 0000000000000003
 </TASK>
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 2.371 msecs
rcu: rcu_preempt kthread starved for 10490 jiffies! g8145 f0x0 RCU_GP_WAIT_FQS(5) ->state=0x0 ->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:R  running task     stack:27904 pid:17    tgid:17    ppid:2      flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5376 [inline]
 __schedule+0xedb/0x5af0 kernel/sched/core.c:6688
 __schedule_loop kernel/sched/core.c:6763 [inline]
 schedule+0xe9/0x270 kernel/sched/core.c:6778
 schedule_timeout+0x137/0x290 kernel/time/timer.c:2167
 rcu_gp_fqs_loop+0x1ec/0xb10 kernel/rcu/tree.c:1631
 rcu_gp_kthread+0x24b/0x380 kernel/rcu/tree.c:1830
 kthread+0x2c6/0x3a0 kernel/kthread.c:388
 ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:242
 </TASK>
rcu: Stack dump where RCU GP kthread last ran:
CPU: 0 PID: 2812 Comm: kworker/u4:7 Not tainted 6.7.0-rc7-syzkaller-00003-gfbafc3e621c3 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/17/2023
Workqueue: events_unbound toggle_allocation_gate
RIP: 0010:__sanitizer_cov_trace_pc+0x59/0x60 kernel/kcov.c:225
Code: 82 d8 15 00 00 83 f8 02 75 20 48 8b 8a e0 15 00 00 8b 92 dc 15 00 00 48 8b 01 48 83 c0 01 48 39 d0 73 07 48 89 01 48 89 34 c1 <c3> 66 0f 1f 44 00 00 f3 0f 1e fa 41 57 41 56 49 89 d6 41 55 41 54
RSP: 0018:ffffc9000a6a7928 EFLAGS: 00000293
RAX: 0000000000000000 RBX: ffff8880b9941a60 RCX: ffffffff817bbab8
RDX: ffff888026bd1dc0 RSI: ffffffff817bba92 RDI: 0000000000000005
RBP: 0000000000000003 R08: 0000000000000005 R09: 0000000000000000
R10: 0000000000000001 R11: 0000000000000006 R12: ffffed101732834d
R13: 0000000000000001 R14: ffff8880b9941a68 R15: ffff8880b983d8c0
FS:  0000000000000000(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f8c604302d0 CR3: 000000000cd77000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 </IRQ>
 <TASK>
 rep_nop arch/x86/include/asm/vdso/processor.h:13 [inline]
 cpu_relax arch/x86/include/asm/vdso/processor.h:18 [inline]
 csd_lock_wait kernel/smp.c:311 [inline]
 smp_call_function_many_cond+0x4e2/0x1550 kernel/smp.c:855
 on_each_cpu_cond_mask+0x40/0x90 kernel/smp.c:1023
 on_each_cpu include/linux/smp.h:71 [inline]
 text_poke_sync arch/x86/kernel/alternative.c:2016 [inline]
 text_poke_bp_batch+0x22b/0x750 arch/x86/kernel/alternative.c:2226
 text_poke_flush arch/x86/kernel/alternative.c:2417 [inline]
 text_poke_flush arch/x86/kernel/alternative.c:2414 [inline]
 text_poke_finish+0x30/0x40 arch/x86/kernel/alternative.c:2424
 arch_jump_label_transform_apply+0x1c/0x30 arch/x86/kernel/jump_label.c:146
 jump_label_update+0x1d7/0x400 kernel/jump_label.c:829
 static_key_enable_cpuslocked+0x1b7/0x270 kernel/jump_label.c:205
 static_key_enable+0x1a/0x20 kernel/jump_label.c:218
 toggle_allocation_gate mm/kfence/core.c:830 [inline]
 toggle_allocation_gate+0xf4/0x250 mm/kfence/core.c:822
 process_one_work+0x886/0x15d0 kernel/workqueue.c:2627
 process_scheduled_works kernel/workqueue.c:2700 [inline]
 worker_thread+0x8b9/0x1290 kernel/workqueue.c:2781
 kthread+0x2c6/0x3a0 kernel/kthread.c:388
 ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:242
 </TASK>

Crashes (34):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2023/12/27 18:28 upstream fbafc3e621c3 fb427a07 .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: rcu detected stall in wait4
2023/07/31 22:49 upstream 5d0c230f1de8 2a0d0f29 .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce-root INFO: rcu detected stall in wait4
2023/07/14 06:06 net 9d23aac8a85f 55eda22f .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-net-this-kasan-gce INFO: rcu detected stall in wait4
2024/04/03 08:44 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 7925100d .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/04/03 01:21 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae eb2966c4 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/04/01 18:55 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/04/01 11:46 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/04/01 07:14 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/03/31 09:39 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/03/30 09:33 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/03/30 09:27 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a788e53c05ae 6baf5069 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/03/14 05:53 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing d99e42ce6b83 f919f202 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/29 14:07 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a560a5672826 352ab904 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/20 13:50 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 3bf0514dc6f3 3af7dd65 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/19 15:23 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing a09ebb32afbe 3af7dd65 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/17 01:44 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 88bae831f381 578f7538 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/11 10:59 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 88bae831f381 77b23aa1 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/10 23:46 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 88bae831f381 77b23aa1 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/02/02 16:37 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing f1a27f081c1f 60bf9982 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/01/24 06:00 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 6613476e225e 1e153dc8 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/01/21 23:48 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 7a396820222d 9bd8dcda .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2024/01/21 11:02 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 7a396820222d 9bd8dcda .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/11/06 03:31 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 1c41041124bd 500bfdc4 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/11/01 08:21 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing c70793fb7632 69904c9f .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/10/27 23:41 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 9b6db9a3a675 3c418d72 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/09/10 12:32 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 6b8bb5b8d9b3 6654cf89 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/09/10 03:20 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 2a5a4326e583 6654cf89 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/09/09 10:08 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 7ba2090ca64e 6654cf89 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/09/03 16:54 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 0468be89b3fa 696ea0d2 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/08/28 06:45 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 895ed7eb263d 7ba13a15 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/08/12 21:56 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 55c3e571d2a0 39990d51 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/08/12 20:53 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 55c3e571d2a0 39990d51 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/06/05 12:27 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing d37537a1f7cf a4ae4f42 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
2023/05/30 07:39 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 046895105d96 cf184559 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-usb INFO: rcu detected stall in wait4
* Struck through repros no longer work on HEAD.