[34397.220573] run fstests generic/416 at 2022-02-26 06:24:14
[34397.491996] BTRFS info (device dm-0): flagging fs with big metadata feature
[34397.492434] BTRFS info (device dm-0): using free space tree
[34397.492781] BTRFS info (device dm-0): has skinny extents
[34397.647360] BTRFS: device fsid 92ceb12f-3dff-4cea-97ab-40c03b749ee2 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (13525)
[34397.658873] BTRFS info (device dm-9): using free space tree
[34397.659239] BTRFS info (device dm-9): has skinny extents
[34397.661108] BTRFS info (device dm-9): checking UUID tree
[34484.069296] BTRFS info (device dm-9): space_info 5 has 643072 free, is full
[34484.069726] BTRFS info (device dm-9): space_info total=128974848, used=126562304, pinned=438272, reserved=94208, may_use=1171456, readonly=65536 zone_unusable=0
[34484.070593] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1138688
[34484.071128] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.071539] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.071959] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.072414] BTRFS info (device dm-9): delayed_refs_rsv: size 3801088 reserved 0
[34484.100892] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.101334] BTRFS info (device dm-9): space_info total=128974848, used=126660608, pinned=274432, reserved=163840, may_use=1171456, readonly=65536 zone_unusable=0
[34484.102218] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1138688
[34484.102676] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.103098] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.103494] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.103942] BTRFS info (device dm-9): delayed_refs_rsv: size 11665408 reserved 0
[34484.104405] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.104821] BTRFS info (device dm-9): space_info total=128974848, used=126660608, pinned=274432, reserved=167936, may_use=1167360, readonly=65536 zone_unusable=0
[34484.105669] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1134592
[34484.106154] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.106548] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.106945] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.107401] BTRFS info (device dm-9): delayed_refs_rsv: size 11796480 reserved 0
[34484.107857] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.108277] BTRFS info (device dm-9): space_info total=128974848, used=126660608, pinned=274432, reserved=172032, may_use=1163264, readonly=65536 zone_unusable=0
[34484.109129] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1130496
[34484.109585] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.109992] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.110383] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.110825] BTRFS info (device dm-9): delayed_refs_rsv: size 11796480 reserved 0
[34484.111393] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.111830] BTRFS info (device dm-9): space_info total=128974848, used=126631936, pinned=323584, reserved=155648, may_use=1159168, readonly=65536 zone_unusable=0
[34484.112678] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1126400
[34484.113149] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.113547] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.113948] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.114404] BTRFS info (device dm-9): delayed_refs_rsv: size 9699328 reserved 0
[34484.115006] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.115416] BTRFS info (device dm-9): space_info total=128974848, used=126644224, pinned=352256, reserved=118784, may_use=1155072, readonly=65536 zone_unusable=0
[34484.116283] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1122304
[34484.116743] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.117158] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.117553] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.118010] BTRFS info (device dm-9): delayed_refs_rsv: size 7733248 reserved 0
[34484.118667] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.119091] BTRFS info (device dm-9): space_info total=128974848, used=126726144, pinned=376832, reserved=16384, may_use=1150976, readonly=65536 zone_unusable=0
[34484.119925] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1118208
[34484.120396] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.120792] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.121197] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.121651] BTRFS info (device dm-9): delayed_refs_rsv: size 3801088 reserved 0
[34484.122235] BTRFS info (device dm-9): space_info 5 has 638976 free, is full
[34484.122644] BTRFS info (device dm-9): space_info total=128974848, used=126664704, pinned=446464, reserved=12288, may_use=1146880, readonly=65536 zone_unusable=0
[34484.123484] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1114112
[34484.123946] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.124351] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.124746] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.125203] BTRFS info (device dm-9): delayed_refs_rsv: size 1572864 reserved 0
[34484.138157] BTRFS info (device dm-9): space_info 5 has 630784 free, is full
[34484.138581] BTRFS info (device dm-9): space_info total=128974848, used=126656512, pinned=352256, reserved=98304, may_use=1171456, readonly=65536 zone_unusable=0
[34484.139445] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1138688
[34484.139912] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.140328] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.140728] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.141190] BTRFS info (device dm-9): delayed_refs_rsv: size 6684672 reserved 0
[34484.141779] BTRFS info (device dm-9): space_info 5 has 630784 free, is full
[34484.142214] BTRFS info (device dm-9): space_info total=128974848, used=126599168, pinned=425984, reserved=86016, may_use=1167360, readonly=65536 zone_unusable=0
[34484.143062] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1134592
[34484.143520] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.143923] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.144328] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.144777] BTRFS info (device dm-9): delayed_refs_rsv: size 4063232 reserved 0
[34484.159249] BTRFS info (device dm-9): space_info 5 has 626688 free, is full
[34484.159669] BTRFS info (device dm-9): space_info total=128974848, used=126636032, pinned=372736, reserved=102400, may_use=1171456, readonly=65536 zone_unusable=0
[34484.160534] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1138688
[34484.161017] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.161412] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.161812] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.162269] BTRFS info (device dm-9): delayed_refs_rsv: size 6160384 reserved 0
[34484.176127] BTRFS info (device dm-9): space_info 5 has 622592 free, is full
[34484.176551] BTRFS info (device dm-9): space_info total=128974848, used=126668800, pinned=323584, reserved=122880, may_use=1171456, readonly=65536 zone_unusable=0
[34484.177414] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1138688
[34484.177881] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.178289] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.178685] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.179145] BTRFS info (device dm-9): delayed_refs_rsv: size 8388608 reserved 0
[34484.179685] BTRFS info (device dm-9): space_info 5 has 622592 free, is full
[34484.180117] BTRFS info (device dm-9): space_info total=128974848, used=126697472, pinned=339968, reserved=81920, may_use=1167360, readonly=65536 zone_unusable=0
[34484.180958] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1134592
[34484.181429] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.181828] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.182233] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.182676] BTRFS info (device dm-9): delayed_refs_rsv: size 6684672 reserved 0
[34484.183131] BTRFS info (device dm-9): space_info 5 has 622592 free, is full
[34484.183541] BTRFS info (device dm-9): space_info total=128974848, used=126697472, pinned=339968, reserved=86016, may_use=1163264, readonly=65536 zone_unusable=0
[34484.184388] BTRFS info (device dm-9): global_block_rsv: size 1138688 reserved 1130496
[34484.184854] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[34484.185261] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[34484.185657] BTRFS info (device dm-9): delayed_block_rsv: size 32768 reserved 32768
[34484.186111] BTRFS info (device dm-9): delayed_refs_rsv: size 6946816 reserved 0
[34484.198885] BTRFS info (device dm-9): space_info 5 has 618496 free, is full
[34484.199322] BTRFS info (device dm-9): space_info total=128974848, used=126648320, pinned=282624, reserved=188416, may_use=1171456, readonly=65536 zone_unusable=0
[34489.816222] ------------[ cut here ]------------
[34489.816517] BTRFS: block rsv 1 returned -28
[34489.816790] WARNING: CPU: 1 PID: 3579835 at fs/btrfs/block-rsv.c:537 btrfs_use_block_rsv+0x18d/0x230
[34489.817352] 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 intel_rapl_msr iTCO_wdt iTCO_vendor_support intel_rapl_common snd_hda_codec_generic i2c_i801 i2c_smbus joydev snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hwdep virtio_balloon snd_hda_core snd_pcm snd_timer snd lpc_ich soundcore zram ip_tables xfs crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel serio_raw virtio_blk qemu_fw_cfg
[34489.820848] CPU: 1 PID: 3579835 Comm: kworker/u4:5 Not tainted 5.17.0-rc5+ #581
[34489.821287] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[34489.821790] Workqueue: btrfs-endio-write btrfs_work_helper
[34489.822128] RIP: 0010:btrfs_use_block_rsv+0x18d/0x230
[34489.822430] Code: c7 c7 60 32 cf a0 e8 22 31 19 00 85 c0 0f 84 38 ff ff ff 41 0f b7 74 24 5a ba e4 ff ff ff 48 c7 c7 f8 1c 86 a0 e8 6c e8 73 00 <0f> 0b e9 1a ff ff ff 4c 89 f7 e8 54 ac 7c 00 4c 89 e0 5b 5d 41 5c
[34489.823557] RSP: 0018:ffffa76e447f39b0 EFLAGS: 00010282
[34489.823870] RAX: 000000000000001f RBX: 0000000000001000 RCX: 0000000000000000
[34489.824299] RDX: 0000000000000001 RSI: ffffffffa0871842 RDI: 00000000ffffffff
[34489.824723] RBP: ffff889dc35b8000 R08: 0000000000000000 R09: ffffa76e447f37f0
[34489.825153] R10: ffffa76e447f37e8 R11: ffffffffa0b63b80 R12: ffff889e1bd50cd8
[34489.825571] R13: ffff889dc35b8258 R14: ffff889e1bd50cf0 R15: 0000000000001000
[34489.826004] FS: 0000000000000000(0000) GS:ffff889e3bd00000(0000) knlGS:0000000000000000
[34489.826479] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[34489.826823] CR2: 00007ffdb4c2fdf0 CR3: 0000000110696005 CR4: 0000000000370ee0
[34489.827258] Call Trace:
[34489.827813]
[34489.827969] btrfs_alloc_tree_block+0x80/0x3b0
[34489.828356] ? lock_release+0x1f0/0x2d0
[34489.828592] __btrfs_cow_block+0x136/0x600
[34489.829098] btrfs_cow_block+0x10b/0x230
[34489.829342] btrfs_search_slot+0x53b/0xb70
[34489.829587] btrfs_lookup_csum+0x4c/0x120
[34489.829838] btrfs_csum_file_blocks+0x15f/0x680
[34489.830257] ? rcu_read_lock_sched_held+0x12/0x60
[34489.830541] ? _raw_read_unlock+0x29/0x40
[34489.830788] ? btrfs_global_root+0x50/0x60
[34489.831047] btrfs_finish_ordered_io.isra.0+0x40f/0x950
[34489.831361] btrfs_work_helper+0xf2/0x3e0
[34489.831742] ? lock_release+0x1f0/0x2d0
[34489.831989] process_one_work+0x26d/0x580
[34489.832236] worker_thread+0x55/0x3b0
[34489.832456] ? process_one_work+0x580/0x580
[34489.832710] kthread+0xf0/0x120
[34489.832900] ? kthread_complete_and_exit+0x20/0x20
[34489.833197] ret_from_fork+0x1f/0x30
[34489.833418]
[34489.833558] irq event stamp: 0
[34489.833746] hardirqs last enabled at (0): [<0000000000000000>] 0x0
[34489.834130] hardirqs last disabled at (0): [] copy_process+0xaeb/0x1fa0
[34489.834607] softirqs last enabled at (0): [] copy_process+0xaeb/0x1fa0
[34489.835098] softirqs last disabled at (0): [<0000000000000000>] 0x0
[34489.835468] ---[ end trace 0000000000000000 ]---
[34490.181829] btrfs_printk: 3269 callbacks suppressed
[34490.181832] BTRFS info (device dm-9): using free space tree
[34490.182492] BTRFS info (device dm-9): has skinny extents