[ 9664.906167] run fstests generic/331 at 2022-04-03 23:31:58
[ 9665.167766] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 9665.168212] BTRFS info (device dm-0): using free space tree
[ 9665.168549] BTRFS info (device dm-0): has skinny extents
[ 9665.473556] BTRFS: device fsid 1de02576-3b3f-4f6e-b4c0-2f20a23593c3 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1836963)
[ 9665.505560] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 9665.505990] BTRFS info (device dm-9): disk space caching is enabled
[ 9665.506384] BTRFS info (device dm-9): has skinny extents
[ 9665.508108] BTRFS info (device dm-9): checking UUID tree
[ 9665.847425] BTRFS: device fsid 7a964d63-c8f2-4c8c-82c9-eac3f610838d devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1837041)
[ 9665.938774] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 9665.939229] BTRFS info (device dm-11): disk space caching is enabled
[ 9665.939615] BTRFS info (device dm-11): has skinny extents
[ 9665.941570] BTRFS info (device dm-11): checking UUID tree
[ 9666.495669] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 9666.496209] BTRFS info (device dm-11): disk space caching is enabled
[ 9666.496650] BTRFS info (device dm-11): has skinny extents
[ 9667.076349] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[ 9667.147749] btrfs_dev_stat_print_on_error: 4 callbacks suppressed
[ 9667.147756] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 9667.153127] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[ 9667.158088] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[ 9667.163308] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[ 9667.168235] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[ 9667.172736] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[ 9667.176534] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[ 9667.178705] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[ 9667.180897] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[ 9667.183478] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[ 9667.199743] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (1834630)
[ 9667.200841] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (1834630)
[ 9667.240129] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 9667.240615] BTRFS info (device dm-11): disk space caching is enabled
[ 9667.240987] BTRFS info (device dm-11): has skinny extents
[ 9667.242686] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[ 9667.545068] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 9667.545564] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1837170, name: rm
[ 9667.546018] preempt_count: 101, expected: 0
[ 9667.546285] RCU nest depth: 0, expected: 0
[ 9667.546531] INFO: lockdep is turned off.
[ 9667.546766] Preemption disabled at:
[ 9667.546767] [<0000000000000000>] 0x0
[ 9667.547204] CPU: 1 PID: 1837170 Comm: rm Tainted: G W 5.17.0+ #641
[ 9667.547678] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 9667.548179] Call Trace:
[ 9667.548336]
[ 9667.548462] dump_stack_lvl+0x56/0x6f
[ 9667.548687] __might_resched.cold+0xff/0x13a
[ 9667.548944] blk_release_queue+0x24/0x120
[ 9667.549188] kobject_put+0x7c/0x1d0
[ 9667.549405] ? rcu_do_batch+0x1ac/0x570
[ 9667.549636] blkg_free.part.0+0x41/0x60
[ 9667.549868] rcu_do_batch+0x1e1/0x570
[ 9667.550088] ? rcu_do_batch+0x1ac/0x570
[ 9667.550324] rcu_core+0x2cb/0x460
[ 9667.550527] __do_softirq+0x178/0x517
[ 9667.550751] __irq_exit_rcu+0xe3/0x160
[ 9667.550979] irq_exit_rcu+0xa/0x20
[ 9667.551185] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 9667.551475]
[ 9667.551606]
[ 9667.551737] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 9667.552042] RIP: 0010:find_free_extent+0x11d0/0x1440
[ 9667.552346] Code: b8 21 00 00 48 39 45 50 0f 84 74 f7 ff ff 48 85 c0 0f 84 6b f7 ff ff e9 a1 ce 81 00 65 ff 05 f7 95 a4 56 48 8b 05 f0 42 86 01 cb f2 c2 ff 85 c0 0f 84 30 01 00 00 65 ff 0d dc 95 a4 56 0f 85
[ 9667.553440] RSP: 0018:ffffa28447903990 EFLAGS: 00000283
[ 9667.553751] RAX: 0000000000000000 RBX: ffff94fc7e315000 RCX: ffffa28447903b57
[ 9667.554170] RDX: 0000000000000001 RSI: ffffffffa9693603 RDI: 00000000ffffffff
[ 9667.554593] RBP: ffff94fc7e315000 R08: 0000000000000000 R09: 0000000000000000
[ 9667.555012] R10: 0000000000004000 R11: ffff94fc444f8000 R12: ffff94fc444f9208
[ 9667.555436] R13: 0000000001e74000 R14: ffffa28447903a40 R15: ffff94fc444f8000
[ 9667.555856] ? btrfs_add_reserved_bytes+0x113/0x280
[ 9667.556152] ? btrfs_get_alloc_profile+0x116/0x240
[ 9667.556444] btrfs_reserve_extent+0x13a/0x250
[ 9667.556708] btrfs_alloc_tree_block+0xba/0x3b0
[ 9667.556978] ? lock_acquire+0x176/0x2d0
[ 9667.557212] __btrfs_cow_block+0x136/0x600
[ 9667.557464] btrfs_cow_block+0x10b/0x230
[ 9667.557701] btrfs_search_slot+0x53b/0xb70
[ 9667.557950] ? rcu_read_lock_sched_held+0x12/0x60
[ 9667.558234] btrfs_lookup_dir_item+0x6c/0xc0
[ 9667.558497] __btrfs_unlink_inode+0x94/0x3a0
[ 9667.558756] btrfs_unlink+0x88/0x110
[ 9667.558974] vfs_unlink+0x110/0x290
[ 9667.559188] do_unlinkat+0x178/0x2a0
[ 9667.559411] __x64_sys_unlinkat+0x34/0x60
[ 9667.559654] do_syscall_64+0x38/0x90
[ 9667.559871] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 9667.560172] RIP: 0033:0x7f4c1e43826b
[ 9667.560394] Code: 73 01 c3 48 8b 0d 05 fc 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 d5 fb 0c 00 f7 d8 64 89 01 48
[ 9667.561487] RSP: 002b:00007fff4de29b48 EFLAGS: 00000246 ORIG_RAX: 0000000000000107
[ 9667.561933] RAX: ffffffffffffffda RBX: 000055703e56a6c0 RCX: 00007f4c1e43826b
[ 9667.562358] RDX: 0000000000000000 RSI: 000055703e569380 RDI: 00000000ffffff9c
[ 9667.562777] RBP: 000055703e5692f0 R08: 0000000000000000 R09: 0000000000000000
[ 9667.563198] R10: 00007fff4de29c70 R11: 0000000000000246 R12: 0000000000000000
[ 9667.563627] R13: 00007fff4de29c70 R14: 0000000000000002 R15: 0000000000000002
[ 9667.564064]