[25369.282363] run fstests generic/283 at 2022-04-04 03:54:13
[25369.539535] BTRFS info (device dm-0): flagging fs with big metadata feature
[25369.539761] BTRFS info (device dm-0): using free space tree
[25369.539952] BTRFS info (device dm-0): has skinny extents
[25369.748664] BTRFS: device fsid 369e7209-95a0-4049-b9d5-dbebee8ed93e devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (972094)
[25369.762456] BTRFS info (device dm-9): flagging fs with big metadata feature
[25369.762688] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[25369.762967] BTRFS info (device dm-9): use zstd compression, level 3
[25369.763156] BTRFS info (device dm-9): turning on async discard
[25369.763394] BTRFS info (device dm-9): disk space caching is enabled
[25369.763634] BTRFS info (device dm-9): has skinny extents
[25369.765466] BTRFS info (device dm-9): checking UUID tree
[25370.036777] BTRFS: device fsid ad0af34b-a81c-4163-a49f-c9fdaed634a5 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (972167)
[25370.155565] BTRFS info (device dm-11): flagging fs with big metadata feature
[25370.155796] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[25370.156075] BTRFS info (device dm-11): use zstd compression, level 3
[25370.156260] BTRFS info (device dm-11): turning on async discard
[25370.156457] BTRFS info (device dm-11): disk space caching is enabled
[25370.156683] BTRFS info (device dm-11): has skinny extents
[25370.158366] BTRFS info (device dm-11): checking UUID tree
[25370.305115] BTRFS info (device dm-11): flagging fs with big metadata feature
[25370.305340] BTRFS info (device dm-11): use zstd compression, level 3
[25370.305532] BTRFS info (device dm-11): turning on async discard
[25370.305714] BTRFS info (device dm-11): disk space caching is enabled
[25370.305929] BTRFS info (device dm-11): has skinny extents
[25370.553233] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[25370.619934] btrfs_dev_stat_print_on_error: 641 callbacks suppressed
[25370.619938] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[25370.620459] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[25370.620783] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[25370.621118] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[25370.621426] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[25370.621753] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[25370.622072] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[25370.622408] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[25370.622757] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[25370.623077] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[25370.660874] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (968458)
[25370.661647] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (968458)
[25370.891044] BTRFS info (device dm-11): flagging fs with big metadata feature
[25370.891268] BTRFS info (device dm-11): use zstd compression, level 3
[25370.891452] BTRFS info (device dm-11): turning on async discard
[25370.891632] BTRFS info (device dm-11): disk space caching is enabled
[25370.891810] BTRFS info (device dm-11): has skinny extents
[25370.893260] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 320, rd 0, flush 0, corrupt 0, gen 0
[25371.036869] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[25371.037148] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 972292, name: fsck
[25371.037406] preempt_count: 101, expected: 0
[25371.037553] RCU nest depth: 0, expected: 0
[25371.037701] INFO: lockdep is turned off.
[25371.037838] Preemption disabled at:
[25371.037839] [<0000000000000000>] 0x0
[25371.038139] CPU: 0 PID: 972292 Comm: fsck Tainted: G        W         5.17.0+ #641
[25371.038378] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[25371.038636] Call Trace:
[25371.038755]  
[25371.038873]  dump_stack_lvl+0x56/0x6f
[25371.039013]  __might_resched.cold+0xff/0x13a
[25371.039161]  blk_release_queue+0x24/0x120
[25371.039307]  kobject_put+0x7c/0x1d0
[25371.039445]  ? rcu_do_batch+0x1ac/0x570
[25371.039591]  blkg_free.part.0+0x41/0x60
[25371.039733]  rcu_do_batch+0x1e1/0x570
[25371.039877]  ? rcu_do_batch+0x1ac/0x570
[25371.040018]  rcu_core+0x2cb/0x460
[25371.040155]  __do_softirq+0x178/0x517
[25371.040296]  __irq_exit_rcu+0xe3/0x160
[25371.040434]  irq_exit_rcu+0xa/0x20
[25371.040566]  sysvec_apic_timer_interrupt+0xa2/0xd0
[25371.040727]  
[25371.040840]  
[25371.040950]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[25371.041112] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[25371.041283] Code: 48 83 c7 18 53 48 89 f3 48 8b 74 24 10 e8 45 1b 38 ff 48 89 ef e8 dd 44 38 ff 80 e7 02 74 0b e8 53 70 44 ff fb 0f 1f 44 00 00  01 00 00 00 e8 93 d8 33 ff 65 8b 05 6c ac 1b 5d 85 c0 74 03 5b
[25371.041735] RSP: 0018:ffffb1264301fe70 EFLAGS: 00000246
[25371.041900] RAX: 0000000000000001 RBX: 0000000000000286 RCX: 0000000000000000
[25371.042095] RDX: 0000000000000002 RSI: ffffffffa21af6bc RDI: ffffffffa2e651fd
[25371.042287] RBP: ffff9be282fa3f30 R08: 0000000000000000 R09: 0000000000000000
[25371.048143] R10: 00000000000000b6 R11: 0000000000000000 R12: ffff9be282fa3200
[25371.048334] R13: 0000000000000001 R14: ffff9be28304bb38 R15: 0000000000000000
[25371.048529]  ? sched_move_task+0x7c/0x170
[25371.049310]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[25371.049483]  sched_move_task+0x7c/0x170
[25371.049648]  do_exit+0x3ac/0xbf0
[25371.049782]  ? up_read+0x17/0x20
[25371.049917]  do_group_exit+0x33/0xb0
[25371.050058]  __x64_sys_exit_group+0x14/0x20
[25371.050218]  do_syscall_64+0x38/0x90
[25371.050356]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[25371.050528] RIP: 0033:0x7f358a6682d1
[25371.050685] Code: Unable to access opcode bytes at RIP 0x7f358a6682a7.
[25371.050880] RSP: 002b:00007ffc15690008 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
[25371.051120] RAX: ffffffffffffffda RBX: 00007f358a760490 RCX: 00007f358a6682d1
[25371.051313] RDX: 000000000000003c RSI: 00000000000000e7 RDI: 0000000000000000
[25371.051511] RBP: 0000000000000000 R08: ffffffffffffff88 R09: 0000000000000001
[25371.051727] R10: 0000000000000006 R11: 0000000000000246 R12: 00007f358a760490
[25371.051922] R13: 0000000000000002 R14: 00007f358a760968 R15: 0000000000000000
[25371.052115]