[31683.373989] run fstests generic/266 at 2022-04-03 05:39:31
[31683.599677] BTRFS info (device dm-0): flagging fs with big metadata feature
[31683.599900] BTRFS info (device dm-0): using free space tree
[31683.600103] BTRFS info (device dm-0): has skinny extents
[31683.721905] BTRFS: device fsid 9ef66021-5a7d-4a7f-90ab-cdc22d47cc6f devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3917397)
[31683.742319] BTRFS info (device dm-9): flagging fs with big metadata feature
[31683.742539] BTRFS info (device dm-9): turning on sync discard
[31683.742714] BTRFS info (device dm-9): enabling free space tree
[31683.742888] BTRFS info (device dm-9): using free space tree
[31683.743085] BTRFS info (device dm-9): has skinny extents
[31683.744791] BTRFS info (device dm-9): creating free space tree
[31683.752599] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[31683.752887] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[31683.768547] BTRFS info (device dm-9): cleaning free space cache v1
[31683.771791] BTRFS info (device dm-9): checking UUID tree
[31683.898758] BTRFS: device fsid 4872b440-5201-475a-8f20-aea23b41034a devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3917470)
[31683.990418] BTRFS info (device dm-11): flagging fs with big metadata feature
[31683.990645] BTRFS info (device dm-11): turning on sync discard
[31683.990818] BTRFS info (device dm-11): enabling free space tree
[31683.990999] BTRFS info (device dm-11): using free space tree
[31683.991185] BTRFS info (device dm-11): has skinny extents
[31683.993158] BTRFS info (device dm-11): creating free space tree
[31683.993425] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[31683.993695] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[31683.999303] BTRFS info (device dm-11): cleaning free space cache v1
[31684.002797] BTRFS info (device dm-11): checking UUID tree
[31684.233830] BTRFS info (device dm-11): flagging fs with big metadata feature
[31684.234072] BTRFS info (device dm-11): turning on sync discard
[31684.234251] BTRFS info (device dm-11): using free space tree
[31684.234424] BTRFS info (device dm-11): has skinny extents
[31684.450717] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[31684.474869] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[31684.505895] BTRFS: error (device dm-11) in btrfs_commit_transaction:2418: errno=-5 IO failure (Error while writing out transaction)
[31684.506242] BTRFS info (device dm-11: state E): forced readonly
[31684.506420] BTRFS warning (device dm-11: state E): Skipping commit of aborted transaction.
[31684.506667] BTRFS: error (device dm-11: state EA) in cleanup_transaction:1982: errno=-5 IO failure
[31684.508192] BTRFS error (device dm-11: state EA): commit super ret -5
[31684.541014] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[31684.541306] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3917565, name: fsck
[31684.541558] preempt_count: 101, expected: 0
[31684.541703] RCU nest depth: 1, expected: 0
[31684.541848] INFO: lockdep is turned off.
[31684.542011] Preemption disabled at:
[31684.542012] [] __blk_mq_delay_run_hw_queue+0x10a/0x210
[31684.542341] CPU: 1 PID: 3917565 Comm: fsck Tainted: G        W         5.17.0+ #640
[31684.542579] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[31684.542838] Call Trace:
[31684.542967]  
[31684.543084]  dump_stack_lvl+0x56/0x6f
[31684.543227]  __might_resched.cold+0xff/0x13a
[31684.543382]  blk_release_queue+0x24/0x120
[31684.543532]  kobject_put+0x7c/0x1d0
[31684.543671]  ? rcu_do_batch+0x1ac/0x570
[31684.543813]  blkg_free.part.0+0x41/0x60
[31684.543962]  rcu_do_batch+0x1e1/0x570
[31684.544104]  ? rcu_do_batch+0x1ac/0x570
[31684.544248]  rcu_core+0x2cb/0x460
[31684.544385]  __do_softirq+0x178/0x517
[31684.544530]  __irq_exit_rcu+0xe3/0x160
[31684.544670]  irq_exit_rcu+0xa/0x20
[31684.544804]  sysvec_apic_timer_interrupt+0xa2/0xd0
[31684.544974]  
[31684.545090]  
[31684.545203]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[31684.545370] RIP: 0010:iowrite16+0xc/0x50
[31684.545519] Code: 48 c7 c7 40 44 89 b8 89 05 81 e3 58 01 e8 83 fc 62 00 0f 0b c3 66 0f 1f 84 00 00 00 00 00 48 81 fe ff ff 03 00 76 04 66 89 3e  48 81 fe 00 00 01 00 76 08 0f b7 d6 89 f8 66 ef c3 8b 05 4c e3
[31684.545979] RSP: 0018:ffffb18fc4233830 EFLAGS: 00010292
[31684.546145] RAX: ffffffffb78ff080 RBX: ffff9e59c13ea600 RCX: 0000000000000000
[31684.546343] RDX: 0000000000000002 RSI: ffffb18fc0151000 RDI: 0000000000000000
[31684.546542] RBP: ffff9e59c2da2800 R08: 0000000000000000 R09: 0000000000000000
[31684.546738] R10: 00000000000004a0 R11: 0000000000000002 R12: ffff9e59c5f31380
[31684.546945] R13: ffff9e59c2d9e800 R14: 0000000000000000 R15: ffff9e59c5f31490
[31684.547142]  ? vp_enable_cbs+0x70/0x70
[31684.547288]  vp_notify+0x12/0x20
[31684.547422]  virtqueue_notify+0x18/0x30
[31684.547567]  virtio_queue_rq+0x424/0x600
[31684.547715]  blk_mq_dispatch_rq_list+0x1de/0x8a0
[31684.547871]  ? rcu_tasks_trace_pregp_step+0x20/0x60
[31684.548036]  ? sbitmap_get+0x93/0x1b0
[31684.548178]  blk_mq_do_dispatch_sched+0x318/0x380
[31684.548334]  ? rcu_read_lock_sched_held+0x12/0x60
[31684.548490]  __blk_mq_sched_dispatch_requests+0xd1/0x130
[31684.548656]  blk_mq_sched_dispatch_requests+0x33/0x60
[31684.548821]  __blk_mq_run_hw_queue+0x6b/0x1b0
[31684.548985]  __blk_mq_delay_run_hw_queue+0x1c6/0x210
[31684.549145]  blk_mq_sched_insert_requests+0xc1/0x430
[31684.549307]  blk_mq_flush_plug_list+0x11b/0x560
[31684.549463]  blk_finish_plug+0x41/0x70
[31684.549608]  read_pages+0x17c/0x3d0
[31684.549747]  page_cache_ra_unbounded+0x1a4/0x1f0
[31684.549913]  force_page_cache_ra+0xc5/0x100
[31684.550065]  filemap_get_pages+0xca/0x740
[31684.550213]  ? atime_needs_update+0xf0/0x160
[31684.550366]  filemap_read+0xbe/0x340
[31684.550505]  ? rcu_read_lock_sched_held+0x12/0x60
[31684.550692]  ? lock_acquire+0x176/0x2d0
[31684.550839]  blkdev_read_iter+0xac/0x1c0
[31684.550994]  new_sync_read+0xf3/0x160
[31684.551136]  vfs_read+0x15f/0x1b0
[31684.551275]  ksys_read+0x55/0xd0
[31684.551408]  do_syscall_64+0x38/0x90
[31684.551546]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[31684.551710] RIP: 0033:0x7fa1fb21f4f2
[31684.551852] Code: c0 e9 b2 fe ff ff 50 48 8d 3d b2 39 0a 00 e8 95 f0 01 00 0f 1f 44 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 0f 05 <48> 3d 00 f0 ff ff 77 56 c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24
[31684.552315] RSP: 002b:00007fff74024e48 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[31684.552555] RAX: ffffffffffffffda RBX: 000055ac429734e8 RCX: 00007fa1fb21f4f2
[31684.552748] RDX: 0000000000000018 RSI: 000055ac429734f8 RDI: 0000000000000003
[31684.552953] RBP: 000055ac429727d0 R08: 000055ac429734d0 R09: 00007fa1fb2f1a60
[31684.553147] R10: 000055ac42973790 R11: 0000000000000246 R12: 000000027ff86400
[31684.553343] R13: 0000000000000018 R14: 000055ac429734d0 R15: 000055ac42972820
[31684.553539]  
[31684.566425] BTRFS info (device dm-9): flagging fs with big metadata feature
[31684.566645] BTRFS info (device dm-9): turning on sync discard
[31684.566824] BTRFS info (device dm-9): using free space tree
[31684.567024] BTRFS info (device dm-9): has skinny extents