------------[ cut here ]------------
======================================================
WARNING: possible circular locking dependency detected
6.7.0-rc4-next-20231207-syzkaller #0 Not tainted
------------------------------------------------------
kworker/u4:7/16248 is trying to acquire lock:
ffffffff8d199458 ((console_sem).lock){-...}-{2:2}, at: down_trylock+0x12/0x70 kernel/locking/semaphore.c:139

but task is already holding lock:
ffffffff8dc75078 (pool_rwlock){----}-{2:2}, at: stack_depot_put lib/stackdepot.c:621 [inline]
ffffffff8dc75078 (pool_rwlock){----}-{2:2}, at: stack_depot_put+0x24/0x110 lib/stackdepot.c:613

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #3 (pool_rwlock){----}-{2:2}:
       __raw_read_lock_irqsave include/linux/rwlock_api_smp.h:160 [inline]
       _raw_read_lock_irqsave+0x46/0x90 kernel/locking/spinlock.c:236
       stack_depot_save_flags+0x169/0x740 lib/stackdepot.c:508
       kasan_save_stack+0x42/0x50 mm/kasan/common.c:48
       __kasan_record_aux_stack+0xc2/0xd0 mm/kasan/generic.c:517
       task_work_add+0x88/0x2a0 kernel/task_work.c:48
       task_tick_numa kernel/sched/fair.c:3512 [inline]
       task_tick_fair+0x5a5/0xc30 kernel/sched/fair.c:12599
       scheduler_tick+0x210/0x650 kernel/sched/core.c:5674
       update_process_times+0x19e/0x220 kernel/time/timer.c:2076
       tick_sched_handle+0x8e/0x170 kernel/time/tick-sched.c:255
       tick_nohz_highres_handler+0xe9/0x110 kernel/time/tick-sched.c:1516
       __run_hrtimer kernel/time/hrtimer.c:1688 [inline]
       __hrtimer_run_queues+0x654/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+0x10c/0x410 arch/x86/kernel/apic/apic.c:1082
       sysvec_apic_timer_interrupt+0x90/0xb0 arch/x86/kernel/apic/apic.c:1076
       asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:645
       orc_find arch/x86/kernel/unwind_orc.c:227 [inline]
       unwind_next_frame+0x30c/0x2390 arch/x86/kernel/unwind_orc.c:494
       arch_stack_walk+0xfa/0x170 arch/x86/kernel/stacktrace.c:25
       stack_trace_save+0x95/0xd0 kernel/stacktrace.c:122
       kasan_save_stack+0x33/0x50 mm/kasan/common.c:47
       kasan_set_track+0x24/0x30 mm/kasan/common.c:61
       ____kasan_kmalloc mm/kasan/common.c:375 [inline]
       __kasan_kmalloc+0xa2/0xb0 mm/kasan/common.c:384
       kasan_kmalloc include/linux/kasan.h:198 [inline]
       __do_kmalloc_node mm/slub.c:3985 [inline]
       __kmalloc+0x1f9/0x440 mm/slub.c:3998
       kmalloc include/linux/slab.h:594 [inline]
       kzalloc include/linux/slab.h:711 [inline]
       tomoyo_init_log+0x142a/0x2100 security/tomoyo/audit.c:275
       tomoyo_supervisor+0x30c/0xea0 security/tomoyo/common.c:2089
       tomoyo_audit_path_log security/tomoyo/file.c:168 [inline]
       tomoyo_path_permission security/tomoyo/file.c:587 [inline]
       tomoyo_path_permission+0x270/0x3b0 security/tomoyo/file.c:573
       tomoyo_check_open_permission+0x373/0x3b0 security/tomoyo/file.c:777
       tomoyo_file_open security/tomoyo/tomoyo.c:333 [inline]
       tomoyo_file_open+0xa7/0xd0 security/tomoyo/tomoyo.c:328
       security_file_open+0x6a/0xe0 security/security.c:2920
       do_dentry_open+0x583/0x18c0 fs/open.c:936
       do_open fs/namei.c:3631 [inline]
       path_openat+0x1df1/0x2990 fs/namei.c:3788
       do_filp_open+0x1dc/0x430 fs/namei.c:3815
       do_sys_openat2+0x176/0x1e0 fs/open.c:1441
       do_sys_open fs/open.c:1456 [inline]
       __do_sys_openat fs/open.c:1472 [inline]
       __se_sys_openat fs/open.c:1467 [inline]
       __x64_sys_openat+0x175/0x210 fs/open.c:1467
       do_syscall_x64 arch/x86/entry/common.c:51 [inline]
       do_syscall_64+0x40/0x110 arch/x86/entry/common.c:82
       entry_SYSCALL_64_after_hwframe+0x62/0x6a

-> #2 (&rq->__lock){-.-.}-{2:2}:
       _raw_spin_lock_nested+0x31/0x40 kernel/locking/spinlock.c:378
       raw_spin_rq_lock_nested+0x29/0x130 kernel/sched/core.c:558
       raw_spin_rq_lock kernel/sched/sched.h:1385 [inline]
       rq_lock kernel/sched/sched.h:1699 [inline]
       task_fork_fair+0x70/0x240 kernel/sched/fair.c:12619
       sched_cgroup_fork+0x3cf/0x510 kernel/sched/core.c:4835
       copy_process+0x6a38/0x9770 kernel/fork.c:2601
       kernel_clone+0xfd/0x940 kernel/fork.c:2899
       user_mode_thread+0xb4/0xf0 kernel/fork.c:2977
       rest_init+0x27/0x2b0 init/main.c:695
       arch_call_rest_init+0x13/0x30 init/main.c:827
       start_kernel+0x39e/0x480 init/main.c:1072
       x86_64_start_reservations+0x18/0x30 arch/x86/kernel/head64.c:555
       x86_64_start_kernel+0xb2/0xc0 arch/x86/kernel/head64.c:536
       secondary_startup_64_no_verify+0x166/0x16b

-> #1 (&p->pi_lock){-.-.}-{2:2}:
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0x3a/0x50 kernel/locking/spinlock.c:162
       class_raw_spinlock_irqsave_constructor include/linux/spinlock.h:522 [inline]
       try_to_wake_up+0x9a/0x13d0 kernel/sched/core.c:4252
       up+0x79/0xb0 kernel/locking/semaphore.c:191
       __up_console_sem kernel/printk/printk.c:340 [inline]
       __console_unlock kernel/printk/printk.c:2706 [inline]
       console_unlock+0x1cf/0x260 kernel/printk/printk.c:3038
       vprintk_emit+0x17f/0x5f0 kernel/printk/printk.c:2303
       vprintk+0x7b/0x90 kernel/printk/printk_safe.c:45
       _printk+0xc8/0x100 kernel/printk/printk.c:2328
       do_ip_vs_set_ctl+0xccd/0x11a0 net/netfilter/ipvs/ip_vs_ctl.c:2780
       nf_setsockopt+0x87/0xe0 net/netfilter/nf_sockopt.c:101
       ip_setsockopt+0xc7/0xe0 net/ipv4/ip_sockglue.c:1426
       udp_setsockopt+0x7d/0xc0 net/ipv4/udp.c:2788
       do_sock_setsockopt+0x222/0x470 net/socket.c:2311
       __sys_setsockopt+0x1a4/0x260 net/socket.c:2334
       __do_sys_setsockopt net/socket.c:2343 [inline]
       __se_sys_setsockopt net/socket.c:2340 [inline]
       __x64_sys_setsockopt+0xbd/0x150 net/socket.c:2340
       do_syscall_x64 arch/x86/entry/common.c:51 [inline]
       do_syscall_64+0x40/0x110 arch/x86/entry/common.c:82
       entry_SYSCALL_64_after_hwframe+0x62/0x6a

-> #0 ((console_sem).lock){-...}-{2:2}:
       check_prev_add kernel/locking/lockdep.c:3134 [inline]
       check_prevs_add kernel/locking/lockdep.c:3253 [inline]
       validate_chain kernel/locking/lockdep.c:3869 [inline]
       __lock_acquire+0x246e/0x3b30 kernel/locking/lockdep.c:5137
       lock_acquire kernel/locking/lockdep.c:5754 [inline]
       lock_acquire+0x1b1/0x530 kernel/locking/lockdep.c:5719
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0x3a/0x50 kernel/locking/spinlock.c:162
       down_trylock+0x12/0x70 kernel/locking/semaphore.c:139
       __down_trylock_console_sem+0x40/0x140 kernel/printk/printk.c:323
       console_trylock+0x73/0x130 kernel/printk/printk.c:2659
       console_trylock_spinning kernel/printk/printk.c:1923 [inline]
       vprintk_emit+0x162/0x5f0 kernel/printk/printk.c:2302
       vprintk+0x7b/0x90 kernel/printk/printk_safe.c:45
       _printk+0xc8/0x100 kernel/printk/printk.c:2328
       __warn_printk+0x158/0x350 kernel/panic.c:721
       refcount_warn_saturate+0x149/0x210 lib/refcount.c:28
       __refcount_sub_and_test include/linux/refcount.h:283 [inline]
       __refcount_dec_and_test include/linux/refcount.h:315 [inline]
       refcount_dec_and_test include/linux/refcount.h:333 [inline]
       stack_depot_put lib/stackdepot.c:627 [inline]
       stack_depot_put+0xe4/0x110 lib/stackdepot.c:613
       qlink_free mm/kasan/quarantine.c:158 [inline]
       qlist_free_all+0x16d/0x1e0 mm/kasan/quarantine.c:194
       kasan_quarantine_reduce+0x18e/0x1d0 mm/kasan/quarantine.c:301
       __kasan_slab_alloc+0x65/0x90 mm/kasan/common.c:306
       kasan_slab_alloc include/linux/kasan.h:188 [inline]
       slab_post_alloc_hook mm/slub.c:3817 [inline]
       slab_alloc_node mm/slub.c:3864 [inline]
       __do_kmalloc_node mm/slub.c:3984 [inline]
       __kmalloc+0x1bd/0x440 mm/slub.c:3998
       kmalloc_array include/linux/slab.h:627 [inline]
       kcalloc include/linux/slab.h:658 [inline]
       ext4_find_extent+0x958/0xce0 fs/ext4/extents.c:914
       ext4_ext_map_blocks+0x27d/0x5b70 fs/ext4/extents.c:4101
       ext4_map_blocks+0x619/0x1770 fs/ext4/inode.c:621
       ext4_convert_unwritten_extents+0x2b7/0x4c0 fs/ext4/extents.c:4811
       ext4_convert_unwritten_io_end_vec+0x127/0x280 fs/ext4/extents.c:4850
       ext4_end_io_end fs/ext4/page-io.c:186 [inline]
       ext4_do_flush_completed_IO fs/ext4/page-io.c:259 [inline]
       ext4_end_io_rsv_work+0x2ca/0x690 fs/ext4/page-io.c:273
       process_one_work+0x8a4/0x15f0 kernel/workqueue.c:2633
       process_scheduled_works kernel/workqueue.c:2706 [inline]
       worker_thread+0x8b6/0x1290 kernel/workqueue.c:2787
       kthread+0x2c1/0x3a0 kernel/kthread.c:389
       ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
       ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:242

other info that might help us debug this:

Chain exists of:
  (console_sem).lock --> &rq->__lock --> pool_rwlock

 Possible unsafe locking scenario:

       CPU0                    CPU1
       ----                    ----
  lock(pool_rwlock);
                               lock(&rq->__lock);
                               lock(pool_rwlock);
  lock((console_sem).lock);

 *** DEADLOCK ***

6 locks held by kworker/u4:7/16248:
 #0: ffff888027009138 ((wq_completion)ext4-rsv-conversion){+.+.}-{0:0}, at: process_one_work+0x78c/0x15f0 kernel/workqueue.c:2608
 #1: ffffc9000322fd80 ((work_completion)(&ei->i_rsv_conversion_work)){+.+.}-{0:0}, at: process_one_work+0x7f6/0x15f0 kernel/workqueue.c:2609
 #2: ffff88802ab00950 (jbd2_handle){++++}-{0:0}, at: start_this_handle+0x10d7/0x15e0 fs/jbd2/transaction.c:463
 #3: ffff8880818caa88 (&ei->i_data_sem){++++}-{3:3}, at: ext4_map_blocks+0x5a7/0x1770 fs/ext4/inode.c:614
 #4: ffffffff8d345eb0 (remove_cache_srcu){.+.+}-{0:0}, at: srcu_lock_acquire include/linux/srcu.h:116 [inline]
 #4: ffffffff8d345eb0 (remove_cache_srcu){.+.+}-{0:0}, at: srcu_read_lock include/linux/srcu.h:215 [inline]
 #4: ffffffff8d345eb0 (remove_cache_srcu){.+.+}-{0:0}, at: kasan_quarantine_reduce+0x81/0x1d0 mm/kasan/quarantine.c:274
 #5: ffffffff8dc75078 (pool_rwlock){----}-{2:2}, at: stack_depot_put lib/stackdepot.c:621 [inline]
 #5: ffffffff8dc75078 (pool_rwlock){----}-{2:2}, at: stack_depot_put+0x24/0x110 lib/stackdepot.c:613

stack backtrace:
CPU: 1 PID: 16248 Comm: kworker/u4:7 Not tainted 6.7.0-rc4-next-20231207-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/10/2023
Workqueue: ext4-rsv-conversion ext4_end_io_rsv_work
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0xd9/0x1b0 lib/dump_stack.c:106
 check_noncircular+0x316/0x400 kernel/locking/lockdep.c:2187
 check_prev_add kernel/locking/lockdep.c:3134 [inline]
 check_prevs_add kernel/locking/lockdep.c:3253 [inline]
 validate_chain kernel/locking/lockdep.c:3869 [inline]
 __lock_acquire+0x246e/0x3b30 kernel/locking/lockdep.c:5137
 lock_acquire kernel/locking/lockdep.c:5754 [inline]
 lock_acquire+0x1b1/0x530 kernel/locking/lockdep.c:5719
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
 _raw_spin_lock_irqsave+0x3a/0x50 kernel/locking/spinlock.c:162
 down_trylock+0x12/0x70 kernel/locking/semaphore.c:139
 __down_trylock_console_sem+0x40/0x140 kernel/printk/printk.c:323
 console_trylock+0x73/0x130 kernel/printk/printk.c:2659
 console_trylock_spinning kernel/printk/printk.c:1923 [inline]
 vprintk_emit+0x162/0x5f0 kernel/printk/printk.c:2302
 vprintk+0x7b/0x90 kernel/printk/printk_safe.c:45
 _printk+0xc8/0x100 kernel/printk/printk.c:2328
 __warn_printk+0x158/0x350 kernel/panic.c:721
 refcount_warn_saturate+0x149/0x210 lib/refcount.c:28
 __refcount_sub_and_test include/linux/refcount.h:283 [inline]
 __refcount_dec_and_test include/linux/refcount.h:315 [inline]
 refcount_dec_and_test include/linux/refcount.h:333 [inline]
 stack_depot_put lib/stackdepot.c:627 [inline]
 stack_depot_put+0xe4/0x110 lib/stackdepot.c:613
 qlink_free mm/kasan/quarantine.c:158 [inline]
 qlist_free_all+0x16d/0x1e0 mm/kasan/quarantine.c:194
 kasan_quarantine_reduce+0x18e/0x1d0 mm/kasan/quarantine.c:301
 __kasan_slab_alloc+0x65/0x90 mm/kasan/common.c:306
 kasan_slab_alloc include/linux/kasan.h:188 [inline]
 slab_post_alloc_hook mm/slub.c:3817 [inline]
 slab_alloc_node mm/slub.c:3864 [inline]
 __do_kmalloc_node mm/slub.c:3984 [inline]
 __kmalloc+0x1bd/0x440 mm/slub.c:3998
 kmalloc_array include/linux/slab.h:627 [inline]
 kcalloc include/linux/slab.h:658 [inline]
 ext4_find_extent+0x958/0xce0 fs/ext4/extents.c:914
 ext4_ext_map_blocks+0x27d/0x5b70 fs/ext4/extents.c:4101
 ext4_map_blocks+0x619/0x1770 fs/ext4/inode.c:621
 ext4_convert_unwritten_extents+0x2b7/0x4c0 fs/ext4/extents.c:4811
 ext4_convert_unwritten_io_end_vec+0x127/0x280 fs/ext4/extents.c:4850
 ext4_end_io_end fs/ext4/page-io.c:186 [inline]
 ext4_do_flush_completed_IO fs/ext4/page-io.c:259 [inline]
 ext4_end_io_rsv_work+0x2ca/0x690 fs/ext4/page-io.c:273
 process_one_work+0x8a4/0x15f0 kernel/workqueue.c:2633
 process_scheduled_works kernel/workqueue.c:2706 [inline]
 worker_thread+0x8b6/0x1290 kernel/workqueue.c:2787
 kthread+0x2c1/0x3a0 kernel/kthread.c:389
 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>
refcount_t: underflow; use-after-free.
WARNING: CPU: 1 PID: 16248 at lib/refcount.c:28 refcount_warn_saturate+0x14a/0x210 lib/refcount.c:28
Modules linked in:
CPU: 1 PID: 16248 Comm: kworker/u4:7 Not tainted 6.7.0-rc4-next-20231207-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/10/2023
Workqueue: ext4-rsv-conversion ext4_end_io_rsv_work
RIP: 0010:refcount_warn_saturate+0x14a/0x210 lib/refcount.c:28
Code: ff 89 de e8 58 b6 22 fd 84 db 0f 85 66 ff ff ff e8 1b bb 22 fd c6 05 32 25 be 0a 01 90 48 c7 c7 00 ce 2e 8b e8 67 ac e8 fc 90 <0f> 0b 90 90 e9 43 ff ff ff e8 f8 ba 22 fd 0f b6 1d 0d 25 be 0a 31
RSP: 0018:ffffc9000322f550 EFLAGS: 00010086
RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffffffff814e05d9
RDX: ffff88803c393b80 RSI: ffffffff814e05e6 RDI: 0000000000000001
RBP: ffff88807966643c R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000001 R12: ffffed1003e57a28
R13: ffff888018b14dc0 R14: 0000000000000000 R15: ffff88801f2bd140
FS:  0000000000000000(0000) GS:ffff8880b9900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000002000f000 CR3: 0000000036783000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <TASK>
 __refcount_sub_and_test include/linux/refcount.h:283 [inline]
 __refcount_dec_and_test include/linux/refcount.h:315 [inline]
 refcount_dec_and_test include/linux/refcount.h:333 [inline]
 stack_depot_put lib/stackdepot.c:627 [inline]
 stack_depot_put+0xe4/0x110 lib/stackdepot.c:613
 qlink_free mm/kasan/quarantine.c:158 [inline]
 qlist_free_all+0x16d/0x1e0 mm/kasan/quarantine.c:194
 kasan_quarantine_reduce+0x18e/0x1d0 mm/kasan/quarantine.c:301
 __kasan_slab_alloc+0x65/0x90 mm/kasan/common.c:306
 kasan_slab_alloc include/linux/kasan.h:188 [inline]
 slab_post_alloc_hook mm/slub.c:3817 [inline]
 slab_alloc_node mm/slub.c:3864 [inline]
 __do_kmalloc_node mm/slub.c:3984 [inline]
 __kmalloc+0x1bd/0x440 mm/slub.c:3998
 kmalloc_array include/linux/slab.h:627 [inline]
 kcalloc include/linux/slab.h:658 [inline]
 ext4_find_extent+0x958/0xce0 fs/ext4/extents.c:914
 ext4_ext_map_blocks+0x27d/0x5b70 fs/ext4/extents.c:4101
 ext4_map_blocks+0x619/0x1770 fs/ext4/inode.c:621
 ext4_convert_unwritten_extents+0x2b7/0x4c0 fs/ext4/extents.c:4811
 ext4_convert_unwritten_io_end_vec+0x127/0x280 fs/ext4/extents.c:4850
 ext4_end_io_end fs/ext4/page-io.c:186 [inline]
 ext4_do_flush_completed_IO fs/ext4/page-io.c:259 [inline]
 ext4_end_io_rsv_work+0x2ca/0x690 fs/ext4/page-io.c:273
 process_one_work+0x8a4/0x15f0 kernel/workqueue.c:2633
 process_scheduled_works kernel/workqueue.c:2706 [inline]
 worker_thread+0x8b6/0x1290 kernel/workqueue.c:2787
 kthread+0x2c1/0x3a0 kernel/kthread.c:389
 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>