[ 3659.555663] run fstests btrfs/120 at 2022-04-02 21:52:27
[ 3659.762297] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3659.762535] BTRFS info (device dm-0): using free space tree
[ 3659.762710] BTRFS info (device dm-0): has skinny extents
[ 3659.922733] BTRFS: device fsid 85c94b4e-3c97-4c10-a3da-fcffa01d0134 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (463100)
[ 3659.985366] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3659.985609] BTRFS info (device dm-11): turning on async discard
[ 3659.985799] BTRFS info (device dm-11): disk space caching is enabled
[ 3659.985986] BTRFS info (device dm-11): has skinny extents
[ 3659.988484] BTRFS info (device dm-11): checking UUID tree
[ 3660.034781] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (461330)
[ 3660.035573] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (461330)
[ 3660.086237] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3660.086461] BTRFS info (device dm-11): turning on async discard
[ 3660.086651] BTRFS info (device dm-11): disk space caching is enabled
[ 3660.086867] BTRFS info (device dm-11): has skinny extents
[ 3660.136956] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (461330)
[ 3660.137839] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (461330)
[ 3660.187310] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3660.187560] BTRFS info (device dm-11): turning on async discard
[ 3660.187749] BTRFS info (device dm-11): disk space caching is enabled
[ 3660.187961] BTRFS info (device dm-11): has skinny extents
[ 3660.241525] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3660.241859] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 3660.242104] preempt_count: 101, expected: 0
[ 3660.242253] RCU nest depth: 0, expected: 0
[ 3660.242401] INFO: lockdep is turned off.
[ 3660.242560] Preemption disabled at:
[ 3660.242561] [] ___slab_alloc+0x961/0xf50
[ 3660.242880] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #640
[ 3660.243118] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3660.243380] Call Trace:
[ 3660.243500]
[ 3660.243614] dump_stack_lvl+0x56/0x6f
[ 3660.243760] __might_resched.cold+0xff/0x13a
[ 3660.243961] blk_release_queue+0x24/0x120
[ 3660.244108] kobject_put+0x7c/0x1d0
[ 3660.244252] ? rcu_do_batch+0x1ac/0x570
[ 3660.244398] blkg_free.part.0+0x41/0x60
[ 3660.244541] rcu_do_batch+0x1e1/0x570
[ 3660.244685] ? rcu_do_batch+0x1ac/0x570
[ 3660.244835] rcu_core+0x2cb/0x460
[ 3660.244971] __do_softirq+0x178/0x517
[ 3660.245114] __irq_exit_rcu+0xe3/0x160
[ 3660.245261] irq_exit_rcu+0xa/0x20
[ 3660.245398] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3660.245558]
[ 3660.245675]
[ 3660.245820] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3660.246004] RIP: 0010:native_safe_halt+0xb/0x10
[ 3660.246163] Code: 48 ff ff ff 7f 5b c3 65 48 8b 04 25 c0 fe 01 00 f0 80 48 02 20 48 8b 00 a8 08 75 c3 eb 80 cc eb 07 0f 00 2d 79 b7 5b 00 fb f4 0f 1f 40 00 eb 07 0f 00 2d 69 b7 5b 00 f4 c3 cc cc cc cc cc 0f
[ 3660.246634] RSP: 0018:ffffb18fc0093ef8 EFLAGS: 00000246
[ 3660.246804] RAX: ffffffffb7e64a80 RBX: ffff9e59c037b200 RCX: 0000000000000000
[ 3660.247004] RDX: ffff9e59c037b200 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[ 3660.247199] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 3660.247396] R10: 0000000000000000 R11: ffff9e5a3bd20c88 R12: 0000000000000000
[ 3660.247596] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 3660.247800] ? mwait_idle+0x80/0x80
[ 3660.247945] ? default_idle_call+0x26/0xa0
[ 3660.248099] default_idle+0xa/0x10
[ 3660.248236] default_idle_call+0x5e/0xa0
[ 3660.248384] do_idle+0x1e9/0x240
[ 3660.248522] cpu_startup_entry+0x19/0x20
[ 3660.248668] secondary_startup_64_no_verify+0xd5/0xdb
[ 3660.248838]