[ 3435.756389] run fstests btrfs/159 at 2022-04-02 21:59:42
[ 3436.000571] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3436.001048] BTRFS info (device dm-0): using free space tree
[ 3436.001455] BTRFS info (device dm-0): has skinny extents
[ 3436.220667] BTRFS: device fsid 8fb0864e-fd5f-4699-913e-e79f381b8354 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (537478)
[ 3436.285495] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3436.285982] BTRFS info (device dm-11): disk space caching is enabled
[ 3436.286433] BTRFS info (device dm-11): has skinny extents
[ 3436.288533] BTRFS info (device dm-11): checking UUID tree
[ 3440.912271] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (539190)
[ 3440.913671] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (539190)
[ 3441.035983] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3441.036504] BTRFS info (device dm-11): disk space caching is enabled
[ 3441.036935] BTRFS info (device dm-11): has skinny extents
[ 3441.039529] BTRFS info (device dm-11): start tree-log replay
[ 3442.144221] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3442.144993] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 3442.145768] preempt_count: 101, expected: 0
[ 3442.146166] RCU nest depth: 0, expected: 0
[ 3442.146577] INFO: lockdep is turned off.
[ 3442.146955] Preemption disabled at:
[ 3442.146956] [] ___slab_alloc+0x961/0xf50
[ 3442.147833] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G        W         5.17.0+ #640
[ 3442.148582] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3442.149429] Call Trace:
[ 3442.149684]  
[ 3442.149902]  dump_stack_lvl+0x56/0x6f
[ 3442.150259]  __might_resched.cold+0xff/0x13a
[ 3442.150673]  blk_release_queue+0x24/0x120
[ 3442.151069]  kobject_put+0x7c/0x1d0
[ 3442.151415]  ? rcu_do_batch+0x1ac/0x570
[ 3442.151792]  blkg_free.part.0+0x41/0x60
[ 3442.152189]  rcu_do_batch+0x1e1/0x570
[ 3442.152566]  ? rcu_do_batch+0x1ac/0x570
[ 3442.152960]  rcu_core+0x2cb/0x460
[ 3442.153301]  __do_softirq+0x178/0x517
[ 3442.153679]  __irq_exit_rcu+0xe3/0x160
[ 3442.154046]  irq_exit_rcu+0xa/0x20
[ 3442.154379]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3442.154927]  
[ 3442.155181]  
[ 3442.155412]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3442.155965] RIP: 0010:native_safe_halt+0xb/0x10
[ 3442.156465] Code: 54 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
[ 3442.158448] RSP: 0018:ffffa8c180093ef8 EFLAGS: 00000246
[ 3442.158966] RAX: ffffffffabe64a80 RBX: ffff9da100340000 RCX: 0000000000000000
[ 3442.159663] RDX: ffff9da100340000 RSI: ffffffffac87e4d9 RDI: ffffffffabe64bf6
[ 3442.160284] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 3442.160776] R10: 0000000000000000 R11: ffff9da17bd20c88 R12: 0000000000000000
[ 3442.161281] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 3442.161778]  ? mwait_idle+0x80/0x80
[ 3442.162037]  ? default_idle_call+0x26/0xa0
[ 3442.162342]  default_idle+0xa/0x10
[ 3442.162587]  default_idle_call+0x5e/0xa0
[ 3442.162892]  do_idle+0x1e9/0x240
[ 3442.163176]  cpu_startup_entry+0x19/0x20
[ 3442.163467]  secondary_startup_64_no_verify+0xd5/0xdb
[ 3442.163826]  
[ 3442.260637] BTRFS: device fsid e5fb6282-a93e-4f7e-98d0-2646b41c8640 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (539232)
[ 3442.328854] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3442.329417] BTRFS info (device dm-11): disk space caching is enabled
[ 3442.329887] BTRFS info (device dm-11): has skinny extents
[ 3442.332438] BTRFS info (device dm-11): checking UUID tree
[ 3446.857805] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (540945)
[ 3446.859585] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (540945)
[ 3446.994635] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3446.995174] BTRFS info (device dm-11): disk space caching is enabled
[ 3446.995622] BTRFS info (device dm-11): has skinny extents
[ 3446.998758] BTRFS info (device dm-11): start tree-log replay
[ 3448.636164] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3448.636710] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 3448.637254] preempt_count: 101, expected: 0
[ 3448.637538] RCU nest depth: 0, expected: 0
[ 3448.637814] INFO: lockdep is turned off.
[ 3448.638084] Preemption disabled at:
[ 3448.638085] [] ___slab_alloc+0x961/0xf50
[ 3448.638712] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G        W         5.17.0+ #640
[ 3448.639217] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3448.639795] Call Trace:
[ 3448.639971]  
[ 3448.640114]  dump_stack_lvl+0x56/0x6f
[ 3448.640366]  __might_resched.cold+0xff/0x13a
[ 3448.640666]  blk_release_queue+0x24/0x120
[ 3448.640938]  kobject_put+0x7c/0x1d0
[ 3448.641181]  ? rcu_do_batch+0x1ac/0x570
[ 3448.641452]  blkg_free.part.0+0x41/0x60
[ 3448.641718]  rcu_do_batch+0x1e1/0x570
[ 3448.641973]  ? rcu_do_batch+0x1ac/0x570
[ 3448.642242]  rcu_core+0x2cb/0x460
[ 3448.642469]  __do_softirq+0x178/0x517
[ 3448.642719]  __irq_exit_rcu+0xe3/0x160
[ 3448.642979]  irq_exit_rcu+0xa/0x20
[ 3448.643212]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3448.643534]  
[ 3448.643681]  
[ 3448.643829]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3448.644179] RIP: 0010:native_safe_halt+0xb/0x10
[ 3448.644491] Code: 54 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
[ 3448.645745] RSP: 0018:ffffa8c180093ef8 EFLAGS: 00000246
[ 3448.646109] RAX: ffffffffabe64a80 RBX: ffff9da100340000 RCX: 0000000000000000
[ 3448.646583] RDX: ffff9da100340000 RSI: ffffffffac87e4d9 RDI: ffffffffabe64bf6
[ 3448.647063] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 3448.647538] R10: 0000000000000000 R11: ffff9da17bd20c88 R12: 0000000000000000
[ 3448.648027] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 3448.648497]  ? mwait_idle+0x80/0x80
[ 3448.648734]  ? default_idle_call+0x26/0xa0
[ 3448.649024]  default_idle+0xa/0x10
[ 3448.649261]  default_idle_call+0x5e/0xa0
[ 3448.649523]  do_idle+0x1e9/0x240
[ 3448.649753]  cpu_startup_entry+0x19/0x20
[ 3448.650020]  secondary_startup_64_no_verify+0xd5/0xdb
[ 3448.650369]