[ 8869.169692] run fstests generic/338 at 2022-04-01 23:19:15
[ 8869.392406] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 8869.392627] BTRFS info (device dm-0): using free space tree
[ 8869.392801] BTRFS info (device dm-0): has skinny extents
[ 8869.582326] BTRFS: device fsid 71a162e8-57a5-469d-99f1-f6d61ed3e024 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1692788)
[ 8869.679304] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 8869.679526] BTRFS info (device dm-11): turning on async discard
[ 8869.679706] BTRFS info (device dm-11): disk space caching is enabled
[ 8869.679888] BTRFS info (device dm-11): has skinny extents
[ 8869.681589] BTRFS info (device dm-11): checking UUID tree
[ 8869.693951] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[ 8869.712461] 338 (1692576): drop_caches: 3
[ 8869.732968] btrfs_dev_stat_print_on_error: 8 callbacks suppressed
[ 8869.732971] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 8869.733725] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[ 8869.734017] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[ 8869.734559] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[ 8869.734982] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[ 8869.735336] BTRFS: error (device dm-11: state A) in __btrfs_run_delayed_items:1092: errno=-5 IO failure
[ 8869.735619] BTRFS info (device dm-11: state EA): forced readonly
[ 8869.735796] BTRFS warning (device dm-11: state EA): Skipping commit of aborted transaction.
[ 8869.736062] BTRFS: error (device dm-11: state EA) in cleanup_transaction:1982: errno=-5 IO failure
[ 8869.778067] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 8869.778334] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[ 8869.778584] preempt_count: 100, expected: 0
[ 8869.778734] RCU nest depth: 0, expected: 0
[ 8869.778879] INFO: lockdep is turned off.
[ 8869.784556] Preemption disabled at:
[ 8869.784557] [] __do_softirq+0x5e/0x517
[ 8869.784868] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G        W         5.17.0+ #639
[ 8869.785109] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 8869.785364] Call Trace:
[ 8869.785482]  
[ 8869.785600]  dump_stack_lvl+0x56/0x6f
[ 8869.785740]  __might_resched.cold+0xff/0x13a
[ 8869.785886]  blk_release_queue+0x24/0x120
[ 8869.786034]  kobject_put+0x7c/0x1d0
[ 8869.786173]  ? rcu_do_batch+0x1ac/0x570
[ 8869.786315]  blkg_free.part.0+0x41/0x60
[ 8869.786456]  rcu_do_batch+0x1e1/0x570
[ 8869.786599]  ? rcu_do_batch+0x1ac/0x570
[ 8869.786741]  rcu_core+0x2cb/0x460
[ 8869.786873]  __do_softirq+0x178/0x517
[ 8869.787015]  ? smpboot_thread_fn+0x2c/0x270
[ 8869.787166]  run_ksoftirqd+0x43/0x70
[ 8869.787302]  smpboot_thread_fn+0x1c1/0x270
[ 8869.787449]  ? sort_range+0x20/0x20
[ 8869.787585]  kthread+0xf0/0x120
[ 8869.787715]  ? kthread_complete_and_exit+0x20/0x20
[ 8869.787871]  ret_from_fork+0x1f/0x30
[ 8869.788014]