[ 2646.119652] run fstests btrfs/056 at 2022-04-02 21:35:33
[ 2646.331556] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 2646.331790] BTRFS info (device dm-0): using free space tree
[ 2646.331962] BTRFS info (device dm-0): has skinny extents
[ 2646.545222] BTRFS: device fsid e1569eef-7d5c-4918-8336-f99e97d9420e devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (366042)
[ 2646.610026] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2646.610245] BTRFS info (device dm-11): turning on async discard
[ 2646.610424] BTRFS info (device dm-11): disk space caching is enabled
[ 2646.610614] BTRFS info (device dm-11): has skinny extents
[ 2646.612324] BTRFS info (device dm-11): checking UUID tree
[ 2646.740585] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (363065)
[ 2646.741360] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (363065)
[ 2646.799082] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2646.799304] BTRFS info (device dm-11): turning on async discard
[ 2646.799481] BTRFS info (device dm-11): disk space caching is enabled
[ 2646.799667] BTRFS info (device dm-11): has skinny extents
[ 2646.801499] BTRFS info (device dm-11): start tree-log replay
[ 2646.945334] BTRFS info (device dm-11): checking UUID tree
[ 2647.173719] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2647.173995] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 2647.174237] preempt_count: 101, expected: 0
[ 2647.174388] RCU nest depth: 0, expected: 0
[ 2647.174541] INFO: lockdep is turned off.
[ 2647.174682] Preemption disabled at:
[ 2647.174683] [] schedule_preempt_disabled+0x1e/0x20
[ 2647.175025] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.17.0+ #640
[ 2647.175205] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2647.175470] Call Trace:
[ 2647.175600]  
[ 2647.175728]  dump_stack_lvl+0x56/0x6f
[ 2647.175874]  __might_resched.cold+0xff/0x13a
[ 2647.176026]  blk_release_queue+0x24/0x120
[ 2647.176177]  kobject_put+0x7c/0x1d0
[ 2647.176317]  ? rcu_do_batch+0x1ac/0x570
[ 2647.176463]  blkg_free.part.0+0x41/0x60
[ 2647.176618]  rcu_do_batch+0x1e1/0x570
[ 2647.176765]  ? rcu_do_batch+0x1ac/0x570
[ 2647.176911]  rcu_core+0x2cb/0x460
[ 2647.177061]  __do_softirq+0x178/0x517
[ 2647.177203]  __irq_exit_rcu+0xe3/0x160
[ 2647.177344]  irq_exit_rcu+0xa/0x20
[ 2647.177485]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2647.177656]  
[ 2647.177773]  
[ 2647.177891]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2647.178054] RIP: 0010:native_safe_halt+0xb/0x10
[ 2647.178207] 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
[ 2647.178714] RSP: 0018:ffffffffb8a03ea8 EFLAGS: 00000246
[ 2647.178902] RAX: ffffffffb7e64a80 RBX: ffffffffb8a30a00 RCX: 0000000000000000
[ 2647.179309] RDX: ffffffffb8a30a00 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[ 2647.179552] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 2647.179824] R10: 0000000000000000 R11: ffff9e5a3bc20c88 R12: 0000000000000000
[ 2647.180139] R13: 0000000000000000 R14: ffffffffb8a30118 R15: 0000000000000065
[ 2647.180444]  ? mwait_idle+0x80/0x80
[ 2647.180632]  ? default_idle_call+0x26/0xa0
[ 2647.180899]  default_idle+0xa/0x10
[ 2647.181083]  default_idle_call+0x5e/0xa0
[ 2647.181236]  do_idle+0x1e9/0x240
[ 2647.181374]  cpu_startup_entry+0x19/0x20
[ 2647.181553]  start_kernel+0x98c/0x99e
[ 2647.181692]  ? copy_bootdata+0x18/0x55
[ 2647.181837]  secondary_startup_64_no_verify+0xd5/0xdb
[ 2647.182005]  
[ 2647.285927] BTRFS: device fsid 70768132-4d4a-4e2b-ab49-67bc354fb54e devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (366179)
[ 2647.352167] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2647.352518] BTRFS info (device dm-11): turning on async discard
[ 2647.352818] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2647.353228] BTRFS info (device dm-11): force lzo compression, level 0
[ 2647.353527] BTRFS info (device dm-11): disk space caching is enabled
[ 2647.353833] BTRFS info (device dm-11): has skinny extents
[ 2647.355968] BTRFS info (device dm-11): checking UUID tree
[ 2647.495139] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (363065)
[ 2647.496198] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (363065)
[ 2647.567056] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2647.567305] BTRFS info (device dm-11): turning on async discard
[ 2647.567515] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2647.567836] BTRFS info (device dm-11): force lzo compression, level 0
[ 2647.568050] BTRFS info (device dm-11): disk space caching is enabled
[ 2647.568258] BTRFS info (device dm-11): has skinny extents
[ 2647.570045] BTRFS info (device dm-11): start tree-log replay
[ 2647.678950] BTRFS info (device dm-11): checking UUID tree
[ 2647.868732] BTRFS: device fsid 26649894-128b-45de-9683-3df0e3041e1a devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (366316)
[ 2647.940124] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2647.940341] BTRFS info (device dm-11): turning on async discard
[ 2647.940525] BTRFS info (device dm-11): disk space caching is enabled
[ 2647.946353] BTRFS info (device dm-11): has skinny extents
[ 2647.948024] BTRFS info (device dm-11): checking UUID tree
[ 2648.017993] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (363065)
[ 2648.018901] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (363065)
[ 2648.086425] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2648.086764] BTRFS info (device dm-11): turning on async discard
[ 2648.087017] BTRFS info (device dm-11): disk space caching is enabled
[ 2648.087273] BTRFS info (device dm-11): has skinny extents
[ 2648.088910] BTRFS info (device dm-11): start tree-log replay
[ 2648.096140] BTRFS info (device dm-11): checking UUID tree
[ 2648.173708] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2648.173984] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 2648.174230] preempt_count: 101, expected: 0
[ 2648.174401] RCU nest depth: 0, expected: 0
[ 2648.174557] INFO: lockdep is turned off.
[ 2648.174723] Preemption disabled at:
[ 2648.174724] [] schedule_preempt_disabled+0x1e/0x20
[ 2648.175133] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #640
[ 2648.175368] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2648.175633] Call Trace:
[ 2648.175754]  
[ 2648.175872]  dump_stack_lvl+0x56/0x6f
[ 2648.176017]  __might_resched.cold+0xff/0x13a
[ 2648.176166]  blk_release_queue+0x24/0x120
[ 2648.176312]  kobject_put+0x7c/0x1d0
[ 2648.176451]  ? rcu_do_batch+0x1ac/0x570
[ 2648.176596]  blkg_free.part.0+0x41/0x60
[ 2648.176736]  rcu_do_batch+0x1e1/0x570
[ 2648.176876]  ? rcu_do_batch+0x1ac/0x570
[ 2648.177020]  rcu_core+0x2cb/0x460
[ 2648.177153]  __do_softirq+0x178/0x517
[ 2648.177295]  __irq_exit_rcu+0xe3/0x160
[ 2648.177437]  irq_exit_rcu+0xa/0x20
[ 2648.177582]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2648.177746]  
[ 2648.177860]  
[ 2648.177971]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2648.178132] RIP: 0010:native_safe_halt+0xb/0x10
[ 2648.178286] 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
[ 2648.178743] RSP: 0018:ffffffffb8a03ea8 EFLAGS: 00000246
[ 2648.178904] RAX: ffffffffb7e64a80 RBX: ffffffffb8a30a00 RCX: 0000000000000000
[ 2648.179097] RDX: ffffffffb8a30a00 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[ 2648.179290] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 2648.179484] R10: 0000000000000000 R11: ffff9e5a3bc20c88 R12: 0000000000000000
[ 2648.179686] R13: 0000000000000000 R14: ffffffffb8a30118 R15: 0000000000000065
[ 2648.179888]  ? mwait_idle+0x80/0x80
[ 2648.180024]  ? default_idle_call+0x26/0xa0
[ 2648.180170]  default_idle+0xa/0x10
[ 2648.180302]  default_idle_call+0x5e/0xa0
[ 2648.180445]  do_idle+0x1e9/0x240
[ 2648.180583]  cpu_startup_entry+0x19/0x20
[ 2648.180724]  start_kernel+0x98c/0x99e
[ 2648.180864]  ? copy_bootdata+0x18/0x55
[ 2648.181005]  secondary_startup_64_no_verify+0xd5/0xdb
[ 2648.181166]  
[ 2648.233892] BTRFS: device fsid dbff4fdc-ad88-4e6e-8287-df236df5c3e5 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (366453)
[ 2648.304725] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2648.304953] BTRFS info (device dm-11): turning on async discard
[ 2648.305129] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2648.305388] BTRFS info (device dm-11): force lzo compression, level 0
[ 2648.305629] BTRFS info (device dm-11): disk space caching is enabled
[ 2648.305828] BTRFS info (device dm-11): has skinny extents
[ 2648.307478] BTRFS info (device dm-11): checking UUID tree
[ 2648.384033] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (363065)
[ 2648.384839] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (363065)
[ 2648.449441] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2648.449672] BTRFS info (device dm-11): turning on async discard
[ 2648.449869] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2648.450122] BTRFS info (device dm-11): force lzo compression, level 0
[ 2648.450307] BTRFS info (device dm-11): disk space caching is enabled