[32045.667455] run fstests generic/251 at 2023-07-25 05:48:03
[32046.035694] BTRFS: device fsid ec3e1159-917e-46d6-82d2-6d36aac552b0 devid 1 transid 2391857 /dev/mapper/vg0-lv0 scanned by mount (786466)
[32046.036880] BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
[32046.036886] BTRFS info (device dm-0): using free space tree
[32046.040758] BTRFS info (device dm-0): auto enabling async discard
[32046.236255] BTRFS: device fsid 04afdb7d-0f9e-463c-a0bd-5e5002176bac devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (786514)
[32046.265837] BTRFS info (device dm-9): using crc32c (crc32c-intel) checksum algorithm
[32046.265845] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[32046.265849] BTRFS info (device dm-9): use zstd compression, level 3
[32046.265852] BTRFS info (device dm-9): turning on async discard
[32046.265853] BTRFS info (device dm-9): disk space caching is enabled
[32046.275868] BTRFS info (device dm-9): checking UUID tree
[32498.754779] ======================================================
[32498.755003] WARNING: possible circular locking dependency detected
[32498.755228] 6.5.0-rc3+ #1250 Not tainted
[32498.755372] ------------------------------------------------------
[32498.755593] kswapd0/47 is trying to acquire lock:
[32498.755764] ffff9dc403202378 (btrfs_trans_num_writers){++++}-{0:0}, at: join_transaction+0x19f/0x550
[32498.756095]
but task is already holding lock:
[32498.756309] ffff9dc4732ee600 (sb_internal#2){++++}-{0:0}, at: btrfs_commit_inode_delayed_inode+0x5f/0x120
[32498.756648]
which lock already depends on the new lock.
[32498.756935]
the existing dependency chain (in reverse order) is:
[32498.757357]
-> #3 (sb_internal#2){++++}-{0:0}:
[32498.757573] start_transaction+0x401/0x730
[32498.757744] btrfs_commit_inode_delayed_inode+0x5f/0x120
[32498.757955] btrfs_evict_inode+0x273/0x3c0
[32498.758125] evict+0xcc/0x1d0
[32498.758262] inode_lru_isolate+0x14d/0x1e0
[32498.758432] __list_lru_walk_one+0xbe/0x1c0
[32498.758605] list_lru_walk_one+0x58/0x80
[32498.758768] prune_icache_sb+0x39/0x60
[32498.758926] super_cache_scan+0x161/0x1f0
[32498.759094] do_shrink_slab+0x163/0x340
[32498.759259] shrink_slab+0x1d7/0x2a0
[32498.759412] shrink_node+0x2eb/0x7b0
[32498.759562] balance_pgdat+0x344/0x780
[32498.759721] kswapd+0x205/0x410
[32498.759858] kthread+0xfc/0x130
[32498.760001] ret_from_fork+0x2d/0x50
[32498.760154] ret_from_fork_asm+0x1b/0x30
[32498.760325]
-> #2 (fs_reclaim){+.+.}-{0:0}:
[32498.760532] fs_reclaim_acquire+0xa5/0xe0
[32498.760698] kmem_cache_alloc_lru+0x4e/0x570
[32498.760873] btrfs_alloc_inode+0x2d/0x250
[32498.761041] alloc_inode+0x1a/0xc0
[32498.761185] iget5_locked+0xba/0x110
[32498.761495] btrfs_iget+0x43/0xe0
[32498.761637] create_reloc_inode+0x19d/0x370
[32498.761810] btrfs_relocate_block_group+0x1b7/0x410
[32498.762005] btrfs_relocate_chunk+0x4c/0x190
[32498.762180] btrfs_balance+0x8e7/0x11d0
[32498.762347] btrfs_ioctl+0x1d3d/0x2900
[32498.762504] __x64_sys_ioctl+0x8b/0xc0
[32498.762750] do_syscall_64+0x38/0x90
[32498.762902] entry_SYSCALL_64_after_hwframe+0x6e/0xd8
[32498.763104]
-> #1 (btrfs_trans_num_extwriters){++++}-{0:0}:
[32498.763361] join_transaction+0xdb/0x550
[32498.763525] start_transaction+0x24f/0x730
[32498.763695] btrfs_uuid_scan_kthread+0x2bc/0x3d0
[32498.763881] kthread+0xfc/0x130
[32498.764016] ret_from_fork+0x2d/0x50
[32498.764166] ret_from_fork_asm+0x1b/0x30
[32498.764333]
-> #0 (btrfs_trans_num_writers){++++}-{0:0}:
[32498.764609] __lock_acquire+0x1415/0x2190
[32498.764781] lock_acquire+0xc7/0x2b0
[32498.764931] join_transaction+0x1cd/0x550
[32498.765098] start_transaction+0x24f/0x730
[32498.765270] btrfs_commit_inode_delayed_inode+0x5f/0x120
[32498.765516] btrfs_evict_inode+0x273/0x3c0
[32498.765685] evict+0xcc/0x1d0
[32498.765816] inode_lru_isolate+0x14d/0x1e0
[32498.765986] __list_lru_walk_one+0xbe/0x1c0
[32498.766157] list_lru_walk_one+0x58/0x80
[32498.766323] prune_icache_sb+0x39/0x60
[32498.766482] super_cache_scan+0x161/0x1f0
[32498.766648] do_shrink_slab+0x163/0x340
[32498.766808] shrink_slab+0x1d7/0x2a0
[32498.766959] shrink_node+0x2eb/0x7b0
[32498.767109] balance_pgdat+0x344/0x780
[32498.767270] kswapd+0x205/0x410
[32498.767407] kthread+0xfc/0x130
[32498.767543] ret_from_fork+0x2d/0x50
[32498.767692] ret_from_fork_asm+0x1b/0x30
[32498.767856]
other info that might help us debug this:
[32498.768135] Chain exists of:
btrfs_trans_num_writers --> fs_reclaim --> sb_internal#2
[32498.768546] Possible unsafe locking scenario:
[32498.768759] CPU0 CPU1
[32498.768922] ---- ----
[32498.769087] rlock(sb_internal#2);
[32498.769221] lock(fs_reclaim);
[32498.769426] lock(sb_internal#2);
[32498.769638] rlock(btrfs_trans_num_writers);
[32498.769798]
*** DEADLOCK ***
[32498.770009] 4 locks held by kswapd0/47:
[32498.770149] #0: ffffffffa7e62280 (fs_reclaim){+.+.}-{0:0}, at: balance_pgdat+0x492/0x780
[32498.770443] #1: ffffffffa7e507d0 (shrinker_rwsem){++++}-{3:3}, at: shrink_slab+0x113/0x2a0
[32498.770738] #2: ffff9dc4732ee0e0 (&type->s_umount_key#45){++++}-{3:3}, at: super_cache_scan+0x38/0x1f0
[32498.771069] #3: ffff9dc4732ee600 (sb_internal#2){++++}-{0:0}, at: btrfs_commit_inode_delayed_inode+0x5f/0x120
[32498.771425]
stack backtrace:
[32498.771584] CPU: 1 PID: 47 Comm: kswapd0 Not tainted 6.5.0-rc3+ #1250
[32498.771813] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[32498.772115] Call Trace:
[32498.772212]
[32498.772296] dump_stack_lvl+0x58/0x90
[32498.772435] check_noncircular+0x101/0x110
[32498.772587] __lock_acquire+0x1415/0x2190
[32498.772735] lock_acquire+0xc7/0x2b0
[32498.772869] ? join_transaction+0x19f/0x550
[32498.773021] ? lock_release+0x134/0x270
[32498.773165] join_transaction+0x1cd/0x550
[32498.773315] ? join_transaction+0x19f/0x550
[32498.773468] start_transaction+0x24f/0x730
[32498.773621] btrfs_commit_inode_delayed_inode+0x5f/0x120
[32498.773813] btrfs_evict_inode+0x273/0x3c0
[32498.773963] ? lock_release+0x134/0x270
[32498.774106] ? __pfx_wake_bit_function+0x10/0x10
[32498.774278] evict+0xcc/0x1d0
[32498.774393] inode_lru_isolate+0x14d/0x1e0
[32498.774543] __list_lru_walk_one+0xbe/0x1c0
[32498.774697] ? __pfx_inode_lru_isolate+0x10/0x10
[32498.774867] ? __pfx_inode_lru_isolate+0x10/0x10
[32498.775034] list_lru_walk_one+0x58/0x80
[32498.775181] prune_icache_sb+0x39/0x60
[32498.775323] super_cache_scan+0x161/0x1f0
[32498.775470] do_shrink_slab+0x163/0x340
[32498.775612] ? lock_is_held_type+0xe2/0x140
[32498.775767] shrink_slab+0x1d7/0x2a0
[32498.775902] shrink_node+0x2eb/0x7b0
[32498.776037] balance_pgdat+0x344/0x780
[32498.776179] kswapd+0x205/0x410
[32498.776302] ? __pfx_autoremove_wake_function+0x10/0x10
[32498.776491] ? __pfx_kswapd+0x10/0x10
[32498.776628] kthread+0xfc/0x130
[32498.776747] ? __pfx_kthread+0x10/0x10
[32498.776886] ret_from_fork+0x2d/0x50
[32498.777019] ? __pfx_kthread+0x10/0x10
[32498.777158] ret_from_fork_asm+0x1b/0x30
[32498.777305] RIP: 0000:0x0
[32498.777414] Code: Unable to access opcode bytes at 0xffffffffffffffd6.
[32498.777646] RSP: 0000:0000000000000000 EFLAGS: 00000000 ORIG_RAX: 0000000000000000
[32498.777914] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
[32498.778168] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
[32498.778423] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[32498.778676] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
[32498.778929] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[32498.779183]
[32817.280898] BTRFS: device fsid 04afdb7d-0f9e-463c-a0bd-5e5002176bac devid 1 transid 70 /dev/mapper/vg0-lv9 scanned by mount (850500)
[32817.282404] BTRFS info (device dm-9): using crc32c (crc32c-intel) checksum algorithm
[32817.282708] BTRFS info (device dm-9): use zstd compression, level 3
[32817.282934] BTRFS info (device dm-9): turning on async discard
[32817.283142] BTRFS info (device dm-9): disk space caching is enabled