syzbot

FAULT_INJECTION: forcing a failure.
name fail_usercopy, interval 1, probability 0, space 0, times 0
======================================================
WARNING: possible circular locking dependency detected
5.15.164-syzkaller #0 Not tainted
------------------------------------------------------
syz-executor682/3619 is trying to acquire lock:
ffffffff8c9146a0 (console_owner){....}-{0:0}, at: console_trylock_spinning+0x185/0x3f0 kernel/printk/printk.c:1879

but task is already holding lock:
ffff8880b9a3a358 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x26/0x140 kernel/sched/core.c:475

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #4 (&rq->__lock){-.-.}-{2:2}:
       lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5623
       _raw_spin_lock_nested+0x2d/0x40 kernel/locking/spinlock.c:368
       raw_spin_rq_lock_nested+0x26/0x140 kernel/sched/core.c:475
       raw_spin_rq_lock kernel/sched/sched.h:1326 [inline]
       rq_lock kernel/sched/sched.h:1621 [inline]
       task_fork_fair+0x5d/0x350 kernel/sched/fair.c:11495
       sched_cgroup_fork+0x2d3/0x330 kernel/sched/core.c:4466
       copy_process+0x224a/0x3ef0 kernel/fork.c:2320
       kernel_clone+0x210/0x960 kernel/fork.c:2604
       kernel_thread+0x168/0x1e0 kernel/fork.c:2656
       rest_init+0x21/0x330 init/main.c:706
       start_kernel+0x48c/0x540 init/main.c:1140
       secondary_startup_64_no_verify+0xb1/0xbb

-> #3 (&p->pi_lock){-.-.}-{2:2}:
       lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5623
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0xd1/0x120 kernel/locking/spinlock.c:162
       try_to_wake_up+0xae/0x1300 kernel/sched/core.c:4030
       __wake_up_common+0x2a0/0x4e0 kernel/sched/wait.c:108
       __wake_up_common_lock kernel/sched/wait.c:138 [inline]
       __wake_up+0x112/0x1c0 kernel/sched/wait.c:157
       tty_port_default_wakeup+0xa8/0x100 drivers/tty/tty_port.c:51
       serial8250_tx_chars+0x60d/0x800 drivers/tty/serial/8250/8250_port.c:1828
       serial8250_handle_irq+0x505/0x600 drivers/tty/serial/8250/8250_port.c:1924
       serial8250_default_handle_irq+0xc8/0x1e0 drivers/tty/serial/8250/8250_port.c:1941
       serial8250_interrupt+0xa1/0x1e0 drivers/tty/serial/8250/8250_core.c:127
       __handle_irq_event_percpu+0x292/0xa70 kernel/irq/handle.c:156
       handle_irq_event_percpu kernel/irq/handle.c:196 [inline]
       handle_irq_event+0xff/0x2b0 kernel/irq/handle.c:213
       handle_edge_irq+0x245/0xbf0 kernel/irq/chip.c:822
       generic_handle_irq_desc include/linux/irqdesc.h:158 [inline]
       handle_irq arch/x86/kernel/irq.c:231 [inline]
       __common_interrupt+0xd7/0x1f0 arch/x86/kernel/irq.c:250
       common_interrupt+0x9f/0xc0 arch/x86/kernel/irq.c:240
       asm_common_interrupt+0x22/0x40 arch/x86/include/asm/idtentry.h:629
       native_safe_halt arch/x86/include/asm/irqflags.h:51 [inline]
       arch_safe_halt arch/x86/include/asm/irqflags.h:89 [inline]
       acpi_safe_halt drivers/acpi/processor_idle.c:108 [inline]
       acpi_idle_do_entry+0x10f/0x340 drivers/acpi/processor_idle.c:562
       acpi_idle_enter+0x352/0x4f0 drivers/acpi/processor_idle.c:697
       cpuidle_enter_state+0x521/0xef0 drivers/cpuidle/cpuidle.c:237
       cpuidle_enter+0x59/0x90 drivers/cpuidle/cpuidle.c:351
       call_cpuidle kernel/sched/idle.c:158 [inline]
       cpuidle_idle_call kernel/sched/idle.c:239 [inline]
       do_idle+0x3e4/0x670 kernel/sched/idle.c:306
       cpu_startup_entry+0x14/0x20 kernel/sched/idle.c:403
       start_secondary+0x371/0x500 arch/x86/kernel/smpboot.c:281
       secondary_startup_64_no_verify+0xb1/0xbb

-> #2 (&tty->write_wait){-.-.}-{2:2}:
       lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5623
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0xd1/0x120 kernel/locking/spinlock.c:162
       __wake_up_common_lock kernel/sched/wait.c:137 [inline]
       __wake_up+0xf5/0x1c0 kernel/sched/wait.c:157
       tty_port_default_wakeup+0xa8/0x100 drivers/tty/tty_port.c:51
       serial8250_tx_chars+0x60d/0x800 drivers/tty/serial/8250/8250_port.c:1828
       serial8250_handle_irq+0x505/0x600 drivers/tty/serial/8250/8250_port.c:1924
       serial8250_default_handle_irq+0xc8/0x1e0 drivers/tty/serial/8250/8250_port.c:1941
       serial8250_interrupt+0xa1/0x1e0 drivers/tty/serial/8250/8250_core.c:127
       __handle_irq_event_percpu+0x292/0xa70 kernel/irq/handle.c:156
       handle_irq_event_percpu kernel/irq/handle.c:196 [inline]
       handle_irq_event+0xff/0x2b0 kernel/irq/handle.c:213
       handle_edge_irq+0x245/0xbf0 kernel/irq/chip.c:822
       generic_handle_irq_desc include/linux/irqdesc.h:158 [inline]
       handle_irq arch/x86/kernel/irq.c:231 [inline]
       __common_interrupt+0xd7/0x1f0 arch/x86/kernel/irq.c:250
       common_interrupt+0x9f/0xc0 arch/x86/kernel/irq.c:240
       asm_common_interrupt+0x22/0x40 arch/x86/include/asm/idtentry.h:629
       native_safe_halt arch/x86/include/asm/irqflags.h:51 [inline]
       arch_safe_halt arch/x86/include/asm/irqflags.h:89 [inline]
       acpi_safe_halt drivers/acpi/processor_idle.c:108 [inline]
       acpi_idle_do_entry+0x10f/0x340 drivers/acpi/processor_idle.c:562
       acpi_idle_enter+0x352/0x4f0 drivers/acpi/processor_idle.c:697
       cpuidle_enter_state+0x521/0xef0 drivers/cpuidle/cpuidle.c:237
       cpuidle_enter+0x59/0x90 drivers/cpuidle/cpuidle.c:351
       call_cpuidle kernel/sched/idle.c:158 [inline]
       cpuidle_idle_call kernel/sched/idle.c:239 [inline]
       do_idle+0x3e4/0x670 kernel/sched/idle.c:306
       cpu_startup_entry+0x14/0x20 kernel/sched/idle.c:403
       start_secondary+0x371/0x500 arch/x86/kernel/smpboot.c:281
       secondary_startup_64_no_verify+0xb1/0xbb

-> #1 (&port_lock_key){-.-.}-{2:2}:
       lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5623
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0xd1/0x120 kernel/locking/spinlock.c:162
       serial8250_console_write+0x19d/0x1180 drivers/tty/serial/8250/8250_port.c:3354
       console_unlock+0xced/0x12b0 kernel/printk/printk.c:2744
       vprintk_emit+0xbf/0x150 kernel/printk/printk.c:2274
       _printk+0xd1/0x120 kernel/printk/printk.c:2299
       register_console+0x65a/0x940 kernel/printk/printk.c:3107
       univ8250_console_init+0x41/0x50 drivers/tty/serial/8250/8250_core.c:690
       console_init+0x18c/0x660 kernel/printk/printk.c:3207
       start_kernel+0x301/0x540 init/main.c:1076
       secondary_startup_64_no_verify+0xb1/0xbb

-> #0 (console_owner){....}-{0:0}:
       check_prev_add kernel/locking/lockdep.c:3053 [inline]
       check_prevs_add kernel/locking/lockdep.c:3172 [inline]
       validate_chain+0x1649/0x5930 kernel/locking/lockdep.c:3788
       __lock_acquire+0x1295/0x1ff0 kernel/locking/lockdep.c:5012
       lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5623
       console_trylock_spinning+0x1a5/0x3f0 kernel/printk/printk.c:1896
       vprintk_emit+0xa6/0x150 kernel/printk/printk.c:2273
       _printk+0xd1/0x120 kernel/printk/printk.c:2299
       fail_dump lib/fault-inject.c:45 [inline]
       should_fail+0x36c/0x4c0 lib/fault-inject.c:146
       strncpy_from_user+0x32/0x370 lib/strncpy_from_user.c:118
       strncpy_from_user_nofault+0x6c/0x130 mm/maccess.c:295
       bpf_probe_read_user_str_common kernel/trace/bpf_trace.c:194 [inline]
       ____bpf_probe_read_user_str kernel/trace/bpf_trace.c:203 [inline]
       bpf_probe_read_user_str+0x26/0x70 kernel/trace/bpf_trace.c:200
       bpf_prog_693483806bd7978c+0x5b/0xa70
       bpf_dispatcher_nop_func include/linux/bpf.h:790 [inline]
       __bpf_prog_run include/linux/filter.h:628 [inline]
       bpf_prog_run include/linux/filter.h:635 [inline]
       __bpf_trace_run kernel/trace/bpf_trace.c:1880 [inline]
       bpf_trace_run2+0x19e/0x340 kernel/trace/bpf_trace.c:1917
       __traceiter_tlb_flush+0x6c/0xc0 include/trace/events/tlb.h:38
       trace_tlb_flush+0xed/0x110 include/trace/events/tlb.h:38
       switch_mm_irqs_off+0x748/0xa30
       context_switch kernel/sched/core.c:5016 [inline]
       __schedule+0x1167/0x45b0 kernel/sched/core.c:6376
       schedule+0x11b/0x1f0 kernel/sched/core.c:6459
       schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6518
       __mutex_lock_common+0xe34/0x25a0 kernel/locking/mutex.c:669
       __mutex_lock kernel/locking/mutex.c:729 [inline]
       mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
       tracepoint_probe_unregister+0x2e/0x980 kernel/tracepoint.c:548
       bpf_raw_tp_link_release+0x5f/0x80 kernel/bpf/syscall.c:2900
       bpf_link_free kernel/bpf/syscall.c:2471 [inline]
       bpf_link_put+0x234/0x2c0 kernel/bpf/syscall.c:2497
       bpf_link_release+0x37/0x40 kernel/bpf/syscall.c:2505
       __fput+0x3fe/0x8e0 fs/file_table.c:280
       task_work_run+0x129/0x1a0 kernel/task_work.c:164
       tracehook_notify_resume include/linux/tracehook.h:189 [inline]
       exit_to_user_mode_loop+0x106/0x130 kernel/entry/common.c:181
       exit_to_user_mode_prepare+0xb1/0x140 kernel/entry/common.c:214
       __syscall_exit_to_user_mode_work kernel/entry/common.c:296 [inline]
       syscall_exit_to_user_mode+0x5d/0x240 kernel/entry/common.c:307
       do_syscall_64+0x47/0xb0 arch/x86/entry/common.c:86
       entry_SYSCALL_64_after_hwframe+0x66/0xd0

other info that might help us debug this:

Chain exists of:
  console_owner --> &p->pi_lock --> &rq->__lock

 Possible unsafe locking scenario:

       CPU0                    CPU1
       ----                    ----
  lock(&rq->__lock);
                               lock(&p->pi_lock);
                               lock(&rq->__lock);
  lock(console_owner);

 *** DEADLOCK ***

3 locks held by syz-executor682/3619:
 #0: ffffffff8c95f9e8 (tracepoints_mutex){+.+.}-{3:3}, at: tracepoint_probe_unregister+0x2e/0x980 kernel/tracepoint.c:548
 #1: ffff8880b9a3a358 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x26/0x140 kernel/sched/core.c:475
 #2: ffffffff8c91fb20 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x5/0x30 include/linux/rcupdate.h:311

stack backtrace:
CPU: 0 PID: 3619 Comm: syz-executor682 Not tainted 5.15.164-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/27/2024
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2d0 lib/dump_stack.c:106
 check_noncircular+0x2f8/0x3b0 kernel/locking/lockdep.c:2133
 check_prev_add kernel/locking/lockdep.c:3053 [inline]
 check_prevs_add kernel/locking/lockdep.c:3172 [inline]
 validate_chain+0x1649/0x5930 kernel/locking/lockdep.c:3788
 __lock_acquire+0x1295/0x1ff0 kernel/locking/lockdep.c:5012
 lock_acquire+0x1db/0x4f0 kernel/locking/lockdep.c:5623
 console_trylock_spinning+0x1a5/0x3f0 kernel/printk/printk.c:1896
 vprintk_emit+0xa6/0x150 kernel/printk/printk.c:2273
 _printk+0xd1/0x120 kernel/printk/printk.c:2299
 fail_dump lib/fault-inject.c:45 [inline]
 should_fail+0x36c/0x4c0 lib/fault-inject.c:146
 strncpy_from_user+0x32/0x370 lib/strncpy_from_user.c:118
 strncpy_from_user_nofault+0x6c/0x130 mm/maccess.c:295
 bpf_probe_read_user_str_common kernel/trace/bpf_trace.c:194 [inline]
 ____bpf_probe_read_user_str kernel/trace/bpf_trace.c:203 [inline]
 bpf_probe_read_user_str+0x26/0x70 kernel/trace/bpf_trace.c:200
 bpf_prog_693483806bd7978c+0x5b/0xa70
 bpf_dispatcher_nop_func include/linux/bpf.h:790 [inline]
 __bpf_prog_run include/linux/filter.h:628 [inline]
 bpf_prog_run include/linux/filter.h:635 [inline]
 __bpf_trace_run kernel/trace/bpf_trace.c:1880 [inline]
 bpf_trace_run2+0x19e/0x340 kernel/trace/bpf_trace.c:1917
 __traceiter_tlb_flush+0x6c/0xc0 include/trace/events/tlb.h:38
 trace_tlb_flush+0xed/0x110 include/trace/events/tlb.h:38
 switch_mm_irqs_off+0x748/0xa30
 context_switch kernel/sched/core.c:5016 [inline]
 __schedule+0x1167/0x45b0 kernel/sched/core.c:6376
 schedule+0x11b/0x1f0 kernel/sched/core.c:6459
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6518
 __mutex_lock_common+0xe34/0x25a0 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 tracepoint_probe_unregister+0x2e/0x980 kernel/tracepoint.c:548
 bpf_raw_tp_link_release+0x5f/0x80 kernel/bpf/syscall.c:2900
 bpf_link_free kernel/bpf/syscall.c:2471 [inline]
 bpf_link_put+0x234/0x2c0 kernel/bpf/syscall.c:2497
 bpf_link_release+0x37/0x40 kernel/bpf/syscall.c:2505
 __fput+0x3fe/0x8e0 fs/file_table.c:280
 task_work_run+0x129/0x1a0 kernel/task_work.c:164
 tracehook_notify_resume include/linux/tracehook.h:189 [inline]
 exit_to_user_mode_loop+0x106/0x130 kernel/entry/common.c:181
 exit_to_user_mode_prepare+0xb1/0x140 kernel/entry/common.c:214
 __syscall_exit_to_user_mode_work kernel/entry/common.c:296 [inline]
 syscall_exit_to_user_mode+0x5d/0x240 kernel/entry/common.c:307
 do_syscall_64+0x47/0xb0 arch/x86/entry/common.c:86
 entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fc242061eaa
Code: Unable to access opcode bytes at RIP 0x7fc242061e80.
RSP: 002b:00007ffe27884d70 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000006 RCX: 00007fc242061eaa
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: 0000000000000001 R08: 00007ffe27884b27 R09: 00000000003d0f00
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
 </TASK>
CPU: 0 PID: 3619 Comm: syz-executor682 Not tainted 5.15.164-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/27/2024
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e3/0x2d0 lib/dump_stack.c:106
 fail_dump lib/fault-inject.c:52 [inline]
 should_fail+0x38a/0x4c0 lib/fault-inject.c:146
 strncpy_from_user+0x32/0x370 lib/strncpy_from_user.c:118
 strncpy_from_user_nofault+0x6c/0x130 mm/maccess.c:295
 bpf_probe_read_user_str_common kernel/trace/bpf_trace.c:194 [inline]
 ____bpf_probe_read_user_str kernel/trace/bpf_trace.c:203 [inline]
 bpf_probe_read_user_str+0x26/0x70 kernel/trace/bpf_trace.c:200
 bpf_prog_693483806bd7978c+0x5b/0xa70
 bpf_dispatcher_nop_func include/linux/bpf.h:790 [inline]
 __bpf_prog_run include/linux/filter.h:628 [inline]
 bpf_prog_run include/linux/filter.h:635 [inline]
 __bpf_trace_run kernel/trace/bpf_trace.c:1880 [inline]
 bpf_trace_run2+0x19e/0x340 kernel/trace/bpf_trace.c:1917
 __traceiter_tlb_flush+0x6c/0xc0 include/trace/events/tlb.h:38
 trace_tlb_flush+0xed/0x110 include/trace/events/tlb.h:38
 switch_mm_irqs_off+0x748/0xa30
 context_switch kernel/sched/core.c:5016 [inline]
 __schedule+0x1167/0x45b0 kernel/sched/core.c:6376
 schedule+0x11b/0x1f0 kernel/sched/core.c:6459
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6518
 __mutex_lock_common+0xe34/0x25a0 kernel/locking/mutex.c:669
 __mutex_lock kernel/locking/mutex.c:729 [inline]
 mutex_lock_nested+0x17/0x20 kernel/locking/mutex.c:743
 tracepoint_probe_unregister+0x2e/0x980 kernel/tracepoint.c:548
 bpf_raw_tp_link_release+0x5f/0x80 kernel/bpf/syscall.c:2900
 bpf_link_free kernel/bpf/syscall.c:2471 [inline]
 bpf_link_put+0x234/0x2c0 kernel/bpf/syscall.c:2497
 bpf_link_release+0x37/0x40 kernel/bpf/syscall.c:2505
 __fput+0x3fe/0x8e0 fs/file_table.c:280
 task_work_run+0x129/0x1a0 kernel/task_work.c:164
 tracehook_notify_resume include/linux/tracehook.h:189 [inline]
 exit_to_user_mode_loop+0x106/0x130 kernel/entry/common.c:181
 exit_to_user_mode_prepare+0xb1/0x140 kernel/entry/common.c:214
 __syscall_exit_to_user_mode_work kernel/entry/common.c:296 [inline]
 syscall_exit_to_user_mode+0x5d/0x240 kernel/entry/common.c:307
 do_syscall_64+0x47/0xb0 arch/x86/entry/common.c:86
 entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fc242061eaa
Code: Unable to access opcode bytes at RIP 0x7fc242061e80.
RSP: 002b:00007ffe27884d70 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000006 RCX: 00007fc242061eaa
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000005
RBP: 0000000000000001 R08: 00007ffe27884b27 R09: 00000000003d0f00
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
 </TASK>