syzbot


INFO: rcu detected stall in kauditd_thread

Status: auto-obsoleted due to no activity on 2023/11/27 06:59
Reported-by: syzbot+ee9778136c8aa7225a14@syzkaller.appspotmail.com
First crash: 346d, last: 260d
Similar bugs (6)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in kauditd_thread (3) kernel 2 164d 198d 0/26 auto-obsoleted due to no activity on 2024/02/20 23:02
upstream INFO: rcu detected stall in kauditd_thread kernel 2 834d 888d 0/26 closed as invalid on 2022/02/08 10:00
linux-6.1 INFO: rcu detected stall in kauditd_thread (2) 3 40d 146d 0/3 upstream: reported on 2023/12/11 16:51
upstream INFO: rcu detected stall in kauditd_thread (4) audit bpf C 5 9d23h 39d 0/26 upstream: reported C repro on 2024/03/27 18:39
linux-5.15 INFO: rcu detected stall in kauditd_thread 2 134d 151d 0/3 auto-obsoleted due to no activity on 2024/03/31 22:45
android-5-15 BUG: soft lockup in kauditd_thread origin:lts C 3 7h56m 31d 0/2 upstream: reported C repro on 2024/04/04 20:01

Sample crash report:
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
rcu: 	0-...!: (1 GPs behind) idle=126c/1/0x4000000000000000 softirq=74290/74291 fqs=2
	(detected by 1, t=10504 jiffies, g=103249, q=106 ncpus=2)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: kauditd Not tainted 6.1.46-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/26/2023
RIP: 0010:bytes_is_nonzero mm/kasan/generic.c:84 [inline]
RIP: 0010:memory_is_nonzero mm/kasan/generic.c:102 [inline]
RIP: 0010:memory_is_poisoned_n mm/kasan/generic.c:128 [inline]
RIP: 0010:memory_is_poisoned mm/kasan/generic.c:159 [inline]
RIP: 0010:check_region_inline mm/kasan/generic.c:180 [inline]
RIP: 0010:kasan_check_range+0x71/0x290 mm/kasan/generic.c:189
Code: 54 37 ff 49 c1 ea 03 49 bb 01 00 00 00 00 fc ff df 4f 8d 34 1a 4c 89 f5 4c 29 cd 48 83 fd 10 7f 26 48 85 ed 0f 84 3a 01 00 00 <49> f7 d2 49 01 da 41 80 39 00 0f 85 c4 01 00 00 49 ff c1 49 ff c2
RSP: 0018:ffffc900000078f8 EFLAGS: 00000002
RAX: 0000000000000001 RBX: 1ffffffff2093444 RCX: ffffffff8169f6e5
RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffffffff9049a220
RBP: 0000000000000001 R08: dffffc0000000000 R09: fffffbfff2093444
R10: 1ffffffff2093444 R11: dffffc0000000001 R12: ffff888013e5e418
R13: ffff888013e5d940 R14: fffffbfff2093445 R15: 0000000000000000
FS:  0000000000000000(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000000 CR3: 00000000804c0000 CR4: 00000000003526f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 instrument_atomic_read include/linux/instrumented.h:72 [inline]
 _test_bit include/asm-generic/bitops/instrumented-non-atomic.h:141 [inline]
 hlock_class kernel/locking/lockdep.c:228 [inline]
 check_wait_context kernel/locking/lockdep.c:4730 [inline]
 __lock_acquire+0x495/0x1f80 kernel/locking/lockdep.c:5006
 lock_acquire+0x1f8/0x5a0 kernel/locking/lockdep.c:5669
 __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_activate+0x16a/0x890 lib/debugobjects.c:706
 debug_hrtimer_activate kernel/time/hrtimer.c:420 [inline]
 debug_activate kernel/time/hrtimer.c:475 [inline]
 enqueue_hrtimer+0x30/0x390 kernel/time/hrtimer.c:1084
 __run_hrtimer kernel/time/hrtimer.c:1702 [inline]
 __hrtimer_run_queues+0x728/0xe50 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x392/0x980 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0x156/0x580 arch/x86/kernel/apic/apic.c:1112
 sysvec_apic_timer_interrupt+0x8c/0xb0 arch/x86/kernel/apic/apic.c:1106
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:console_emit_next_record+0xb70/0xda0 kernel/printk/printk.c:2740
Code: 00 48 8b 5c 24 68 44 0f b6 74 24 1f 4d 85 e4 75 07 e8 e4 13 1c 00 eb 06 e8 dd 13 1c 00 fb 48 c7 84 24 a0 00 00 00 0e 36 e0 45 <41> c7 44 1d 00 00 00 00 00 49 c7 44 1d 0a 00 00 00 00 49 c7 44 1d
RSP: 0018:ffffc90000a3f820 EFLAGS: 00000293
RAX: ffffffff816dea23 RBX: 1ffff92000147f18 RCX: ffff888013e5d940
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: ffffc90000a3fad0 R08: ffffffff816de9f9 R09: fffffbfff2093445
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000000000200
R13: dffffc0000000000 R14: 0000000000000001 R15: ffffc90000a3fb40
 console_unlock+0x278/0x6e0 kernel/printk/printk.c:2860
 vprintk_emit+0xd1/0x1f0 kernel/printk/printk.c:2268
 _printk+0xd1/0x111 kernel/printk/printk.c:2289
 kauditd_printk_skb kernel/audit.c:536 [inline]
 kauditd_hold_skb+0x1b8/0x200 kernel/audit.c:571
 kauditd_send_queue+0x2a3/0x2f0 kernel/audit.c:756
 kauditd_thread+0x722/0x960 kernel/audit.c:880
 kthread+0x26e/0x300 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>
rcu: rcu_preempt kthread starved for 10495 jiffies! g103249 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:25496 pid:16    ppid:2      flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5241 [inline]
 __schedule+0x132c/0x4330 kernel/sched/core.c:6554
 schedule+0xbf/0x180 kernel/sched/core.c:6630
 schedule_timeout+0x1b9/0x300 kernel/time/timer.c:1935
 rcu_gp_fqs_loop+0x2c2/0x1010 kernel/rcu/tree.c:1661
 rcu_gp_kthread+0xa3/0x3a0 kernel/rcu/tree.c:1860
 kthread+0x26e/0x300 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>
rcu: Stack dump where RCU GP kthread last ran:
CPU: 1 PID: 16054 Comm: kworker/u4:15 Not tainted 6.1.46-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/26/2023
Workqueue: events_unbound toggle_allocation_gate
RIP: 0010:csd_lock_wait kernel/smp.c:413 [inline]
RIP: 0010:smp_call_function_many_cond+0x1f62/0x33d0 kernel/smp.c:987
Code: 2f 44 89 ee 83 e6 01 31 ff e8 4a 19 0b 00 41 83 e5 01 49 bd 00 00 00 00 00 fc ff df 75 0a e8 d5 15 0b 00 e9 1b ff ff ff f3 90 <42> 0f b6 04 2b 84 c0 75 14 41 f7 07 01 00 00 00 0f 84 fe fe ff ff
RSP: 0018:ffffc9000983f5a0 EFLAGS: 00000293
RAX: ffffffff817ee84d RBX: 1ffff1101730841d RCX: ffff8880892d5940
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: ffffc9000983f980 R08: ffffffff817ee816 R09: fffffbfff2093445
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000800000000
R13: dffffc0000000000 R14: 0000000000000000 R15: ffff8880b98420e8
FS:  0000000000000000(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000001b31025000 CR3: 000000000ce8e000 CR4: 00000000003526e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 </IRQ>
 <TASK>
 on_each_cpu_cond_mask+0x3b/0x80 kernel/smp.c:1155
 on_each_cpu include/linux/smp.h:71 [inline]
 text_poke_sync arch/x86/kernel/alternative.c:1316 [inline]
 text_poke_bp_batch+0x2bb/0x940 arch/x86/kernel/alternative.c:1516
 text_poke_flush arch/x86/kernel/alternative.c:1707 [inline]
 text_poke_finish+0x16/0x30 arch/x86/kernel/alternative.c:1714
 arch_jump_label_transform_apply+0x13/0x20 arch/x86/kernel/jump_label.c:146
 static_key_enable_cpuslocked+0x12e/0x250 kernel/jump_label.c:177
 static_key_enable+0x16/0x20 kernel/jump_label.c:190
 toggle_allocation_gate+0xbf/0x480 mm/kfence/core.c:804
 process_one_work+0x8aa/0x11f0 kernel/workqueue.c:2292
 worker_thread+0xa5f/0x1210 kernel/workqueue.c:2439
 kthread+0x26e/0x300 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>
watchdog: BUG: soft lockup - CPU#1 stuck for 246s! [kworker/u4:15:16054]
Modules linked in:
irq event stamp: 681496
hardirqs last  enabled at (681495): [<ffffffff8aa00cc6>] asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:649
hardirqs last disabled at (681496): [<ffffffff8a84d9aa>] sysvec_apic_timer_interrupt+0xa/0xb0 arch/x86/kernel/apic/apic.c:1106
softirqs last  enabled at (630022): [<ffffffff8153e755>] invoke_softirq kernel/softirq.c:445 [inline]
softirqs last  enabled at (630022): [<ffffffff8153e755>] __irq_exit_rcu+0x155/0x240 kernel/softirq.c:650
softirqs last disabled at (629929): [<ffffffff8153e755>] invoke_softirq kernel/softirq.c:445 [inline]
softirqs last disabled at (629929): [<ffffffff8153e755>] __irq_exit_rcu+0x155/0x240 kernel/softirq.c:650
CPU: 1 PID: 16054 Comm: kworker/u4:15 Not tainted 6.1.46-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/26/2023
Workqueue: events_unbound toggle_allocation_gate
RIP: 0010:csd_lock_wait kernel/smp.c:413 [inline]
RIP: 0010:smp_call_function_many_cond+0x1f62/0x33d0 kernel/smp.c:987
Code: 2f 44 89 ee 83 e6 01 31 ff e8 4a 19 0b 00 41 83 e5 01 49 bd 00 00 00 00 00 fc ff df 75 0a e8 d5 15 0b 00 e9 1b ff ff ff f3 90 <42> 0f b6 04 2b 84 c0 75 14 41 f7 07 01 00 00 00 0f 84 fe fe ff ff
RSP: 0018:ffffc9000983f5a0 EFLAGS: 00000293
RAX: ffffffff817ee84d RBX: 1ffff1101730841d RCX: ffff8880892d5940
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: ffffc9000983f980 R08: ffffffff817ee816 R09: fffffbfff2093445
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000800000000
R13: dffffc0000000000 R14: 0000000000000000 R15: ffff8880b98420e8
FS:  0000000000000000(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000001b31025000 CR3: 000000000ce8e000 CR4: 00000000003526e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 </IRQ>
 <TASK>
 on_each_cpu_cond_mask+0x3b/0x80 kernel/smp.c:1155
 on_each_cpu include/linux/smp.h:71 [inline]
 text_poke_sync arch/x86/kernel/alternative.c:1316 [inline]
 text_poke_bp_batch+0x2bb/0x940 arch/x86/kernel/alternative.c:1516
 text_poke_flush arch/x86/kernel/alternative.c:1707 [inline]
 text_poke_finish+0x16/0x30 arch/x86/kernel/alternative.c:1714
 arch_jump_label_transform_apply+0x13/0x20 arch/x86/kernel/jump_label.c:146
 static_key_enable_cpuslocked+0x12e/0x250 kernel/jump_label.c:177
 static_key_enable+0x16/0x20 kernel/jump_label.c:190
 toggle_allocation_gate+0xbf/0x480 mm/kfence/core.c:804
 process_one_work+0x8aa/0x11f0 kernel/workqueue.c:2292
 worker_thread+0xa5f/0x1210 kernel/workqueue.c:2439
 kthread+0x26e/0x300 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: kauditd Not tainted 6.1.46-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/26/2023
RIP: 0010:variable_test_bit arch/x86/include/asm/bitops.h:233 [inline]
RIP: 0010:arch_test_bit arch/x86/include/asm/bitops.h:240 [inline]
RIP: 0010:_test_bit include/asm-generic/bitops/instrumented-non-atomic.h:142 [inline]
RIP: 0010:cpumask_test_cpu include/linux/cpumask.h:444 [inline]
RIP: 0010:cpu_online include/linux/cpumask.h:1030 [inline]
RIP: 0010:trace_hrtimer_expire_entry include/trace/events/timer.h:239 [inline]
RIP: 0010:__run_hrtimer kernel/time/hrtimer.c:1682 [inline]
RIP: 0010:__hrtimer_run_queues+0x52b/0xe50 kernel/time/hrtimer.c:1749
Code: 7e 73 8e be 08 00 00 00 e8 22 3a 67 00 31 db 4c 0f a3 3d 50 26 fa 0c 41 0f 92 c7 0f 92 c3 bf 02 00 00 00 89 de e8 35 a8 10 00 <31> ff 89 de e8 2c a8 10 00 45 84 ff 74 21 e8 a2 93 f9 ff 84 c0 49
RSP: 0018:ffffc90000007d60 EFLAGS: 00000046
RAX: 0000000000010001 RBX: 0000000000000001 RCX: ffffffff8179580e
RDX: ffff888013e5d940 RSI: 0000000000000001 RDI: 0000000000000002
RBP: ffffc90000007eb0 R08: ffffffff8179582b R09: fffffbfff1ce6fce
R10: 0000000000000000 R11: dffffc0000000001 R12: ffffffff88b672f0
R13: 0000000000000000 R14: ffff8880b982a4c0 R15: 0000000000000001
FS:  0000000000000000(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000000 CR3: 00000000804c0000 CR4: 00000000003526f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <IRQ>
 hrtimer_interrupt+0x392/0x980 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0x156/0x580 arch/x86/kernel/apic/apic.c:1112
 sysvec_apic_timer_interrupt+0x8c/0xb0 arch/x86/kernel/apic/apic.c:1106
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:console_emit_next_record+0xb70/0xda0 kernel/printk/printk.c:2740
Code: 00 48 8b 5c 24 68 44 0f b6 74 24 1f 4d 85 e4 75 07 e8 e4 13 1c 00 eb 06 e8 dd 13 1c 00 fb 48 c7 84 24 a0 00 00 00 0e 36 e0 45 <41> c7 44 1d 00 00 00 00 00 49 c7 44 1d 0a 00 00 00 00 49 c7 44 1d
RSP: 0018:ffffc90000a3f820 EFLAGS: 00000293
RAX: ffffffff816dea23 RBX: 1ffff92000147f18 RCX: ffff888013e5d940
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: ffffc90000a3fad0 R08: ffffffff816de9f9 R09: fffffbfff2093445
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000000000200
R13: dffffc0000000000 R14: 0000000000000001 R15: ffffc90000a3fb40
 console_unlock+0x278/0x6e0 kernel/printk/printk.c:2860
 vprintk_emit+0xd1/0x1f0 kernel/printk/printk.c:2268
 _printk+0xd1/0x111 kernel/printk/printk.c:2289
 kauditd_printk_skb kernel/audit.c:536 [inline]
 kauditd_hold_skb+0x1b8/0x200 kernel/audit.c:571
 kauditd_send_queue+0x2a3/0x2f0 kernel/audit.c:756
 kauditd_thread+0x722/0x960 kernel/audit.c:880
 kthread+0x26e/0x300 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>

Crashes (2):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2023/08/19 06:59 linux-6.1.y 6c44e13dc284 d216d8a0 .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-6-1-kasan INFO: rcu detected stall in kauditd_thread
2023/05/25 05:17 linux-6.1.y a343b0dd87b4 4bce1a3e .config console log report info [disk image] [vmlinux] [kernel image] ci2-linux-6-1-kasan INFO: rcu detected stall in kauditd_thread
* Struck through repros no longer work on HEAD.