[ 3657.987636] run fstests btrfs/118 at 2022-04-02 21:52:25
[ 3658.191675] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3658.191897] BTRFS info (device dm-0): using free space tree
[ 3658.192071] BTRFS info (device dm-0): has skinny extents
[ 3658.349593] BTRFS: device fsid 33af717c-411f-4ff7-9460-6a074eee72f8 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (462324)
[ 3658.420002] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3658.420223] BTRFS info (device dm-11): turning on async discard
[ 3658.420403] BTRFS info (device dm-11): disk space caching is enabled
[ 3658.420604] BTRFS info (device dm-11): has skinny extents
[ 3658.422345] BTRFS info (device dm-11): checking UUID tree
[ 3658.467857] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (461330)
[ 3658.468739] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (461330)
[ 3658.536343] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3658.536575] BTRFS info (device dm-11): turning on async discard
[ 3658.536758] BTRFS info (device dm-11): disk space caching is enabled
[ 3658.536945] BTRFS info (device dm-11): has skinny extents
[ 3658.585861] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (461330)
[ 3658.587027] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (461330)
[ 3658.637945] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3658.638244] BTRFS info (device dm-11): turning on async discard
[ 3658.638440] BTRFS info (device dm-11): disk space caching is enabled
[ 3658.638652] BTRFS info (device dm-11): has skinny extents
[ 3658.686540] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3658.686845] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 3658.687092] preempt_count: 101, expected: 0
[ 3658.687240] RCU nest depth: 0, expected: 0
[ 3658.687389] INFO: lockdep is turned off.
[ 3658.687548] Preemption disabled at:
[ 3658.687549] [] ___slab_alloc+0x961/0xf50
[ 3658.687865] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #640
[ 3658.688108] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3658.688375] Call Trace:
[ 3658.688496]
[ 3658.688613] dump_stack_lvl+0x56/0x6f
[ 3658.688759] __might_resched.cold+0xff/0x13a
[ 3658.688917] blk_release_queue+0x24/0x120
[ 3658.689066] kobject_put+0x7c/0x1d0
[ 3658.689211] ? rcu_do_batch+0x1ac/0x570
[ 3658.689359] blkg_free.part.0+0x41/0x60
[ 3658.689505] rcu_do_batch+0x1e1/0x570
[ 3658.689650] ? rcu_do_batch+0x1ac/0x570
[ 3658.689804] rcu_core+0x2cb/0x460
[ 3658.689943] __do_softirq+0x178/0x517
[ 3658.690090] __irq_exit_rcu+0xe3/0x160
[ 3658.690240] irq_exit_rcu+0xa/0x20
[ 3658.690382] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3658.690575]
[ 3658.690693]
[ 3658.690816] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3658.690985] RIP: 0010:native_safe_halt+0xb/0x10
[ 3658.691146] 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
[ 3658.691623] RSP: 0018:ffffb18fc0093ef8 EFLAGS: 00000246
[ 3658.691795] RAX: ffffffffb7e64a80 RBX: ffff9e59c037b200 RCX: 0000000000000000
[ 3658.691996] RDX: ffff9e59c037b200 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[ 3658.692194] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 3658.692392] R10: 0000000000000000 R11: ffff9e5a3bd20c88 R12: 0000000000000000
[ 3658.692591] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 3658.692796] ? mwait_idle+0x80/0x80
[ 3658.692940] ? default_idle_call+0x26/0xa0
[ 3658.693096] default_idle+0xa/0x10
[ 3658.693232] default_idle_call+0x5e/0xa0
[ 3658.693382] do_idle+0x1e9/0x240
[ 3658.693519] cpu_startup_entry+0x19/0x20
[ 3658.693664] secondary_startup_64_no_verify+0xd5/0xdb
[ 3658.693839]