[38673.569175] run fstests generic/331 at 2022-04-04 07:35:58
[38673.798917] BTRFS info (device dm-0): flagging fs with big metadata feature
[38673.799154] BTRFS info (device dm-0): using free space tree
[38673.799327] BTRFS info (device dm-0): has skinny extents
[38673.986886] BTRFS: device fsid 43e2954e-d71c-4b31-b83b-96166904417a devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3987463)
[38674.000183] BTRFS info (device dm-9): flagging fs with big metadata feature
[38674.000403] BTRFS info (device dm-9): turning on sync discard
[38674.000579] BTRFS info (device dm-9): enabling free space tree
[38674.000755] BTRFS info (device dm-9): using free space tree
[38674.000921] BTRFS info (device dm-9): has skinny extents
[38674.002589] BTRFS info (device dm-9): creating free space tree
[38674.002848] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[38674.003161] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[38674.012557] BTRFS info (device dm-9): cleaning free space cache v1
[38674.020653] BTRFS info (device dm-9): checking UUID tree
[38674.354096] BTRFS: device fsid 8223ccbd-1750-49ae-96e1-a7cc51c9e4eb devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3987540)
[38674.497675] BTRFS info (device dm-11): flagging fs with big metadata feature
[38674.497897] BTRFS info (device dm-11): turning on sync discard
[38674.498102] BTRFS info (device dm-11): enabling free space tree
[38674.498280] BTRFS info (device dm-11): using free space tree
[38674.498450] BTRFS info (device dm-11): has skinny extents
[38674.500384] BTRFS info (device dm-11): creating free space tree
[38674.500633] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[38674.507235] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[38674.546756] BTRFS info (device dm-11): cleaning free space cache v1
[38674.598969] BTRFS info (device dm-11): checking UUID tree
[38674.950924] BTRFS info (device dm-11): flagging fs with big metadata feature
[38674.951181] BTRFS info (device dm-11): turning on sync discard
[38674.951361] BTRFS info (device dm-11): using free space tree
[38674.951536] BTRFS info (device dm-11): has skinny extents
[38675.525403] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[38675.587958] btrfs_dev_stat_print_on_error: 2 callbacks suppressed
[38675.587961] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[38675.589726] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[38675.591353] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[38675.593160] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[38675.594996] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[38675.596877] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[38675.598819] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[38675.602180] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[38675.605220] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[38675.622237] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (3985813)
[38675.623078] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (3985813)
[38675.683016] BTRFS info (device dm-11): flagging fs with big metadata feature
[38675.683298] BTRFS info (device dm-11): turning on sync discard
[38675.683504] BTRFS info (device dm-11): using free space tree
[38675.683705] BTRFS info (device dm-11): has skinny extents
[38675.685661] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[38675.927096] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[38675.927433] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3987669, name: rm
[38675.927756] preempt_count: 100, expected: 0
[38675.927959] RCU nest depth: 0, expected: 0
[38675.928147] INFO: lockdep is turned off.
[38675.928311] Preemption disabled at:
[38675.928312] [] __do_softirq+0x5e/0x517
[38675.928621] CPU: 0 PID: 3987669 Comm: rm Tainted: G W 5.17.0+ #641
[38675.928865] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[38675.929159] Call Trace:
[38675.929278]
[38675.929393] dump_stack_lvl+0x56/0x6f
[38675.929533] __might_resched.cold+0xff/0x13a
[38675.929695] blk_release_queue+0x24/0x120
[38675.929845] kobject_put+0x7c/0x1d0
[38675.929985] ? rcu_do_batch+0x1ac/0x570
[38675.930129] blkg_free.part.0+0x41/0x60
[38675.930281] rcu_do_batch+0x1e1/0x570
[38675.930429] ? rcu_do_batch+0x1ac/0x570
[38675.930582] rcu_core+0x2cb/0x460
[38675.930714] __do_softirq+0x178/0x517
[38675.930857] __irq_exit_rcu+0xe3/0x160
[38675.931002] irq_exit_rcu+0xa/0x20
[38675.931145] sysvec_apic_timer_interrupt+0xa2/0xd0
[38675.931305]
[38675.931419]
[38675.931530] asm_sysvec_apic_timer_interrupt+0x12/0x20
[38675.931695] RIP: 0010:btrfs_page_clear_checked+0x53/0x60
[38675.931862] Code: 80 48 fb ff ff 4d 85 c0 75 21 4c 8b 40 40 49 83 f8 01 74 05 e9 3e e8 ff ff 81 bf 70 20 00 00 ff 0f 00 00 76 ef f0 80 66 01 fb 80 b8 50 fb ff ff 84 75 da eb d4 90 0f 1f 44 00 00 48 85 ff 74
[38675.932317] RSP: 0018:ffffb126421d7c28 EFLAGS: 00000202
[38675.932479] RAX: 0017ffffc0002035 RBX: 000000000077f000 RCX: 0000000000001000
[38675.932675] RDX: 000000000077e000 RSI: ffffed3005939b80 RDI: ffff9be293300000
[38675.932870] RBP: ffffed3005939b80 R08: 0000000000000000 R09: 0000000000000000
[38675.933071] R10: 0000000000000004 R11: ffffffffa23cd75c R12: 0000000000001000
[38675.933267] R13: 000000000077efff R14: ffff9be2aea8ae90 R15: 000000000077efff
[38675.933464] ? free_unref_page_list+0x12c/0x560
[38675.933620] btrfs_invalidate_folio+0x295/0x420
[38675.933775] truncate_cleanup_folio+0x72/0xb0
[38675.933926] truncate_inode_pages_range+0xeb/0x5f0
[38675.934091] btrfs_evict_inode+0x88/0x510
[38675.934239] ? rcu_read_lock_sched_held+0x12/0x60
[38675.934392] ? lock_release+0x1ed/0x2c0
[38675.934532] ? collect_percpu_times+0x280/0x280
[38675.934687] evict+0xcc/0x1d0
[38675.934816] do_unlinkat+0x1a5/0x2a0
[38675.934956] __x64_sys_unlinkat+0x34/0x60
[38675.935107] do_syscall_64+0x38/0x90
[38675.935247] entry_SYSCALL_64_after_hwframe+0x44/0xae
[38675.935407] RIP: 0033:0x7f985f8380cb
[38675.935545] Code: 73 01 c3 48 8b 0d ad fd 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa b8 07 01 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 7d fd 0c 00 f7 d8 64 89 01 48
[38675.936008] RSP: 002b:00007ffc209f1488 EFLAGS: 00000246 ORIG_RAX: 0000000000000107
[38675.936244] RAX: ffffffffffffffda RBX: 000055d9ec0a06c0 RCX: 00007f985f8380cb
[38675.936439] RDX: 0000000000000000 RSI: 000055d9ec09f380 RDI: 00000000ffffff9c
[38675.936634] RBP: 000055d9ec09f2f0 R08: 0000000000000000 R09: 0000000000000000
[38675.936827] R10: 00007ffc209f15b0 R11: 0000000000000246 R12: 0000000000000000
[38675.937025] R13: 00007ffc209f15b0 R14: 0000000000000002 R15: 0000000000000002
[38675.937223]