[31681.917754] run fstests generic/265 at 2022-04-03 05:39:29
[31682.294605] BTRFS: device fsid fd04cd71-8114-441e-9199-375dd0bd3ebc devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3916929)
[31682.314963] BTRFS info (device dm-9): flagging fs with big metadata feature
[31682.315211] BTRFS info (device dm-9): turning on sync discard
[31682.315388] BTRFS info (device dm-9): enabling free space tree
[31682.315559] BTRFS info (device dm-9): using free space tree
[31682.315729] BTRFS info (device dm-9): has skinny extents
[31682.318911] BTRFS info (device dm-9): creating free space tree
[31682.319194] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[31682.319462] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[31682.323595] BTRFS info (device dm-9): cleaning free space cache v1
[31682.330646] BTRFS info (device dm-9): checking UUID tree
[31682.443268] BTRFS: device fsid 1c2e90a4-557a-4a31-b641-d7e3573e31d8 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3917002)
[31682.548512] BTRFS info (device dm-11): flagging fs with big metadata feature
[31682.548730] BTRFS info (device dm-11): turning on sync discard
[31682.548902] BTRFS info (device dm-11): enabling free space tree
[31682.549104] BTRFS info (device dm-11): using free space tree
[31682.549276] BTRFS info (device dm-11): has skinny extents
[31682.550935] BTRFS info (device dm-11): creating free space tree
[31682.551200] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[31682.551457] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[31682.556916] BTRFS info (device dm-11): cleaning free space cache v1
[31682.560700] BTRFS info (device dm-11): checking UUID tree
[31682.807482] BTRFS info (device dm-11): flagging fs with big metadata feature
[31682.807700] BTRFS info (device dm-11): turning on sync discard
[31682.807874] BTRFS info (device dm-11): using free space tree
[31682.808066] BTRFS info (device dm-11): has skinny extents
[31683.029691] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[31683.052538] btrfs_dev_stat_print_on_error: 18 callbacks suppressed
[31683.052542] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[31683.054661] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[31683.056573] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[31683.058408] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[31683.060268] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[31683.062098] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[31683.064764] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[31683.068056] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[31683.069544] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[31683.089611] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (3916393)
[31683.090417] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (3916393)
[31683.125332] BTRFS info (device dm-11): flagging fs with big metadata feature
[31683.125555] BTRFS info (device dm-11): turning on sync discard
[31683.125734] BTRFS info (device dm-11): using free space tree
[31683.126956] BTRFS info (device dm-11): has skinny extents
[31683.129087] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[31683.257031] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[31683.257311] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3917127, name: dmsetup
[31683.257558] preempt_count: 100, expected: 0
[31683.257708] RCU nest depth: 0, expected: 0
[31683.257851] INFO: lockdep is turned off.
[31683.258000] Preemption disabled at:
[31683.258001] [] __do_softirq+0x5e/0x517
[31683.258331] CPU: 0 PID: 3917127 Comm: dmsetup Tainted: G        W         5.17.0+ #640
[31683.258574] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[31683.258832] Call Trace:
[31683.258957]  
[31683.259073]  dump_stack_lvl+0x56/0x6f
[31683.259217]  __might_resched.cold+0xff/0x13a
[31683.259368]  blk_release_queue+0x24/0x120
[31683.259514]  kobject_put+0x7c/0x1d0
[31683.259654]  ? rcu_do_batch+0x1ac/0x570
[31683.259796]  blkg_free.part.0+0x41/0x60
[31683.259942]  rcu_do_batch+0x1e1/0x570
[31683.260084]  ? rcu_do_batch+0x1ac/0x570
[31683.260228]  rcu_core+0x2cb/0x460
[31683.260362]  __do_softirq+0x178/0x517
[31683.260504]  __irq_exit_rcu+0xe3/0x160
[31683.260646]  irq_exit_rcu+0xa/0x20
[31683.260781]  sysvec_apic_timer_interrupt+0xa2/0xd0
[31683.260943]  
[31683.261058]  
[31683.261169]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[31683.261336] RIP: 0010:do_user_addr_fault+0x2ed/0x6b0
[31683.261499] Code: ff 0f 0b e9 4c ff ff ff 31 f6 4c 89 f7 44 89 04 24 e8 67 b5 29 00 44 8b 04 24 e9 18 ff ff ff e8 69 79 1a 00 fb 0f 1f 44 00 00  03 fe ff ff 66 81 3d e5 3c d7 01 0f 02 0f 84 29 01 00 00 48 83
[31683.261962] RSP: 0018:ffffb18fc3ac3c50 EFLAGS: 00000246
[31683.262127] RAX: 0000000080000000 RBX: 0000000000000214 RCX: 0000000000000000
[31683.262321] RDX: 000055a1ce6a5270 RSI: ffffffffb890b2d5 RDI: ffffffffb71048e7
[31683.262516] RBP: 0000000000000002 R08: 0000000000000000 R09: 0000000000000000
[31683.262713] R10: 0000000000000000 R11: 0000000000000000 R12: ffffb18fc3ac3cc8
[31683.262911] R13: 000055a1ce6a5270 R14: ffff9e59f1dadb00 R15: 0000000000000000
[31683.263111]  ? do_user_addr_fault+0x2e7/0x6b0
[31683.263266]  exc_page_fault+0x78/0x280
[31683.263405]  asm_exc_page_fault+0x1e/0x30
[31683.263552] RIP: 0010:__clear_user+0x40/0x70
[31683.263703] Code: 00 00 00 e8 b2 c6 b6 ff 0f 01 cb 48 89 d8 48 c1 eb 03 48 89 ef 83 e0 07 48 89 d9 48 85 c9 74 19 66 2e 0f 1f 84 00 00 00 00 00 <48> c7 07 00 00 00 00 48 83 c7 08 ff c9 75 f1 48 89 c1 85 c9 74 0a
[31683.264178] RSP: 0018:ffffb18fc3ac3d70 EFLAGS: 00050206
[31683.264344] RAX: 0000000000000000 RBX: 00000000000001b2 RCX: 00000000000001b2
[31683.264542] RDX: 0000000000000000 RSI: ffffffffb73a03ca RDI: 000055a1ce6a5270
[31683.264735] RBP: 000055a1ce6a5270 R08: 0000000000000001 R09: 0000000000000000
[31683.264953] R10: 0000000000000000 R11: ffff9e59f9e23f30 R12: ffff9e59d0f6bc00
[31683.265253] R13: 0000000000000000 R14: ffff9e59d0a2f200 R15: ffff9e59ce6a1a40
[31683.265458]  ? __might_fault+0x2a/0x70
[31683.265605]  load_elf_binary+0x1109/0x1720
[31683.265755]  bprm_execve+0x307/0x860
[31683.265894]  do_execveat_common+0x1b5/0x230
[31683.266053]  __x64_sys_execve+0x39/0x50
[31683.266202]  do_syscall_64+0x38/0x90
[31683.266340]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[31683.266502] RIP: 0033:0x7fd0ba1c62fb
[31683.266645] Code: Unable to access opcode bytes at RIP 0x7fd0ba1c62d1.
[31683.266832] RSP: 002b:00007ffc721a4178 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[31683.267074] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fd0ba1c62fb
[31683.267272] RDX: 000055c496824cb0 RSI: 000055c49655a7d0 RDI: 000055c49682a020
[31683.267469] RBP: 000055c49682a020 R08: 000055c49655a7d0 R09: 000055c400000001
[31683.267667] R10: 00000000000001b6 R11: 0000000000000246 R12: 00000000ffffffff
[31683.267864] R13: 000055c49655a7d0 R14: 000055c496824cb0 R15: 000055c4967ed600
[31683.268069]