[20360.379283] run fstests generic/276 at 2022-04-02 02:29:51
[20360.757548] BTRFS: device fsid bd8f96f4-6a02-4ba6-9f59-1ab01ea18bec devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (796019)
[20360.770697] BTRFS info (device dm-7): flagging fs with big metadata feature
[20360.770945] BTRFS info (device dm-7): use zlib compression, level 3
[20360.771152] BTRFS info (device dm-7): using free space tree
[20360.771340] BTRFS info (device dm-7): has skinny extents
[20360.795626] BTRFS info (device dm-7): checking UUID tree
[20360.968689] BTRFS: device fsid d8f2670c-520f-4819-bbc0-a8cf800b9b3b devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (796095)
[20361.044167] BTRFS info (device dm-9): flagging fs with big metadata feature
[20361.044386] BTRFS info (device dm-9): use zlib compression, level 3
[20361.044570] BTRFS info (device dm-9): using free space tree
[20361.044761] BTRFS info (device dm-9): has skinny extents
[20361.046703] BTRFS info (device dm-9): checking UUID tree
[20361.230803] BTRFS info (device dm-9): flagging fs with big metadata feature
[20361.231555] BTRFS info (device dm-9): use zlib compression, level 3
[20361.232278] BTRFS info (device dm-9): using free space tree
[20361.232936] BTRFS info (device dm-9): has skinny extents
[20361.525570] Buffer I/O error on dev dm-9, logical block 2621424, async page read
[20361.566835] btrfs_dev_stat_print_on_error: 126 callbacks suppressed
[20361.566841] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[20361.567656] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[20361.578724] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[20361.584950] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[20361.585254] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[20361.585561] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[20361.586068] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[20361.586370] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[20361.586804] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[20361.587113] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[20361.604493] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (795368)
[20361.605267] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (795368)
[20361.682084] BTRFS info (device dm-9): flagging fs with big metadata feature
[20361.682639] BTRFS info (device dm-9): use zlib compression, level 3
[20361.683210] BTRFS info (device dm-9): using free space tree
[20361.683640] BTRFS info (device dm-9): has skinny extents
[20361.688063] BTRFS info (device dm-9): bdev /dev/mapper/error-test errs: wr 64, rd 0, flush 0, corrupt 0, gen 0
[20361.846691] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[20361.847123] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 796215, name: fsck
[20361.847473] preempt_count: 101, expected: 0
[20361.847715] RCU nest depth: 1, expected: 0
[20361.848039] INFO: lockdep is turned off.
[20361.848295] Preemption disabled at:
[20361.848296] [] __blk_mq_delay_run_hw_queue+0x10a/0x210
[20361.848773] CPU: 1 PID: 796215 Comm: fsck Tainted: G W 5.17.0+ #639
[20361.849144] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[20361.849463] Call Trace:
[20361.849617]
[20361.849769] dump_stack_lvl+0x56/0x6f
[20361.849957] __might_resched.cold+0xff/0x13a
[20361.850147] blk_release_queue+0x24/0x120
[20361.850331] kobject_put+0x7c/0x1d0
[20361.850505] ? rcu_do_batch+0x1ac/0x570
[20361.850693] blkg_free.part.0+0x41/0x60
[20361.850877] rcu_do_batch+0x1e1/0x570
[20361.851051] ? rcu_do_batch+0x1ac/0x570
[20361.851233] rcu_core+0x2cb/0x460
[20361.851401] __do_softirq+0x178/0x517
[20361.851576] __irq_exit_rcu+0xe3/0x160
[20361.851764] irq_exit_rcu+0xa/0x20
[20361.851940] sysvec_apic_timer_interrupt+0xa2/0xd0
[20361.852136]
[20361.852277]
[20361.852419] asm_sysvec_apic_timer_interrupt+0x12/0x20
[20361.852622] RIP: 0010:iowrite16+0xc/0x50
[20361.852806] Code: 48 c7 c7 40 44 89 93 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
[20361.853388] RSP: 0018:ffffa59281a8b830 EFLAGS: 00010292
[20361.853597] RAX: ffffffff928ff080 RBX: ffff93608133c900 RCX: 0000000000000000
[20361.853818] RDX: 0000000000000002 RSI: ffffa59280151000 RDI: 0000000000000000
[20361.854036] RBP: ffff936082d6a000 R08: 0000000000000000 R09: 0000000000000000
[20361.854250] R10: 00000000000005a0 R11: 0000000000000002 R12: ffff936085e60480
[20361.854460] R13: ffff936082d67800 R14: 0000000000000000 R15: ffff936085e60590
[20361.854683] ? vp_enable_cbs+0x70/0x70
[20361.854858] vp_notify+0x12/0x20
[20361.855014] virtqueue_notify+0x18/0x30
[20361.855170] virtio_queue_rq+0x424/0x600
[20361.855333] blk_mq_dispatch_rq_list+0x1de/0x8a0
[20361.855510] ? rcu_tasks_trace_pregp_step+0x20/0x60
[20361.855699] ? sbitmap_get+0x93/0x1b0
[20361.855859] blk_mq_do_dispatch_sched+0x318/0x380
[20361.856035] ? rcu_read_lock_sched_held+0x12/0x60
[20361.856211] __blk_mq_sched_dispatch_requests+0xd1/0x130
[20361.856398] blk_mq_sched_dispatch_requests+0x33/0x60
[20361.856573] __blk_mq_run_hw_queue+0x6b/0x1b0
[20361.856747] __blk_mq_delay_run_hw_queue+0x1c6/0x210
[20361.856933] blk_mq_sched_insert_requests+0xc1/0x430
[20361.857116] blk_mq_flush_plug_list+0x11b/0x560
[20361.857287] blk_finish_plug+0x41/0x70
[20361.857440] read_pages+0x17c/0x3d0
[20361.857597] page_cache_ra_unbounded+0x1a4/0x1f0
[20361.857780] force_page_cache_ra+0xc5/0x100
[20361.857949] filemap_get_pages+0xca/0x740
[20361.858109] ? atime_needs_update+0xf0/0x160
[20361.858279] filemap_read+0xbe/0x340
[20361.858450] ? rcu_read_lock_sched_held+0x12/0x60
[20361.858603] ? lock_acquire+0x176/0x2d0
[20361.858750] blkdev_read_iter+0xac/0x1c0
[20361.858908] new_sync_read+0xf3/0x160
[20361.859052] vfs_read+0x15f/0x1b0
[20361.859183] ksys_read+0x55/0xd0
[20361.859318] do_syscall_64+0x38/0x90
[20361.859457] entry_SYSCALL_64_after_hwframe+0x44/0xae
[20361.859617] RIP: 0033:0x7fbfce0182e2
[20361.859763] Code: c0 e9 b2 fe ff ff 50 48 8d 3d ea 2e 0a 00 e8 95 e9 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
[20361.860227] RSP: 002b:00007ffeb95d6a18 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[20361.860461] RAX: ffffffffffffffda RBX: 000055807a48d078 RCX: 00007fbfce0182e2
[20361.860657] RDX: 0000000000000200 RSI: 000055807a48d088 RDI: 0000000000000003
[20361.860859] RBP: 000055807a48cad0 R08: 000055807a48d060 R09: 00007fbfce0eaa60
[20361.861564] R10: 000055807a48da90 R11: 0000000000000246 R12: 000000027ffffe00
[20361.861774] R13: 0000000000000200 R14: 000055807a48d060 R15: 000055807a48cb20
[20361.861979]