[ 3412.163681] run fstests btrfs/120 at 2022-04-01 21:48:18
[ 3412.360409] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3412.360628] BTRFS info (device dm-0): using free space tree
[ 3412.360805] BTRFS info (device dm-0): has skinny extents
[ 3412.480350] BTRFS: device fsid 8615feca-e4c4-4cbd-b6eb-1ba38e944f19 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (458761)
[ 3412.541906] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3412.542131] BTRFS info (device dm-11): turning on async discard
[ 3412.542337] BTRFS info (device dm-11): disk space caching is enabled
[ 3412.542527] BTRFS info (device dm-11): has skinny extents
[ 3412.544410] BTRFS info (device dm-11): checking UUID tree
[ 3412.585151] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (456989)
[ 3412.585965] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (456989)
[ 3412.638393] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3412.644347] BTRFS info (device dm-11): turning on async discard
[ 3412.644532] BTRFS info (device dm-11): disk space caching is enabled
[ 3412.644723] BTRFS info (device dm-11): has skinny extents
[ 3412.686179] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (456989)
[ 3412.686953] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (456989)
[ 3412.735584] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3412.735818] BTRFS info (device dm-11): turning on async discard
[ 3412.735998] BTRFS info (device dm-11): disk space caching is enabled
[ 3412.736183] BTRFS info (device dm-11): has skinny extents
[ 3412.780293] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3412.780565] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 20, name: ksoftirqd/1
[ 3412.780823] preempt_count: 100, expected: 0
[ 3412.781006] RCU nest depth: 0, expected: 0
[ 3412.781154] INFO: lockdep is turned off.
[ 3412.781308] Preemption disabled at:
[ 3412.781309] [] __do_softirq+0x5e/0x517
[ 3412.781614] CPU: 1 PID: 20 Comm: ksoftirqd/1 Tainted: G        W         5.17.0+ #639
[ 3412.781869] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3412.782124] Call Trace:
[ 3412.782248]  
[ 3412.782365]  dump_stack_lvl+0x56/0x6f
[ 3412.782508]  __might_resched.cold+0xff/0x13a
[ 3412.782669]  blk_release_queue+0x24/0x120
[ 3412.782820]  kobject_put+0x7c/0x1d0
[ 3412.782958]  ? rcu_do_batch+0x1ac/0x570
[ 3412.783105]  blkg_free.part.0+0x41/0x60
[ 3412.783252]  rcu_do_batch+0x1e1/0x570
[ 3412.783391]  ? rcu_do_batch+0x1ac/0x570
[ 3412.783536]  rcu_core+0x2cb/0x460
[ 3412.783679]  __do_softirq+0x178/0x517
[ 3412.783825]  ? smpboot_thread_fn+0x2c/0x270
[ 3412.783974]  run_ksoftirqd+0x43/0x70
[ 3412.784116]  smpboot_thread_fn+0x1c1/0x270
[ 3412.784265]  ? sort_range+0x20/0x20
[ 3412.784402]  kthread+0xf0/0x120
[ 3412.784534]  ? kthread_complete_and_exit+0x20/0x20
[ 3412.784699]  ret_from_fork+0x1f/0x30
[ 3412.784844]