[51769.364777] run fstests generic/299 at 2022-09-25 11:15:16
[51769.849549] BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
[51769.849558] BTRFS info (device dm-0): using free space tree
[51773.540380] BTRFS: device fsid aab36773-60a6-4592-b752-0b32461bd635 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3596716)
[51773.595248] BTRFS info (device dm-9): using crc32c (crc32c-intel) checksum algorithm
[51773.595258] BTRFS info (device dm-9): using free space tree
[51773.604197] BTRFS info (device dm-9): checking UUID tree
[51790.818484] ------------[ cut here ]------------
[51790.818689] BTRFS: Transaction aborted (error -28)
[51790.818712] WARNING: CPU: 0 PID: 3552544 at fs/btrfs/free-space-tree.c:859 remove_from_free_space_tree+0xfc/0x140
[51790.818734] Modules linked in: dm_thin_pool dm_persistent_data dm_bio_prison dm_log_writes dm_dust dm_flakey nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject nft_ct nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip6_tables nft_compat ip_set rfkill nf_tables nfnetlink snd_hda_codec_generic intel_rapl_msr iTCO_wdt iTCO_vendor_support snd_hda_intel snd_intel_dspcfg snd_hda_codec intel_rapl_common joydev snd_hwdep snd_hda_core snd_pcm snd_timer virtio_balloon snd soundcore i2c_i801 lpc_ich i2c_smbus zram ip_tables xfs crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel serio_raw qemu_fw_cfg
[51790.818805] CPU: 0 PID: 3552544 Comm: kworker/u4:10 Not tainted 6.0.0-rc6+ #847
[51790.818808] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[51790.818812] Workqueue: events_unbound btrfs_async_reclaim_metadata_space
[51790.818819] RIP: 0010:remove_from_free_space_tree+0xfc/0x140
[51790.818822] Code: 41 83 fc fb 0f 95 c3 41 83 fc e2 0f 95 c0 20 c3 75 09 66 90 bb 01 00 00 00 eb 11 44 89 e6 48 c7 c7 f8 d3 87 b4 e8 2b ab 79 00 <0f> 0b 41 89 d8 44 89 e1 ba 5b 03 00 00 48 89 ef 41 83 e0 01 48 c7
[51790.818825] RSP: 0018:ffffa86149237bd0 EFLAGS: 00010286
[51790.818828] RAX: 0000000000000026 RBX: 0000000000004001 RCX: 0000000000000000
[51790.818829] RDX: 0000000000000001 RSI: ffffffffb489ce9e RDI: 00000000ffffffff
[51790.818831] RBP: ffff9a2ed6e885b0 R08: 0000000000000000 R09: 0000000100011e3b
[51790.818833] R10: ffffa86149237a88 R11: ffffffffb4d72068 R12: 00000000ffffffe4
[51790.818834] R13: ffff9a2ffd7ac850 R14: ffff9a2fd31a8000 R15: ffff9a2fd31a8280
[51790.818836] FS:  0000000000000000(0000) GS:ffff9a303bc00000(0000) knlGS:0000000000000000
[51790.818838] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[51790.818840] CR2: 00007fec4b51dd30 CR3: 00000001026f2004 CR4: 0000000000370ef0
[51790.818846] Call Trace:
[51790.818849]  
[51790.818856]  alloc_reserved_extent+0x20/0x120
[51790.818866]  __btrfs_run_delayed_refs+0xa64/0x1250
[51790.818885]  btrfs_run_delayed_refs+0x8f/0x240
[51790.818897]  flush_space+0x3d5/0x640
[51790.818901]  ? find_held_lock+0x2b/0x80
[51790.818913]  ? lock_release+0x137/0x2d0
[51790.818916]  ? _raw_spin_unlock+0x29/0x50
[51790.818926]  btrfs_async_reclaim_metadata_space+0x159/0x360
[51790.818934]  process_one_work+0x271/0x590
[51790.818948]  ? process_one_work+0x590/0x590
[51790.818951]  worker_thread+0x52/0x3b0
[51790.818957]  ? process_one_work+0x590/0x590
[51790.818960]  kthread+0xf0/0x120
[51790.818965]  ? kthread_complete_and_exit+0x20/0x20
[51790.818970]  ret_from_fork+0x1f/0x30
[51790.818987]  
[51790.818989] irq event stamp: 13475971
[51790.818990] hardirqs last  enabled at (13475977): [] __up_console_sem+0x5e/0x70
[51790.818996] hardirqs last disabled at (13475982): [] __up_console_sem+0x43/0x70
[51790.818998] softirqs last  enabled at (13475296): [] __irq_exit_rcu+0xfd/0x150
[51790.819003] softirqs last disabled at (13475249): [] __irq_exit_rcu+0xfd/0x150
[51790.819006] ---[ end trace 0000000000000000 ]---
[51790.819011] BTRFS info (device dm-9: state A): dumping space info:
[51790.819014] BTRFS info (device dm-9: state A): space_info DATA has 6656188416 free, is full
[51790.819179] BTRFS info (device dm-9: state A): space_info total=10182721536, used=2580000768, pinned=937820160, reserved=5292032, may_use=3420160, readonly=0 zone_unusable=0
[51790.819183] BTRFS info (device dm-9: state A): space_info METADATA has 360448 free, is not full
[51790.819185] BTRFS info (device dm-9: state A): space_info total=268435456, used=10551296, pinned=63569920, reserved=32768, may_use=193855488, readonly=65536 zone_unusable=0
[51790.819189] BTRFS info (device dm-9: state A): space_info SYSTEM has 8372224 free, is not full
[51790.819191] BTRFS info (device dm-9: state A): space_info total=8388608, used=16384, pinned=0, reserved=0, may_use=0, readonly=0 zone_unusable=0
[51790.819194] BTRFS info (device dm-9: state A): global_block_rsv: size 3670016 reserved 0
[51790.819309] BTRFS info (device dm-9: state A): trans_block_rsv: size 0 reserved 0
[51790.819312] BTRFS info (device dm-9: state A): chunk_block_rsv: size 0 reserved 0
[51790.819314] BTRFS info (device dm-9: state A): delayed_block_rsv: size 131072 reserved 131072
[51790.819316] BTRFS info (device dm-9: state A): delayed_refs_rsv: size 96993280 reserved 0
[51790.819424] BTRFS: error (device dm-9: state A) in remove_from_free_space_tree:859: errno=-28 No space left
[51790.819972] BTRFS info (device dm-9: state EA): forced readonly
[51790.820001] BTRFS: error (device dm-9: state EA) in btrfs_run_delayed_refs:2141: errno=-28 No space left
[51795.280523] BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
[51795.280534] BTRFS info (device dm-0): using free space tree
[51796.073558] BTRFS info (device dm-9): using crc32c (crc32c-intel) checksum algorithm
[51796.073567] BTRFS info (device dm-9): using free space tree