syzbot

------------[ cut here ]------------
======================================================
WARNING: possible circular locking dependency detected
5.15.157-syzkaller #0 Not tainted
------------------------------------------------------
ksoftirqd/0/14 is trying to acquire lock:
ffffffff8c914680 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x2c/0x60 kernel/printk/printk.c:1802

but task is already holding lock:
ffff8880b9a28098 (&base->lock){-.-.}-{2:2}, at: __mod_timer+0x772/0xeb0 kernel/time/timer.c:1043

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #5 (&base->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
       lock_timer_base+0x120/0x260 kernel/time/timer.c:946
       __mod_timer+0x1d6/0xeb0 kernel/time/timer.c:1019
       queue_delayed_work_on+0x156/0x250 kernel/workqueue.c:1715
       psi_enqueue kernel/sched/stats.h:104 [inline]
       enqueue_task+0x2fe/0x3a0 kernel/sched/core.c:1973
       activate_task kernel/sched/core.c:2005 [inline]
       wake_up_new_task+0x515/0xb60 kernel/sched/core.c:4522
       kernel_clone+0x44e/0x960 kernel/fork.c:2628
       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

-> #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:11480
       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
       __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
       _raw_spin_unlock_irqrestore+0xd4/0x130 kernel/locking/spinlock.c:194
       __debug_check_no_obj_freed lib/debugobjects.c:981 [inline]
       debug_check_no_obj_freed+0x455/0x4e0 lib/debugobjects.c:1002
       slab_free_hook mm/slub.c:1680 [inline]
       slab_free_freelist_hook+0x86/0x160 mm/slub.c:1731
       slab_free mm/slub.c:3499 [inline]
       kmem_cache_free+0x91/0x1f0 mm/slub.c:3515
       putname fs/namei.c:271 [inline]
       user_path_at_empty+0x12b/0x180 fs/namei.c:2885
       user_path_at include/linux/namei.h:57 [inline]
       vfs_statx+0x106/0x3b0 fs/stat.c:221
       vfs_fstatat fs/stat.c:243 [inline]
       __do_sys_newfstatat fs/stat.c:411 [inline]
       __se_sys_newfstatat fs/stat.c:405 [inline]
       __x64_sys_newfstatat+0x12c/0x1b0 fs/stat.c:405
       do_syscall_x64 arch/x86/entry/common.c:50 [inline]
       do_syscall_64+0x3b/0xb0 arch/x86/entry/common.c:80
       entry_SYSCALL_64_after_hwframe+0x66/0xd0

-> #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
       __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:161 [inline]
       _raw_spin_unlock_irqrestore+0xd4/0x130 kernel/locking/spinlock.c:194
       spin_unlock_irqrestore include/linux/spinlock.h:418 [inline]
       uart_write+0x6af/0x930 drivers/tty/serial/serial_core.c:590
       process_output_block drivers/tty/n_tty.c:592 [inline]
       n_tty_write+0xd7e/0x1280 drivers/tty/n_tty.c:2339
       do_tty_write drivers/tty/tty_io.c:1038 [inline]
       file_tty_write+0x561/0x920 drivers/tty/tty_io.c:1110
       call_write_iter include/linux/fs.h:2172 [inline]
       new_sync_write fs/read_write.c:507 [inline]
       vfs_write+0xacf/0xe50 fs/read_write.c:594
       ksys_write+0x1a2/0x2c0 fs/read_write.c:647
       do_syscall_x64 arch/x86/entry/common.c:50 [inline]
       do_syscall_64+0x3b/0xb0 arch/x86/entry/common.c:80
       entry_SYSCALL_64_after_hwframe+0x66/0xd0

-> #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_lock_spinning_enable+0x51/0x60 kernel/printk/printk.c:1805
       console_unlock+0xa47/0x12b0 kernel/printk/printk.c:2741
       vprintk_emit+0xbf/0x150 kernel/printk/printk.c:2274
       _printk+0xd1/0x120 kernel/printk/printk.c:2299
       report_bug+0x1e5/0x2e0 lib/bug.c:195
       handle_bug+0x3d/0x70 arch/x86/kernel/traps.c:239
       exc_invalid_op+0x16/0x40 arch/x86/kernel/traps.c:259
       asm_exc_invalid_op+0x16/0x20 arch/x86/include/asm/idtentry.h:566
       copy_from_user_nofault+0x15c/0x1c0
       bpf_probe_read_user_common kernel/trace/bpf_trace.c:157 [inline]
       ____bpf_probe_read_user kernel/trace/bpf_trace.c:166 [inline]
       bpf_probe_read_user+0x26/0x70 kernel/trace/bpf_trace.c:163
       bpf_prog_078a3568a35342ea+0x38/0xbe8
       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_run3+0x1d1/0x380 kernel/trace/bpf_trace.c:1918
       trace_timer_start include/trace/events/timer.h:52 [inline]
       enqueue_timer+0x3ae/0x540 kernel/time/timer.c:586
       internal_add_timer kernel/time/timer.c:611 [inline]
       __mod_timer+0xa60/0xeb0 kernel/time/timer.c:1062
       queue_delayed_work_on+0x156/0x250 kernel/workqueue.c:1715
       queue_delayed_work include/linux/workqueue.h:527 [inline]
       srcu_funnel_gp_start kernel/rcu/srcutree.c:682 [inline]
       srcu_gp_start_if_needed+0x5dc/0x8f0 kernel/rcu/srcutree.c:838
       rcu_do_batch kernel/rcu/tree.c:2523 [inline]
       rcu_core+0xa15/0x1650 kernel/rcu/tree.c:2763
       __do_softirq+0x3b3/0x93a kernel/softirq.c:558
       run_ksoftirqd+0xc1/0x120 kernel/softirq.c:921
       smpboot_thread_fn+0x51b/0x9d0 kernel/smpboot.c:164
       kthread+0x3f6/0x4f0 kernel/kthread.c:334
       ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:300

other info that might help us debug this:

Chain exists of:
  console_owner --> &rq->__lock --> &base->lock

 Possible unsafe locking scenario:

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

 *** DEADLOCK ***

6 locks held by ksoftirqd/0/14:
 #0: ffffffff8c91fbe0 (rcu_callback){....}-{0:0}, at: rcu_lock_acquire+0x0/0x20
 #1: ffffffff8c95f858 (tracepoint_srcu){....}-{0:0}, at: rcu_lock_acquire+0x0/0x20
 #2: ffffffff8c95f5d0 (tracepoint_srcu.lock){..-.}-{2:2}, at: srcu_funnel_gp_start kernel/rcu/srcutree.c:665 [inline]
 #2: ffffffff8c95f5d0 (tracepoint_srcu.lock){..-.}-{2:2}, at: srcu_gp_start_if_needed+0x460/0x8f0 kernel/rcu/srcutree.c:838
 #3: ffff8880b9a28098 (&base->lock){-.-.}-{2:2}, at: __mod_timer+0x772/0xeb0 kernel/time/timer.c:1043
 #4: ffffffff8c91fb20 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x5/0x30 include/linux/rcupdate.h:311
 #5: ffffffff8c7fc3c0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0xa6/0x150 kernel/printk/printk.c:2273

stack backtrace:
CPU: 0 PID: 14 Comm: ksoftirqd/0 Not tainted 5.15.157-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/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_lock_spinning_enable+0x51/0x60 kernel/printk/printk.c:1805
 console_unlock+0xa47/0x12b0 kernel/printk/printk.c:2741
 vprintk_emit+0xbf/0x150 kernel/printk/printk.c:2274
 _printk+0xd1/0x120 kernel/printk/printk.c:2299
 report_bug+0x1e5/0x2e0 lib/bug.c:195
 handle_bug+0x3d/0x70 arch/x86/kernel/traps.c:239
 exc_invalid_op+0x16/0x40 arch/x86/kernel/traps.c:259
 asm_exc_invalid_op+0x16/0x20 arch/x86/include/asm/idtentry.h:566
RIP: 0010:copy_from_user_nofault+0x15c/0x1c0 mm/maccess.c:226
Code: db 48 c7 c0 f2 ff ff ff 48 0f 44 c5 eb 0c e8 8b c4 d5 ff 48 c7 c0 f2 ff ff ff 5b 41 5c 41 5d 41 5e 41 5f 5d c3 e8 74 c4 d5 ff <0f> 0b e9 1e ff ff ff 89 d9 80 e1 07 80 c1 03 38 c1 0f 8c ef fe ff
RSP: 0018:ffffc90000d37608 EFLAGS: 00010046
RAX: ffffffff81aa986c RBX: 0000000000000000 RCX: ffff88813fe21dc0
RDX: 0000000080000103 RSI: 0000000000000000 RDI: 0000000000000000
RBP: dffffc0000000000 R08: ffffffff81aa977d R09: fffffbfff1f7ee19
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000020000000
R13: 0000000000000008 R14: 0000000020000000 R15: ffffc90000d37668
 bpf_probe_read_user_common kernel/trace/bpf_trace.c:157 [inline]
 ____bpf_probe_read_user kernel/trace/bpf_trace.c:166 [inline]
 bpf_probe_read_user+0x26/0x70 kernel/trace/bpf_trace.c:163
 bpf_prog_078a3568a35342ea+0x38/0xbe8
 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_run3+0x1d1/0x380 kernel/trace/bpf_trace.c:1918
 trace_timer_start include/trace/events/timer.h:52 [inline]
 enqueue_timer+0x3ae/0x540 kernel/time/timer.c:586
 internal_add_timer kernel/time/timer.c:611 [inline]
 __mod_timer+0xa60/0xeb0 kernel/time/timer.c:1062
 queue_delayed_work_on+0x156/0x250 kernel/workqueue.c:1715
 queue_delayed_work include/linux/workqueue.h:527 [inline]
 srcu_funnel_gp_start kernel/rcu/srcutree.c:682 [inline]
 srcu_gp_start_if_needed+0x5dc/0x8f0 kernel/rcu/srcutree.c:838
 rcu_do_batch kernel/rcu/tree.c:2523 [inline]
 rcu_core+0xa15/0x1650 kernel/rcu/tree.c:2763
 __do_softirq+0x3b3/0x93a kernel/softirq.c:558
 run_ksoftirqd+0xc1/0x120 kernel/softirq.c:921
 smpboot_thread_fn+0x51b/0x9d0 kernel/smpboot.c:164
 kthread+0x3f6/0x4f0 kernel/kthread.c:334
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:300
 </TASK>
WARNING: CPU: 0 PID: 14 at mm/maccess.c:226 copy_from_user_nofault+0x15c/0x1c0
Modules linked in:
CPU: 0 PID: 14 Comm: ksoftirqd/0 Not tainted 5.15.157-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
RIP: 0010:copy_from_user_nofault+0x15c/0x1c0 mm/maccess.c:226
Code: db 48 c7 c0 f2 ff ff ff 48 0f 44 c5 eb 0c e8 8b c4 d5 ff 48 c7 c0 f2 ff ff ff 5b 41 5c 41 5d 41 5e 41 5f 5d c3 e8 74 c4 d5 ff <0f> 0b e9 1e ff ff ff 89 d9 80 e1 07 80 c1 03 38 c1 0f 8c ef fe ff
RSP: 0018:ffffc90000d37608 EFLAGS: 00010046
RAX: ffffffff81aa986c RBX: 0000000000000000 RCX: ffff88813fe21dc0
RDX: 0000000080000103 RSI: 0000000000000000 RDI: 0000000000000000
RBP: dffffc0000000000 R08: ffffffff81aa977d R09: fffffbfff1f7ee19
R10: 0000000000000000 R11: dffffc0000000001 R12: 0000000020000000
R13: 0000000000000008 R14: 0000000020000000 R15: ffffc90000d37668
FS:  0000000000000000(0000) GS:ffff8880b9a00000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000555556b19da8 CR3: 0000000074326000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <TASK>
 bpf_probe_read_user_common kernel/trace/bpf_trace.c:157 [inline]
 ____bpf_probe_read_user kernel/trace/bpf_trace.c:166 [inline]
 bpf_probe_read_user+0x26/0x70 kernel/trace/bpf_trace.c:163
 bpf_prog_078a3568a35342ea+0x38/0xbe8
 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_run3+0x1d1/0x380 kernel/trace/bpf_trace.c:1918
 trace_timer_start include/trace/events/timer.h:52 [inline]
 enqueue_timer+0x3ae/0x540 kernel/time/timer.c:586
 internal_add_timer kernel/time/timer.c:611 [inline]
 __mod_timer+0xa60/0xeb0 kernel/time/timer.c:1062
 queue_delayed_work_on+0x156/0x250 kernel/workqueue.c:1715
 queue_delayed_work include/linux/workqueue.h:527 [inline]
 srcu_funnel_gp_start kernel/rcu/srcutree.c:682 [inline]
 srcu_gp_start_if_needed+0x5dc/0x8f0 kernel/rcu/srcutree.c:838
 rcu_do_batch kernel/rcu/tree.c:2523 [inline]
 rcu_core+0xa15/0x1650 kernel/rcu/tree.c:2763
 __do_softirq+0x3b3/0x93a kernel/softirq.c:558
 run_ksoftirqd+0xc1/0x120 kernel/softirq.c:921
 smpboot_thread_fn+0x51b/0x9d0 kernel/smpboot.c:164
 kthread+0x3f6/0x4f0 kernel/kthread.c:334
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:300
 </TASK>