[31978.993560] run fstests btrfs/232 at 2022-03-19 05:45:24
[31979.217985] BTRFS info (device dm-0): flagging fs with big metadata feature
[31979.218418] BTRFS info (device dm-0): using free space tree
[31979.218769] BTRFS info (device dm-0): has skinny extents
[31979.848735] BTRFS: device fsid bee18d9c-fcf2-4217-b6c3-a6311cd3c0f5 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3075578)
[31979.872285] BTRFS info (device dm-9): flagging fs with big metadata feature
[31979.872799] BTRFS info (device dm-9): using free space tree
[31979.873183] BTRFS info (device dm-9): has skinny extents
[31979.879424] BTRFS info (device dm-9): checking UUID tree
[31985.831773] BTRFS warning (device dm-9): qgroup rescan is already in progress
[31985.840843] BTRFS info (device dm-9): qgroup scan completed (inconsistency flag cleared)
[32013.181265] ------------[ cut here ]------------
[32013.181675] qgroup 5 data reserved space underflow, have 0 to free 4096
[32013.181700] WARNING: CPU: 0 PID: 3075630 at fs/btrfs/qgroup.c:76 qgroup_rsv_release+0xaa/0xb0
[32013.182615] 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 snd_hda_codec_generic snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hwdep snd_hda_core snd_pcm intel_rapl_common joydev snd_timer snd soundcore i2c_i801 i2c_smbus lpc_ich virtio_balloon zram ip_tables crct10dif_pclmul crc32_pclmul crc32c_intel xfs ghash_clmulni_intel serio_raw qemu_fw_cfg
[32013.186040] CPU: 0 PID: 3075630 Comm: fsstress Not tainted 5.17.0-rc8+ #624
[32013.186457] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[32013.186958] RIP: 0010:qgroup_rsv_release+0xaa/0xb0
[32013.187245] Code: fe 01 74 14 41 83 fe 02 48 c7 c2 e7 99 85 83 b8 00 00 00 00 48 0f 45 d0 48 8b 33 49 89 e8 48 c7 c7 90 f7 85 83 e8 52 10 76 00 <0f> 0b eb a7 66 90 0f 1f 44 00 00 41 57 41 56 41 55 41 54 55 53 48
[32013.188374] RSP: 0018:ffffa101c5a478e0 EFLAGS: 00010282
[32013.188683] RAX: 000000000000003b RBX: ffff95aef8444e00 RCX: 0000000000000000
[32013.189105] RDX: 0000000000000002 RSI: ffffffff83872aba RDI: 00000000ffffffff
[32013.189530] RBP: 0000000000001000 R08: 0000000000000000 R09: ffffa101c5a47720
[32013.189948] R10: ffffa101c5a47718 R11: ffffffff83b5d6e8 R12: ffff95aec2c64000
[32013.190371] R13: 000000000000000a R14: 0000000000000000 R15: ffff95aec2c65790
[32013.190788] FS: 00007fcf2bbd6740(0000) GS:ffff95af3bc00000(0000) knlGS:0000000000000000
[32013.191265] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[32013.191609] CR2: 00007fcf2b962000 CR3: 000000010c90e005 CR4: 0000000000370ef0
[32013.192034] Call Trace:
[32013.192189]
[32013.192337] btrfs_qgroup_free_refroot+0x14d/0x190
[32013.192626] qgroup_free_reserved_data+0x106/0x130
[32013.192921] btrfs_truncate_block+0x65e/0x6d0
[32013.193180] ? trace_hardirqs_on+0x1b/0xe0
[32013.193434] ? rcu_read_lock_sched_held+0x12/0x60
[32013.193718] btrfs_cont_expand+0x4b/0x4d0
[32013.193965] ? lock_acquire+0x179/0x2d0
[32013.194198] ? lock_release+0x1f0/0x2d0
[32013.194438] ? trace_hardirqs_on+0x1b/0xe0
[32013.194684] ? seqcount_lockdep_reader_access.constprop.0+0x84/0x90
[32013.195060] ? ktime_get_coarse_real_ts64+0xe/0x50
[32013.195354] ? ktime_get_coarse_real_ts64+0xe/0x50
[32013.195638] btrfs_write_check.constprop.0+0x115/0x1a0
[32013.195951] btrfs_buffered_write+0x95/0x790
[32013.196207] ? rcu_read_lock_sched_held+0x12/0x60
[32013.196496] ? btrfs_dirty_inode+0x97/0xe0
[32013.196744] btrfs_do_write_iter+0x233/0x5b0
[32013.197008] ? lock_acquire+0x179/0x2d0
[32013.197238] do_iter_readv_writev+0x13f/0x190
[32013.197509] do_iter_write+0x7f/0x1c0
[32013.197733] iter_file_splice_write+0x274/0x400
[32013.198011] direct_splice_actor+0x29/0x40
[32013.198258] splice_direct_to_actor+0xe0/0x200
[32013.198533] ? generic_file_splice_read+0x1a0/0x1a0
[32013.198826] do_splice_direct+0x81/0xb0
[32013.199063] vfs_copy_file_range+0x1aa/0x580
[32013.199329] __do_sys_copy_file_range+0xb1/0x1e0
[32013.199605] do_syscall_64+0x38/0x90
[32013.199820] entry_SYSCALL_64_after_hwframe+0x44/0xae
[32013.200126] RIP: 0033:0x7fcf2bcd411d
[32013.200355] Code: c5 0c 00 0f 05 eb a9 66 0f 1f 44 00 00 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 23 7d 0c 00 f7 d8 64 89 01 48
[32013.201450] RSP: 002b:00007ffded245308 EFLAGS: 00000246 ORIG_RAX: 0000000000000146
[32013.201896] RAX: ffffffffffffffda RBX: 00000000004de43f RCX: 00007fcf2bcd411d
[32013.202317] RDX: 0000000000000004 RSI: 00007ffded245340 RDI: 0000000000000003
[32013.202736] RBP: 0000000000000003 R08: 00000000000043bf R09: 0000000000000000
[32013.203157] R10: 00007ffded245348 R11: 0000000000000246 R12: 00000000000001d5
[32013.203580] R13: 0000000000000004 R14: 00000000000043bf R15: 00000000003d92d8
[32013.204006]
[32013.204140] irq event stamp: 0
[32013.204332] hardirqs last enabled at (0): [<0000000000000000>] 0x0
[32013.204700] hardirqs last disabled at (0): [] copy_process+0xaeb/0x1fa0
[32013.205183] softirqs last enabled at (0): [] copy_process+0xaeb/0x1fa0
[32013.205668] softirqs last disabled at (0): [<0000000000000000>] 0x0
[32013.206039] ---[ end trace 0000000000000000 ]---
[32133.735527] BTRFS info (device dm-9): flagging fs with big metadata feature
[32133.735961] BTRFS info (device dm-9): using free space tree
[32133.736311] BTRFS info (device dm-9): has skinny extents