------------[ cut here ]------------
======================================================
WARNING: possible circular locking dependency detected
6.7.0-rc4-next-20231207-syzkaller #0 Not tainted
------------------------------------------------------
jbd2/sda1-8/4481 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
       check_kcov_mode kernel/kcov.c:173 [inline]
       __sanitizer_cov_trace_pc+0x18/0x60 kernel/kcov.c:207
       __orc_find+0x42/0x130 arch/x86/kernel/unwind_orc.c:99
       orc_find arch/x86/kernel/unwind_orc.c:227 [inline]
       unwind_next_frame+0x329/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_slab_alloc+0x81/0x90 mm/kasan/common.c:329
       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]
       kmem_cache_alloc+0x136/0x320 mm/slub.c:3871
       mempool_alloc+0x176/0x390 mm/mempool.c:398
       bio_alloc_bioset+0x47c/0x8b0 block/bio.c:543
       bio_alloc include/linux/bio.h:434 [inline]
       ext4_mpage_readpages+0x11c2/0x1a10 fs/ext4/readpage.c:354
       ext4_readahead+0x102/0x140 fs/ext4/inode.c:3148
       read_pages+0x1d1/0xdc0 mm/readahead.c:160
       page_cache_ra_unbounded+0x455/0x5e0 mm/readahead.c:269
       do_page_cache_ra mm/readahead.c:299 [inline]
       page_cache_ra_order+0x7d7/0xaa0 mm/readahead.c:544
       ondemand_readahead+0x493/0x1130 mm/readahead.c:666
       page_cache_async_ra mm/readahead.c:715 [inline]
       page_cache_async_ra+0x123/0x150 mm/readahead.c:697
       filemap_readahead mm/filemap.c:2472 [inline]
       filemap_get_pages+0x359/0x1830 mm/filemap.c:2513
       filemap_read+0x39b/0xcf0 mm/filemap.c:2593
       generic_file_read_iter+0x346/0x450 mm/filemap.c:2772
       ext4_file_read_iter+0x1dc/0x6c0 fs/ext4/file.c:147
       __kernel_read+0x300/0x870 fs/read_write.c:428
       integrity_kernel_read+0x7f/0xb0 security/integrity/iint.c:221
       ima_calc_file_hash_tfm+0x2c5/0x3d0 security/integrity/ima/ima_crypto.c:485
       ima_calc_file_shash security/integrity/ima/ima_crypto.c:516 [inline]
       ima_calc_file_hash+0x1c6/0x4a0 security/integrity/ima/ima_crypto.c:573
       ima_collect_measurement+0x85e/0xa20 security/integrity/ima/ima_api.c:290
       process_measurement+0xe92/0x2260 security/integrity/ima/ima_main.c:359
       ima_file_check+0xc1/0x100 security/integrity/ima/ima_main.c:557
       do_open fs/namei.c:3633 [inline]
       path_openat+0x17a3/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
       kauditd_printk_skb kernel/audit.c:546 [inline]
       kauditd_hold_skb+0x1fb/0x240 kernel/audit.c:581
       kauditd_send_queue+0x229/0x280 kernel/audit.c:766
       kauditd_thread+0x61e/0xa80 kernel/audit.c:890
       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

-> #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
       __kasan_record_aux_stack+0xb3/0xd0 mm/kasan/generic.c:515
       insert_work+0x38/0x230 kernel/workqueue.c:1653
       __queue_work+0x633/0x11d0 kernel/workqueue.c:1802
       __queue_delayed_work+0x1bf/0x270 kernel/workqueue.c:1953
       mod_delayed_work_on+0xcc/0x1a0 kernel/workqueue.c:2027
       kblockd_mod_delayed_work_on+0x29/0x40 block/blk-core.c:1046
       blk_kick_flush block/blk-flush.c:348 [inline]
       blk_flush_complete_seq+0xa66/0x10c0 block/blk-flush.c:213
       blk_insert_flush+0x349/0x6d0 block/blk-flush.c:456
       blk_mq_submit_bio+0x16cd/0x2150 block/blk-mq.c:3005
       __submit_bio+0xfd/0x310 block/blk-core.c:607
       __submit_bio_noacct_mq block/blk-core.c:686 [inline]
       submit_bio_noacct_nocheck+0x852/0xbb0 block/blk-core.c:715
       submit_bio_noacct+0x87b/0x1b90 block/blk-core.c:809
       journal_submit_commit_record+0x73b/0xab0 fs/jbd2/commit.c:156
       jbd2_journal_commit_transaction+0x39fa/0x63c0 fs/jbd2/commit.c:881
       kjournald2+0x1f8/0x8f0 fs/jbd2/journal.c:201
       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 ***

4 locks held by jbd2/sda1-8/4481:
 #0: ffff88801f0c9518 (&fq->mq_flush_lock){..-.}-{2:2}, at: spin_lock_irq include/linux/spinlock.h:376 [inline]
 #0: ffff88801f0c9518 (&fq->mq_flush_lock){..-.}-{2:2}, at: blk_insert_flush+0x337/0x6d0 block/blk-flush.c:455
 #1: ffffffff8d1ab9a0 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire include/linux/rcupdate.h:298 [inline]
 #1: ffffffff8d1ab9a0 (rcu_read_lock){....}-{1:2}, at: rcu_read_lock include/linux/rcupdate.h:744 [inline]
 #1: ffffffff8d1ab9a0 (rcu_read_lock){....}-{1:2}, at: __queue_work+0xf2/0x11d0 kernel/workqueue.c:1730
 #2: ffff8880b983c2f8 (&pool->lock){-.-.}-{2:2}, at: __queue_work+0x38e/0x11d0 kernel/workqueue.c:1763
 #3: ffffffff8dc75078 (pool_rwlock){----}-{2:2}, at: stack_depot_put lib/stackdepot.c:621 [inline]
 #3: ffffffff8dc75078 (pool_rwlock){----}-{2:2}, at: stack_depot_put+0x24/0x110 lib/stackdepot.c:613

stack backtrace:
CPU: 0 PID: 4481 Comm: jbd2/sda1-8 Not tainted 6.7.0-rc4-next-20231207-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/10/2023
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
 __kasan_record_aux_stack+0xb3/0xd0 mm/kasan/generic.c:515
 insert_work+0x38/0x230 kernel/workqueue.c:1653
 __queue_work+0x633/0x11d0 kernel/workqueue.c:1802
 __queue_delayed_work+0x1bf/0x270 kernel/workqueue.c:1953
 mod_delayed_work_on+0xcc/0x1a0 kernel/workqueue.c:2027
 kblockd_mod_delayed_work_on+0x29/0x40 block/blk-core.c:1046
 blk_kick_flush block/blk-flush.c:348 [inline]
 blk_flush_complete_seq+0xa66/0x10c0 block/blk-flush.c:213
 blk_insert_flush+0x349/0x6d0 block/blk-flush.c:456
 blk_mq_submit_bio+0x16cd/0x2150 block/blk-mq.c:3005
 __submit_bio+0xfd/0x310 block/blk-core.c:607
 __submit_bio_noacct_mq block/blk-core.c:686 [inline]
 submit_bio_noacct_nocheck+0x852/0xbb0 block/blk-core.c:715
 submit_bio_noacct+0x87b/0x1b90 block/blk-core.c:809
 journal_submit_commit_record+0x73b/0xab0 fs/jbd2/commit.c:156
 jbd2_journal_commit_transaction+0x39fa/0x63c0 fs/jbd2/commit.c:881
 kjournald2+0x1f8/0x8f0 fs/jbd2/journal.c:201
 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: 0 PID: 4481 at lib/refcount.c:28 refcount_warn_saturate+0x14a/0x210 lib/refcount.c:28
Modules linked in:
CPU: 0 PID: 4481 Comm: jbd2/sda1-8 Not tainted 6.7.0-rc4-next-20231207-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/10/2023
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:ffffc9000362f300 EFLAGS: 00010082
RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffffffff814e05d9
RDX: ffff88802abf3b80 RSI: ffffffff814e05e6 RDI: 0000000000000001
RBP: ffff8880202cf31c R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000001 R12: ffff8880182ff8c0
R13: ffff888019289000 R14: ffff88801928905c R15: ffff8880b983c340
FS:  0000000000000000(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000555556c608f8 CR3: 000000002e887000 CR4: 00000000003506f0
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
 __kasan_record_aux_stack+0xb3/0xd0 mm/kasan/generic.c:515
 insert_work+0x38/0x230 kernel/workqueue.c:1653
 __queue_work+0x633/0x11d0 kernel/workqueue.c:1802
 __queue_delayed_work+0x1bf/0x270 kernel/workqueue.c:1953
 mod_delayed_work_on+0xcc/0x1a0 kernel/workqueue.c:2027
 kblockd_mod_delayed_work_on+0x29/0x40 block/blk-core.c:1046
 blk_kick_flush block/blk-flush.c:348 [inline]
 blk_flush_complete_seq+0xa66/0x10c0 block/blk-flush.c:213
 blk_insert_flush+0x349/0x6d0 block/blk-flush.c:456
 blk_mq_submit_bio+0x16cd/0x2150 block/blk-mq.c:3005
 __submit_bio+0xfd/0x310 block/blk-core.c:607
 __submit_bio_noacct_mq block/blk-core.c:686 [inline]
 submit_bio_noacct_nocheck+0x852/0xbb0 block/blk-core.c:715
 submit_bio_noacct+0x87b/0x1b90 block/blk-core.c:809
 journal_submit_commit_record+0x73b/0xab0 fs/jbd2/commit.c:156
 jbd2_journal_commit_transaction+0x39fa/0x63c0 fs/jbd2/commit.c:881
 kjournald2+0x1f8/0x8f0 fs/jbd2/journal.c:201
 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>