[ 3905.705446] run fstests btrfs/160 at 2022-04-03 21:55:59
[ 3905.944652] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3905.945130] BTRFS info (device dm-0): using free space tree
[ 3905.945546] BTRFS info (device dm-0): has skinny extents
[ 3906.861747] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 1 transid 6 /dev/dm-11 scanned by systemd-udevd (651362)
[ 3906.873935] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 2 transid 6 /dev/dm-8 scanned by systemd-udevd (650935)
[ 3906.877181] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 4 transid 6 /dev/dm-6 scanned by systemd-udevd (651365)
[ 3906.887791] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 5 transid 6 /dev/dm-5 scanned by systemd-udevd (651364)
[ 3906.893067] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 3 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (651358)
[ 3906.894522] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 6 transid 6 /dev/mapper/vg0-lv4 scanned by mkfs.btrfs (651358)
[ 3906.896599] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 7 transid 6 /dev/mapper/vg0-lv3 scanned by mkfs.btrfs (651358)
[ 3906.899472] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 8 transid 6 /dev/mapper/vg0-lv2 scanned by mkfs.btrfs (651358)
[ 3906.901045] BTRFS: device fsid f4350d5d-4d5c-4728-8adf-25755874633b devid 9 transid 6 /dev/mapper/vg0-lv1 scanned by mkfs.btrfs (651358)
[ 3906.914133] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3906.914998] BTRFS info (device dm-11): disk space caching is enabled
[ 3906.915882] BTRFS info (device dm-11): has skinny extents
[ 3906.917738] BTRFS info: devid 9 device path /dev/mapper/vg0-lv1 changed to /dev/dm-1 scanned by systemd-udevd (651364)
[ 3906.920061] BTRFS info: devid 8 device path /dev/mapper/vg0-lv2 changed to /dev/dm-2 scanned by systemd-udevd (650935)
[ 3906.921506] BTRFS info: devid 9 device path /dev/dm-1 changed to /dev/mapper/vg0-lv1 scanned by systemd-udevd (651364)
[ 3906.922203] BTRFS info: devid 8 device path /dev/dm-2 changed to /dev/mapper/vg0-lv2 scanned by systemd-udevd (650935)
[ 3906.941130] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (651362)
[ 3906.944828] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (651362)
[ 3906.955641] BTRFS info: devid 3 device path /dev/mapper/vg0-lv7 changed to /dev/dm-7 scanned by systemd-udevd (651361)
[ 3906.956697] BTRFS info: devid 5 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (650858)
[ 3906.962242] BTRFS info: devid 5 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (650858)
[ 3906.963899] BTRFS info: devid 4 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (650819)
[ 3906.966093] BTRFS info: devid 4 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (650819)
[ 3906.967437] BTRFS info: devid 3 device path /dev/dm-7 changed to /dev/mapper/vg0-lv7 scanned by systemd-udevd (651361)
[ 3906.983585] BTRFS info (device dm-11): checking UUID tree
[ 3906.983908] BTRFS info: devid 2 device path /dev/mapper/vg0-lv8 changed to /dev/dm-8 scanned by systemd-udevd (651396)
[ 3906.986367] BTRFS info: devid 2 device path /dev/dm-8 changed to /dev/mapper/vg0-lv8 scanned by systemd-udevd (651396)
[ 3907.001828] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (651362)
[ 3907.004047] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (651362)
[ 3907.038582] BTRFS info: devid 3 device path /dev/mapper/vg0-lv7 changed to /dev/dm-7 scanned by systemd-udevd (650935)
[ 3907.140304] BTRFS info: devid 4 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (651365)
[ 3907.142434] BTRFS info: devid 3 device path /dev/dm-7 changed to /dev/mapper/vg0-lv7 scanned by systemd-udevd (650935)
[ 3907.143705] BTRFS info: devid 4 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (651365)
[ 3907.144447] BTRFS info: devid 5 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (651364)
[ 3907.151098] BTRFS info: devid 2 device path /dev/mapper/vg0-lv8 changed to /dev/dm-8 scanned by systemd-udevd (650926)
[ 3907.153151] BTRFS info: devid 2 device path /dev/dm-8 changed to /dev/mapper/vg0-lv8 scanned by systemd-udevd (650926)
[ 3907.154478] BTRFS info: devid 5 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (651364)
[ 3907.160063] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (651362)
[ 3907.162076] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (651362)
[ 3907.170496] BTRFS info: devid 3 device path /dev/mapper/vg0-lv7 changed to /dev/dm-7 scanned by systemd-udevd (650935)
[ 3907.172763] BTRFS info: devid 3 device path /dev/dm-7 changed to /dev/mapper/vg0-lv7 scanned by systemd-udevd (650935)
[ 3907.197707] BTRFS info: devid 2 device path /dev/mapper/vg0-lv8 changed to /dev/dm-8 scanned by systemd-udevd (650926)
[ 3907.199788] BTRFS info: devid 2 device path /dev/dm-8 changed to /dev/mapper/vg0-lv8 scanned by systemd-udevd (650926)
[ 3907.202891] BTRFS info: devid 4 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (651365)
[ 3907.204954] BTRFS info: devid 4 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (651365)
[ 3907.223567] BTRFS info: devid 2 device path /dev/mapper/vg0-lv8 changed to /dev/dm-8 scanned by systemd-udevd (651362)
[ 3907.225686] BTRFS info: devid 2 device path /dev/dm-8 changed to /dev/mapper/vg0-lv8 scanned by systemd-udevd (651362)
[ 3907.228679] btrfs_dev_stat_print_on_error: 38 callbacks suppressed
[ 3907.228682] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 3907.232022] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[ 3907.233492] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[ 3907.234762] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[ 3907.235674] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[ 3907.236625] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[ 3907.237570] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[ 3907.238478] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[ 3907.239867] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[ 3907.240819] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[ 3907.252468] BTRFS info: devid 4 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (650935)
[ 3907.255637] BTRFS info: devid 4 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (650935)
[ 3907.276094] BTRFS info: devid 2 device path /dev/mapper/vg0-lv8 changed to /dev/dm-8 scanned by systemd-udevd (651362)
[ 3907.280985] BTRFS info: devid 2 device path /dev/dm-8 changed to /dev/mapper/vg0-lv8 scanned by systemd-udevd (651362)
[ 3907.368822] BTRFS warning (device dm-11): lost page write due to IO error on /dev/mapper/error-test (-5)
[ 3907.369746] BTRFS warning (device dm-11): lost page write due to IO error on /dev/mapper/error-test (-5)
[ 3907.371133] BTRFS error (device dm-11): error writing primary super block to device 1
[ 3907.408841] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (651362)
[ 3907.410858] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (651362)
[ 3907.499408] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3907.499911] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 3907.500403] preempt_count: 101, expected: 0
[ 3907.500670] RCU nest depth: 0, expected: 0
[ 3907.500933] INFO: lockdep is turned off.
[ 3907.501177] Preemption disabled at:
[ 3907.501178] [] schedule_preempt_disabled+0x1e/0x20
[ 3907.501792] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[ 3907.502247] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3907.502776] Call Trace:
[ 3907.502935]
[ 3907.503086] dump_stack_lvl+0x56/0x6f
[ 3907.503322] __might_resched.cold+0xff/0x13a
[ 3907.503599] blk_release_queue+0x24/0x120
[ 3907.503857] kobject_put+0x7c/0x1d0
[ 3907.504082] ? rcu_do_batch+0x1ac/0x570
[ 3907.504328] blkg_free.part.0+0x41/0x60
[ 3907.504576] rcu_do_batch+0x1e1/0x570
[ 3907.504809] ? rcu_do_batch+0x1ac/0x570
[ 3907.505056] rcu_core+0x2cb/0x460
[ 3907.505272] __do_softirq+0x178/0x517
[ 3907.505512] __irq_exit_rcu+0xe3/0x160
[ 3907.505756] irq_exit_rcu+0xa/0x20
[ 3907.505974] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3907.506275]
[ 3907.506417]
[ 3907.506556] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3907.506885] RIP: 0010:native_safe_halt+0xb/0x10
[ 3907.507172] Code: 56 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
[ 3907.508316] RSP: 0018:ffffffffaaa03ea8 EFLAGS: 00000246
[ 3907.508647] RAX: ffffffffa9e64a80 RBX: ffffffffaaa30a00 RCX: 0000000000000000
[ 3907.509086] RDX: ffffffffaaa30a00 RSI: ffffffffaa87e4d9 RDI: ffffffffa9e64bf6
[ 3907.509527] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 3907.509965] R10: 0000000000000000 R11: ffff94fcbbc20c88 R12: 0000000000000000
[ 3907.510407] R13: 0000000000000000 R14: ffffffffaaa30118 R15: 0000000000000073
[ 3907.510861] ? mwait_idle+0x80/0x80
[ 3907.511089] ? default_idle_call+0x26/0xa0
[ 3907.511358] default_idle+0xa/0x10
[ 3907.511574] default_idle_call+0x5e/0xa0
[ 3907.511820] do_idle+0x1e9/0x240
[ 3907.512032] cpu_startup_entry+0x19/0x20
[ 3907.512279] start_kernel+0x98c/0x99e
[ 3907.512521] ? copy_bootdata+0x18/0x55
[ 3907.512777] secondary_startup_64_no_verify+0xd5/0xdb
[ 3907.513094]