[ 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