syzbot


INFO: rcu detected stall in sys_symlinkat

Status: auto-obsoleted due to no activity on 2022/11/28 07:06
Subsystems: kvm
[Documentation on labels]
First crash: 869d, last: 769d
Similar bugs (9)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: rcu detected stall in sys_symlinkat (3) ext4 3 436d 489d 0/28 auto-obsoleted due to no activity on 2023/10/26 22:46
linux-5.15 INFO: rcu detected stall in sys_symlinkat 1 500d 500d 0/3 auto-obsoleted due to no activity on 2023/09/03 15:54
linux-5.15 INFO: rcu detected stall in sys_symlinkat (3) 2 112d 137d 0/3 auto-obsoleted due to no activity on 2024/09/25 17:37
linux-6.1 INFO: rcu detected stall in sys_symlinkat (2) 1 284d 284d 0/3 auto-obsoleted due to no activity on 2024/04/06 00:16
linux-6.1 INFO: rcu detected stall in sys_symlinkat 1 529d 529d 0/3 auto-obsoleted due to no activity on 2023/08/23 09:07
upstream INFO: rcu detected stall in sys_symlinkat (2) ext4 lsm 2 582d 675d 0/28 auto-obsoleted due to no activity on 2023/06/03 10:14
upstream INFO: rcu detected stall in sys_symlinkat (4) ext4 1 313d 313d 0/28 auto-obsoleted due to no activity on 2024/02/27 04:07
linux-5.15 INFO: rcu detected stall in sys_symlinkat (2) 1 309d 309d 0/3 auto-obsoleted due to no activity on 2024/03/12 11:22
android-5-15 BUG: soft lockup in sys_symlinkat 13 84d 175d 0/2 premoderation: reported on 2024/04/15 06:45

Sample crash report:
rcu: INFO: rcu_preempt detected stalls on CPUs/tasks:
	(detected by 0, t=10502 jiffies, g=353273, q=96 ncpus=2)
rcu: All QSes seen, last rcu_preempt kthread activity 10502 (4295281297-4295270795), jiffies_till_next_fqs=1, root ->qsmask 0x0
rcu: rcu_preempt kthread starved for 10502 jiffies! g353273 f0x2 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:26424 pid:   16 ppid:     2 flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5182 [inline]
 __schedule+0x957/0xe20 kernel/sched/core.c:6494
 schedule+0xcb/0x190 kernel/sched/core.c:6570
 schedule_timeout+0x1b9/0x300 kernel/time/timer.c:1935
 rcu_gp_fqs_loop+0x309/0x1070 kernel/rcu/tree.c:1657
 rcu_gp_kthread+0xa5/0x390 kernel/rcu/tree.c:1854
 kthread+0x266/0x300 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30
 </TASK>
rcu: Stack dump where RCU GP kthread last ran:
NMI backtrace for cpu 0
CPU: 0 PID: 2317 Comm: syz-executor.1 Not tainted 6.0.0-rc3-syzkaller-00007-gdcf8e5633e2e #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2cb lib/dump_stack.c:106
 nmi_cpu_backtrace+0x47c/0x4b0 lib/nmi_backtrace.c:111
 nmi_trigger_cpumask_backtrace+0x169/0x280 lib/nmi_backtrace.c:62
 trigger_single_cpu_backtrace include/linux/nmi.h:166 [inline]
 rcu_check_gp_kthread_starvation+0x1da/0x250 kernel/rcu/tree_stall.h:514
 print_other_cpu_stall+0x1554/0x1690 kernel/rcu/tree_stall.h:619
 check_cpu_stall kernel/rcu/tree_stall.h:762 [inline]
 rcu_pending kernel/rcu/tree.c:3660 [inline]
 rcu_sched_clock_irq+0xa17/0x1950 kernel/rcu/tree.c:2342
 update_process_times+0x148/0x1b0 kernel/time/timer.c:1839
 tick_sched_handle kernel/time/tick-sched.c:243 [inline]
 tick_sched_timer+0x377/0x540 kernel/time/tick-sched.c:1480
 __run_hrtimer kernel/time/hrtimer.c:1685 [inline]
 __hrtimer_run_queues+0x4cb/0xa60 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x3a6/0xfd0 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0xf9/0x280 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
RIP: 0010:native_safe_halt arch/x86/include/asm/irqflags.h:51 [inline]
RIP: 0010:arch_safe_halt arch/x86/include/asm/irqflags.h:89 [inline]
RIP: 0010:kvm_wait+0x1b0/0x1f0 arch/x86/kernel/kvm.c:1066
Code: 4c 89 e0 48 c1 e8 03 42 8a 04 28 84 c0 75 41 45 8a 34 24 e8 c2 14 54 00 44 3a 74 24 1c 75 10 66 90 0f 00 2d 52 3a 6e 09 fb f4 <e9> cc fe ff ff fb e9 c6 fe ff ff 44 89 e1 80 e1 07 38 c1 0f 8c 57
RSP: 0018:ffffc9000b3c7a40 EFLAGS: 00000246
RAX: dc0a4a96797cb600 RBX: 1ffff92001678f4c RCX: ffffffff8169b53a
RDX: dffffc0000000000 RSI: ffffffff8aad76c0 RDI: ffffffff8b0a59a0
RBP: ffffc9000b3c7b10 R08: dffffc0000000000 R09: fffffbfff1ff3c0c
R10: fffffbfff1ff3c0c R11: 1ffffffff1ff3c0b R12: ffff888028221c00
R13: dffffc0000000000 R14: 1ffff92001678f03 R15: ffffc9000b3c7a80
 pv_wait arch/x86/include/asm/paravirt.h:603 [inline]
 pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:470 [inline]
 __pv_queued_spin_lock_slowpath+0x70d/0xc60 kernel/locking/qspinlock.c:511
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:591 [inline]
 queued_spin_lock_slowpath+0x42/0x50 arch/x86/include/asm/qspinlock.h:51
 queued_spin_lock include/asm-generic/qspinlock.h:114 [inline]
 do_raw_spin_lock+0x264/0x360 kernel/locking/spinlock_debug.c:115
 spin_lock include/linux/spinlock.h:349 [inline]
 list_lru_add+0xae/0x450 mm/list_lru.c:126
 d_lru_add+0xd8/0x160 fs/dcache.c:431
 retain_dentry+0x1c8/0x290 fs/dcache.c:685
 dput+0x1e3/0x410 fs/dcache.c:908
 done_path_create fs/namei.c:3833 [inline]
 do_symlinkat+0x21e/0x610 fs/namei.c:4410
 __do_sys_symlinkat fs/namei.c:4424 [inline]
 __se_sys_symlinkat fs/namei.c:4421 [inline]
 __x64_sys_symlinkat+0x95/0xa0 fs/namei.c:4421
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x2b/0x70 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7fee79c88b87

================================
WARNING: inconsistent lock state
6.0.0-rc3-syzkaller-00007-gdcf8e5633e2e #0 Not tainted
--------------------------------
inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage.
syz-executor.1/2317 [HC1[1]:SC0[0]:HE0:SE1] takes:
ffffffff8cdf1fb8 (vmap_area_lock){?.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:349 [inline]
ffffffff8cdf1fb8 (vmap_area_lock){?.+.}-{2:2}, at: find_vmap_area+0x1d/0x120 mm/vmalloc.c:1836
{HARDIRQ-ON-W} state was registered at:
  lock_acquire+0x1a7/0x400 kernel/locking/lockdep.c:5666
  __raw_spin_lock include/linux/spinlock_api_smp.h:133 [inline]
  _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:154
  spin_lock include/linux/spinlock.h:349 [inline]
  alloc_vmap_area+0x1954/0x1b70 mm/vmalloc.c:1617
  __get_vm_area_node+0x18a/0x380 mm/vmalloc.c:2484
  get_vm_area_caller+0x45/0x50 mm/vmalloc.c:2537
  __ioremap_caller+0x510/0x920 arch/x86/mm/ioremap.c:280
  acpi_os_ioremap include/acpi/acpi_io.h:13 [inline]
  acpi_map drivers/acpi/osl.c:296 [inline]
  acpi_os_map_iomem+0x226/0x4b0 drivers/acpi/osl.c:355
  acpi_tb_acquire_table+0xf5/0x25d drivers/acpi/acpica/tbdata.c:142
  acpi_tb_validate_table drivers/acpi/acpica/tbdata.c:317 [inline]
  acpi_tb_validate_temp_table+0xa6/0x10b drivers/acpi/acpica/tbdata.c:400
  acpi_tb_verify_temp_table+0x82/0x8ed drivers/acpi/acpica/tbdata.c:504
  acpi_reallocate_root_table+0x328/0x584 drivers/acpi/acpica/tbxface.c:180
  acpi_early_init+0xdb/0x536 drivers/acpi/bus.c:1214
  start_kernel+0x40b/0x55b init/main.c:1099
  secondary_startup_64_no_verify+0xcf/0xdb
irq event stamp: 46466
hardirqs last  enabled at (46465): [<ffffffff813c44be>] kvm_wait+0x19e/0x1f0
hardirqs last disabled at (46466): [<ffffffff8a38cc3a>] sysvec_apic_timer_interrupt+0xa/0xb0 arch/x86/kernel/apic/apic.c:1106
softirqs last  enabled at (5178): [<ffffffff8152fddc>] __irq_exit_rcu+0xec/0x170 kernel/softirq.c:650
softirqs last disabled at (5069): [<ffffffff8152fddc>] __irq_exit_rcu+0xec/0x170 kernel/softirq.c:650

other info that might help us debug this:
 Possible unsafe locking scenario:

       CPU0
       ----
  lock(vmap_area_lock);
  <Interrupt>
    lock(vmap_area_lock);

 *** DEADLOCK ***

4 locks held by syz-executor.1/2317:
 #0: ffff88814b1ae460 (sb_writers#4){.+.+}-{0:0}, at: mnt_want_write+0x3b/0x80 fs/namespace.c:393
 #1: ffff888044942218 (&type->i_mutex_dir_key#3/1){+.+.}-{3:3}, at: inode_lock_nested include/linux/fs.h:791 [inline]
 #1: ffff888044942218 (&type->i_mutex_dir_key#3/1){+.+.}-{3:3}, at: filename_create+0x24d/0x500 fs/namei.c:3784
 #2: ffff8880783dcf58 (&dentry->d_lock){+.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:349 [inline]
 #2: ffff8880783dcf58 (&dentry->d_lock){+.+.}-{2:2}, at: fast_dput fs/dcache.c:789 [inline]
 #2: ffff8880783dcf58 (&dentry->d_lock){+.+.}-{2:2}, at: dput+0x230/0x410 fs/dcache.c:900
 #3: ffff888028221c18 (&lru->node[i].lock){+.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:349 [inline]
 #3: ffff888028221c18 (&lru->node[i].lock){+.+.}-{2:2}, at: list_lru_add+0xae/0x450 mm/list_lru.c:126

stack backtrace:
CPU: 0 PID: 2317 Comm: syz-executor.1 Not tainted 6.0.0-rc3-syzkaller-00007-gdcf8e5633e2e #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
Call Trace:
 <IRQ>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2cb lib/dump_stack.c:106
 mark_lock_irq+0xb2a/0xf10
 mark_lock+0x21e/0x350 kernel/locking/lockdep.c:4632
 mark_usage kernel/locking/lockdep.c:4524 [inline]
 __lock_acquire+0xb40/0x1f60 kernel/locking/lockdep.c:5007
 lock_acquire+0x1a7/0x400 kernel/locking/lockdep.c:5666
 __raw_spin_lock include/linux/spinlock_api_smp.h:133 [inline]
 _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:154
 spin_lock include/linux/spinlock.h:349 [inline]
 find_vmap_area+0x1d/0x120 mm/vmalloc.c:1836
 check_heap_object+0x30/0x820 mm/usercopy.c:176
 __check_object_size+0xad/0x210 mm/usercopy.c:250
 check_object_size include/linux/thread_info.h:199 [inline]
 __copy_from_user_inatomic include/linux/uaccess.h:62 [inline]
 copy_from_user_nmi+0x98/0x100 arch/x86/lib/usercopy.c:47
 copy_code arch/x86/kernel/dumpstack.c:91 [inline]
 show_opcodes+0xa2/0x120 arch/x86/kernel/dumpstack.c:121
 show_ip arch/x86/kernel/dumpstack.c:144 [inline]
 show_iret_regs+0x2f/0x60 arch/x86/kernel/dumpstack.c:149
 __show_regs+0x29/0x500 arch/x86/kernel/process_64.c:74
 show_regs_if_on_stack arch/x86/kernel/dumpstack.c:167 [inline]
 show_trace_log_lvl+0x562/0x630 arch/x86/kernel/dumpstack.c:292
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2cb lib/dump_stack.c:106
 nmi_cpu_backtrace+0x47c/0x4b0 lib/nmi_backtrace.c:111
 nmi_trigger_cpumask_backtrace+0x169/0x280 lib/nmi_backtrace.c:62
 trigger_single_cpu_backtrace include/linux/nmi.h:166 [inline]
 rcu_check_gp_kthread_starvation+0x1da/0x250 kernel/rcu/tree_stall.h:514
 print_other_cpu_stall+0x1554/0x1690 kernel/rcu/tree_stall.h:619
 check_cpu_stall kernel/rcu/tree_stall.h:762 [inline]
 rcu_pending kernel/rcu/tree.c:3660 [inline]
 rcu_sched_clock_irq+0xa17/0x1950 kernel/rcu/tree.c:2342
 update_process_times+0x148/0x1b0 kernel/time/timer.c:1839
 tick_sched_handle kernel/time/tick-sched.c:243 [inline]
 tick_sched_timer+0x377/0x540 kernel/time/tick-sched.c:1480
 __run_hrtimer kernel/time/hrtimer.c:1685 [inline]
 __hrtimer_run_queues+0x4cb/0xa60 kernel/time/hrtimer.c:1749
 hrtimer_interrupt+0x3a6/0xfd0 kernel/time/hrtimer.c:1811
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
 __sysvec_apic_timer_interrupt+0xf9/0x280 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
RIP: 0010:native_safe_halt arch/x86/include/asm/irqflags.h:51 [inline]
RIP: 0010:arch_safe_halt arch/x86/include/asm/irqflags.h:89 [inline]
RIP: 0010:kvm_wait+0x1b0/0x1f0 arch/x86/kernel/kvm.c:1066
Code: 4c 89 e0 48 c1 e8 03 42 8a 04 28 84 c0 75 41 45 8a 34 24 e8 c2 14 54 00 44 3a 74 24 1c 75 10 66 90 0f 00 2d 52 3a 6e 09 fb f4 <e9> cc fe ff ff fb e9 c6 fe ff ff 44 89 e1 80 e1 07 38 c1 0f 8c 57
RSP: 0018:ffffc9000b3c7a40 EFLAGS: 00000246
RAX: dc0a4a96797cb600 RBX: 1ffff92001678f4c RCX: ffffffff8169b53a
RDX: dffffc0000000000 RSI: ffffffff8aad76c0 RDI: ffffffff8b0a59a0
RBP: ffffc9000b3c7b10 R08: dffffc0000000000 R09: fffffbfff1ff3c0c
R10: fffffbfff1ff3c0c R11: 1ffffffff1ff3c0b R12: ffff888028221c00
R13: dffffc0000000000 R14: 1ffff92001678f03 R15: ffffc9000b3c7a80
 pv_wait arch/x86/include/asm/paravirt.h:603 [inline]
 pv_wait_head_or_lock kernel/locking/qspinlock_paravirt.h:470 [inline]
 __pv_queued_spin_lock_slowpath+0x70d/0xc60 kernel/locking/qspinlock.c:511
 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:591 [inline]
 queued_spin_lock_slowpath+0x42/0x50 arch/x86/include/asm/qspinlock.h:51
 queued_spin_lock include/asm-generic/qspinlock.h:114 [inline]
 do_raw_spin_lock+0x264/0x360 kernel/locking/spinlock_debug.c:115
 spin_lock include/linux/spinlock.h:349 [inline]
 list_lru_add+0xae/0x450 mm/list_lru.c:126
 d_lru_add+0xd8/0x160 fs/dcache.c:431
 retain_dentry+0x1c8/0x290 fs/dcache.c:685
 dput+0x1e3/0x410 fs/dcache.c:908
 done_path_create fs/namei.c:3833 [inline]
 do_symlinkat+0x21e/0x610 fs/namei.c:4410
 __do_sys_symlinkat fs/namei.c:4424 [inline]
 __se_sys_symlinkat fs/namei.c:4421 [inline]
 __x64_sys_symlinkat+0x95/0xa0 fs/namei.c:4421
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x2b/0x70 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7fee79c88b87
Code: 73 01 c3 48 c7 c1 b8 ff ff ff 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 0a 01 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 b8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007ffc7fafe8e8 EFLAGS: 00000206 ORIG_RAX: 000000000000010a
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fee79c88b87
RDX: 00007fee79ce3235 RSI: 00000000ffffff9c RDI: 00007ffc7fafe9b0
RBP: 0000000000000001 R08: 0000000000000000 R09: 00007ffc7fafe780
R10: 00007ffc7fafe637 R11: 0000000000000206 R12: 0000000000000001
R13: 0000000000000000 R14: 0000000000000001 R15: 00007ffc7fafe9b0
 </TASK>
Code: 73 01 c3 48 c7 c1 b8 ff ff ff 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 0a 01 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 b8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007ffc7fafe8e8 EFLAGS: 00000206 ORIG_RAX: 000000000000010a
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fee79c88b87
RDX: 00007fee79ce3235 RSI: 00000000ffffff9c RDI: 00007ffc7fafe9b0
RBP: 0000000000000001 R08: 0000000000000000 R09: 00007ffc7fafe780
R10: 00007ffc7fafe637 R11: 0000000000000206 R12: 0000000000000001
R13: 0000000000000000 R14: 0000000000000001 R15: 00007ffc7fafe9b0
 </TASK>

Crashes (5):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2022/08/30 06:57 upstream dcf8e5633e2e 5b44472d .config console log report info ci-upstream-kasan-gce-smack-root INFO: rcu detected stall in sys_symlinkat
2022/08/18 21:19 upstream 3b06a2755758 26a13b38 .config console log report info ci-upstream-kasan-gce-root INFO: rcu detected stall in sys_symlinkat
2022/07/07 21:57 upstream e8a4e1c1bb69 bff65f44 .config console log report info ci-upstream-kasan-gce-selinux-root INFO: rcu detected stall in sys_symlinkat
2022/06/13 16:02 upstream b13baccc3850 0d5abf15 .config console log report info ci-upstream-kasan-gce-root INFO: rcu detected stall in sys_symlinkat
2022/05/22 12:07 upstream eaea45fc0e7b 7268fa62 .config console log report info ci-upstream-kasan-gce-selinux-root INFO: rcu detected stall in sys_symlinkat
* Struck through repros no longer work on HEAD.