[31488.779763] run fstests generic/281 at 2022-04-02 05:36:14
[31489.130395] BTRFS: device fsid 0ceb21c7-52ec-448b-9626-5edaf6c0386b devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3853102)
[31489.142401] BTRFS info (device dm-9): flagging fs with big metadata feature
[31489.142635] BTRFS info (device dm-9): turning on sync discard
[31489.142813] BTRFS info (device dm-9): enabling free space tree
[31489.142989] BTRFS info (device dm-9): using free space tree
[31489.143160] BTRFS info (device dm-9): has skinny extents
[31489.144566] BTRFS info (device dm-9): creating free space tree
[31489.144810] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[31489.145077] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[31489.148540] BTRFS info (device dm-9): cleaning free space cache v1
[31489.151945] BTRFS info (device dm-9): checking UUID tree
[31489.309410] BTRFS: device fsid a60640c6-9f8b-49bc-9e99-24b70d62e2c6 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3853175)
[31489.388060] BTRFS info (device dm-11): flagging fs with big metadata feature
[31489.388282] BTRFS info (device dm-11): turning on sync discard
[31489.388474] BTRFS info (device dm-11): enabling free space tree
[31489.388650] BTRFS info (device dm-11): using free space tree
[31489.388821] BTRFS info (device dm-11): has skinny extents
[31489.391203] BTRFS info (device dm-11): creating free space tree
[31489.391477] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[31489.391745] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[31489.401810] BTRFS info (device dm-11): cleaning free space cache v1
[31489.409873] BTRFS info (device dm-11): checking UUID tree
[31489.690550] BTRFS info (device dm-11): flagging fs with big metadata feature
[31489.691684] BTRFS info (device dm-11): turning on sync discard
[31489.692653] BTRFS info (device dm-11): using free space tree
[31489.693606] BTRFS info (device dm-11): has skinny extents
[31489.947416] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[31490.024032] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[31490.025892] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[31490.027741] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[31490.061566] BTRFS: error (device dm-11) in btrfs_commit_transaction:2418: errno=-5 IO failure (Error while writing out transaction)
[31490.061887] BTRFS info (device dm-11: state E): forced readonly
[31490.062065] BTRFS warning (device dm-11: state E): Skipping commit of aborted transaction.
[31490.062325] BTRFS: error (device dm-11: state EA) in cleanup_transaction:1982: errno=-5 IO failure
[31490.106453] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[31490.106727] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3853275, name: perl
[31490.106969] preempt_count: 100, expected: 0
[31490.107122] RCU nest depth: 0, expected: 0
[31490.107265] INFO: lockdep is turned off.
[31490.107404] Preemption disabled at:
[31490.107404] [] __do_softirq+0x5e/0x517
[31490.107717] CPU: 1 PID: 3853275 Comm: perl Tainted: G        W         5.17.0+ #639
[31490.107954] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[31490.114618] Call Trace:
[31490.114741]  
[31490.114853]  dump_stack_lvl+0x56/0x6f
[31490.114993]  __might_resched.cold+0xff/0x13a
[31490.115150]  blk_release_queue+0x24/0x120
[31490.115295]  kobject_put+0x7c/0x1d0
[31490.115430]  ? rcu_do_batch+0x1ac/0x570
[31490.115575]  blkg_free.part.0+0x41/0x60
[31490.115720]  rcu_do_batch+0x1e1/0x570
[31490.115858]  ? rcu_do_batch+0x1ac/0x570
[31490.115999]  rcu_core+0x2cb/0x460
[31490.116137]  __do_softirq+0x178/0x517
[31490.116276]  __irq_exit_rcu+0xe3/0x160
[31490.116416]  irq_exit_rcu+0xa/0x20
[31490.116551]  sysvec_apic_timer_interrupt+0xa2/0xd0
[31490.116710]  
[31490.116821]  
[31490.116934]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[31490.117102] RIP: 0010:lock_release+0x2/0x2c0
[31490.117249] Code: fe ff ff 48 c7 c6 81 59 81 b9 48 c7 c7 a6 13 7f b9 e8 69 1a bf 00 0f 0b e9 d6 fe ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 41 57 <41> 56 49 89 f6 41 55 41 54 55 48 89 fd 48 83 ec 10 0f 1f 44 00 00
[31490.117703] RSP: 0018:ffffb835c53d7c90 EFLAGS: 00000293
[31490.117868] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
[31490.118064] RDX: ffffffffb840229c RSI: ffffffffb8402321 RDI: ffffffffb9b9ce40
[31490.118259] RBP: ffff9418401eaa00 R08: 0000000000000000 R09: 0000000000000000
[31490.118453] R10: 0000000000000003 R11: 0000000000000040 R12: ffff9418445fef80
[31490.118644] R13: 0000000000000000 R14: ffffb835c53d7ce8 R15: ffffddd184a8bd80
[31490.118838]  ? memcg_slab_post_alloc_hook+0xcc/0x440
[31490.118998]  ? memcg_slab_post_alloc_hook+0x151/0x440
[31490.119164]  memcg_slab_post_alloc_hook+0x169/0x440
[31490.119325]  kmem_cache_alloc+0x1d1/0x3f0
[31490.119473]  __alloc_file+0x23/0x100
[31490.119610]  alloc_empty_file+0x41/0xb0
[31490.119752]  path_openat+0x39/0xab0
[31490.119890]  ? rcu_read_lock_sched_held+0x12/0x60
[31490.120047]  ? xfs_iunlock+0x12e/0x180 [xfs]
[31490.120407]  do_filp_open+0x96/0x120
[31490.120550]  ? lock_acquire+0x176/0x2d0
[31490.120694]  ? lock_release+0x1ed/0x2c0
[31490.120835]  ? _raw_spin_unlock+0x29/0x40
[31490.120981]  ? alloc_fd+0x130/0x1f0
[31490.121125]  do_sys_openat2+0x7b/0x140
[31490.121271]  __x64_sys_openat+0x46/0x70
[31490.121413]  do_syscall_64+0x38/0x90
[31490.121554]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[31490.121718] RIP: 0033:0x7f57b19eafc8
[31490.121854] Code: f9 41 89 f0 41 83 e2 40 75 30 89 f0 25 00 00 41 00 3d 00 00 41 00 74 22 44 89 c2 4c 89 ce bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 30 c3 0f 1f 80 00 00 00 00 48 8d 44 24 08 c7
[31490.122345] RSP: 002b:00007ffff82f4e38 EFLAGS: 00000287 ORIG_RAX: 0000000000000101
[31490.122586] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f57b19eafc8
[31490.122776] RDX: 0000000000080000 RSI: 00007f57b19c54d0 RDI: 00000000ffffff9c
[31490.122969] RBP: 00007ffff82f4ea0 R08: 0000000000080000 R09: 00007f57b19c54d0
[31490.123164] R10: 0000000000000000 R11: 0000000000000287 R12: 00007ffff82f4eff
[31490.123356] R13: 00007f57b19c54d0 R14: 00007f57b19fb000 R15: 00007ffff82f4f10
[31490.123553]  
[31490.128223] BTRFS info (device dm-9): flagging fs with big metadata feature
[31490.128429] BTRFS info (device dm-9): turning on sync discard
[31490.128621] BTRFS info (device dm-9): using free space tree
[31490.128793] BTRFS info (device dm-9): has skinny extents