[51146.777071] run fstests generic/299 at 2022-09-18 11:04:51
[51147.285014] BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
[51147.285064] BTRFS info (device dm-0): using free space tree
[51150.938749] BTRFS: device fsid 58a4ac11-20c9-4a7b-8bb3-b6e3b5bca38c devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3628087)
[51150.981313] BTRFS info (device dm-9): using crc32c (crc32c-intel) checksum algorithm
[51150.981322] BTRFS info (device dm-9): using free space tree
[51150.987238] BTRFS info (device dm-9): checking UUID tree
[51167.645325] ------------[ cut here ]------------
[51167.645329] BTRFS: Transaction aborted (error -28)
[51167.645351] WARNING: CPU: 0 PID: 3606186 at fs/btrfs/free-space-tree.c:859 remove_from_free_space_tree+0xfc/0x140
[51167.645362] 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 rfkill ip_set nf_tables nfnetlink intel_rapl_msr iTCO_wdt iTCO_vendor_support snd_hda_codec_generic snd_hda_intel snd_intel_dspcfg snd_hda_codec intel_rapl_common snd_hwdep i2c_i801 i2c_smbus snd_hda_core lpc_ich joydev virtio_balloon snd_pcm snd_timer snd soundcore zram ip_tables xfs crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel serio_raw qemu_fw_cfg
[51167.645433] CPU: 0 PID: 3606186 Comm: kworker/u4:12 Not tainted 6.0.0-rc5+ #840
[51167.645436] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[51167.645440] Workqueue: events_unbound btrfs_async_reclaim_metadata_space
[51167.645446] RIP: 0010:remove_from_free_space_tree+0xfc/0x140
[51167.645449] 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 18 d3 87 b4 e8 5b a2 79 00 <0f> 0b 41 89 d8 44 89 e1 ba 5b 03 00 00 48 89 ef 41 83 e0 01 48 c7
[51167.645451] RSP: 0018:ffffb40c066ebbd0 EFLAGS: 00010286
[51167.645454] RAX: 0000000000000026 RBX: 0000000000004001 RCX: 0000000000000000
[51167.645456] RDX: 0000000000000001 RSI: ffffffffb489ccbe RDI: 00000000ffffffff
[51167.645458] RBP: ffff964115175208 R08: 0000000000000000 R09: 0000000100011ed3
[51167.645460] R10: ffffb40c066eba88 R11: ffffffffb4d72ea8 R12: 00000000ffffffe4
[51167.645461] R13: ffff964050b26380 R14: ffff964107f9f800 R15: ffff964107f9fa88
[51167.645463] FS:  0000000000000000(0000) GS:ffff96417bc00000(0000) knlGS:0000000000000000
[51167.645465] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[51167.645467] CR2: 0000561734379048 CR3: 0000000164a3e005 CR4: 0000000000370ef0
[51167.645472] Call Trace:
[51167.645475]  
[51167.645482]  alloc_reserved_extent+0x20/0x120
[51167.645489]  __btrfs_run_delayed_refs+0xa64/0x1250
[51167.645508]  btrfs_run_delayed_refs+0x8f/0x240
[51167.645515]  flush_space+0x3d5/0x640
[51167.645518]  ? find_held_lock+0x2b/0x80
[51167.645524]  ? lock_release+0x137/0x2d0
[51167.645527]  ? _raw_spin_unlock+0x29/0x50
[51167.645535]  btrfs_async_reclaim_metadata_space+0x159/0x360
[51167.645542]  process_one_work+0x271/0x590
[51167.645552]  ? process_one_work+0x590/0x590
[51167.645556]  worker_thread+0x52/0x3b0
[51167.645561]  ? process_one_work+0x590/0x590
[51167.645564]  kthread+0xf0/0x120
[51167.645567]  ? kthread_complete_and_exit+0x20/0x20
[51167.645572]  ret_from_fork+0x1f/0x30
[51167.645588]  
[51167.645589] irq event stamp: 1655913
[51167.645591] hardirqs last  enabled at (1655919): [] __up_console_sem+0x5e/0x70
[51167.645594] hardirqs last disabled at (1655924): [] __up_console_sem+0x43/0x70
[51167.645597] softirqs last  enabled at (1654968): [] __irq_exit_rcu+0xfd/0x150
[51167.645600] softirqs last disabled at (1654923): [] __irq_exit_rcu+0xfd/0x150
[51167.645603] ---[ end trace 0000000000000000 ]---
[51167.645610] BTRFS info (device dm-9: state A): dumping space info:
[51167.645613] BTRFS info (device dm-9: state A): space_info DATA has 6444019712 free, is full
[51167.645940] BTRFS info (device dm-9: state A): space_info total=10182721536, used=3074920448, pinned=656408576, reserved=3706880, may_use=3665920, readonly=0 zone_unusable=0
[51167.645944] BTRFS info (device dm-9: state A): space_info METADATA has 540672 free, is not full
[51167.645946] BTRFS info (device dm-9: state A): space_info total=268435456, used=11108352, pinned=47497216, reserved=32768, may_use=209190912, readonly=65536 zone_unusable=0
[51167.645949] BTRFS info (device dm-9: state A): space_info SYSTEM has 8372224 free, is not full
[51167.645952] 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
[51167.645955] BTRFS info (device dm-9: state A): global_block_rsv: size 3670016 reserved 0
[51167.646055] BTRFS info (device dm-9: state A): trans_block_rsv: size 0 reserved 0
[51167.646058] BTRFS info (device dm-9: state A): chunk_block_rsv: size 0 reserved 0
[51167.646060] BTRFS info (device dm-9: state A): delayed_block_rsv: size 0 reserved 0
[51167.646062] BTRFS info (device dm-9: state A): delayed_refs_rsv: size 66584576 reserved 0
[51167.646065] BTRFS: error (device dm-9: state A) in remove_from_free_space_tree:859: errno=-28 No space left
[51167.646422] BTRFS info (device dm-9: state EA): forced readonly
[51167.649715] BTRFS: error (device dm-9: state EA) in btrfs_run_delayed_refs:2141: errno=-28 No space left
[51171.982341] BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
[51171.982350] BTRFS info (device dm-0): using free space tree
[51172.838553] BTRFS info (device dm-9): using crc32c (crc32c-intel) checksum algorithm
[51172.838561] BTRFS info (device dm-9): using free space tree