[ 7629.555963] run fstests generic/276 at 2022-04-02 23:09:36
[ 7629.782456] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 7629.782907] BTRFS info (device dm-0): using free space tree
[ 7629.783239] BTRFS info (device dm-0): has skinny extents
[ 7629.920839] BTRFS: device fsid 29105513-73a9-4b4c-beb8-2447a312cc74 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1677284)
[ 7629.934853] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 7629.935290] BTRFS info (device dm-9): disk space caching is enabled
[ 7629.935663] BTRFS info (device dm-9): has skinny extents
[ 7629.937653] BTRFS info (device dm-9): checking UUID tree
[ 7630.103141] BTRFS: device fsid fbc2e9de-9b6b-4f22-ac44-2d7cf0551972 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1677360)
[ 7630.184055] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 7630.184493] BTRFS info (device dm-11): disk space caching is enabled
[ 7630.184891] BTRFS info (device dm-11): has skinny extents
[ 7630.186869] BTRFS info (device dm-11): checking UUID tree
[ 7630.426418] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 7630.426871] BTRFS info (device dm-11): disk space caching is enabled
[ 7630.427250] BTRFS info (device dm-11): has skinny extents
[ 7630.653031] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[ 7630.664254] btrfs_dev_stat_print_on_error: 2 callbacks suppressed
[ 7630.664258] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 7630.665212] BTRFS warning (device dm-11): direct IO failed ino 259 rw 1,34817 sector 0x126f80 len 1822720 err no 10
[ 7630.681271] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (1677084)
[ 7630.682350] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (1677084)
[ 7630.734603] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 7630.735039] BTRFS info (device dm-11): disk space caching is enabled
[ 7630.735400] BTRFS info (device dm-11): has skinny extents
[ 7630.737118] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 7630.865689] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 7630.866184] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1677483, name: 276
[ 7630.866642] preempt_count: 101, expected: 0
[ 7630.866914] RCU nest depth: 0, expected: 0
[ 7630.867161] INFO: lockdep is turned off.
[ 7630.867395] Preemption disabled at:
[ 7630.867396] [<0000000000000000>] 0x0
[ 7630.867887] CPU: 1 PID: 1677483 Comm: 276 Tainted: G        W         5.17.0+ #640
[ 7630.868336] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 7630.868838] Call Trace:
[ 7630.868990]  
[ 7630.869117]  dump_stack_lvl+0x56/0x6f
[ 7630.869342]  __might_resched.cold+0xff/0x13a
[ 7630.869600]  blk_release_queue+0x24/0x120
[ 7630.869846]  kobject_put+0x7c/0x1d0
[ 7630.870060]  ? rcu_do_batch+0x1ac/0x570
[ 7630.870293]  blkg_free.part.0+0x41/0x60
[ 7630.870525]  rcu_do_batch+0x1e1/0x570
[ 7630.870751]  ? rcu_do_batch+0x1ac/0x570
[ 7630.870985]  rcu_core+0x2cb/0x460
[ 7630.871187]  __do_softirq+0x178/0x517
[ 7630.871411]  __irq_exit_rcu+0xe3/0x160
[ 7630.871638]  irq_exit_rcu+0xa/0x20
[ 7630.871849]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 7630.872136]  
[ 7630.872268]  
[ 7630.872399]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 7630.872706] RIP: 0010:lock_release+0x15c/0x2c0
[ 7630.872975] Code: e8 a9 d4 c6 00 b8 ff ff ff ff 65 0f c1 05 54 9f e3 54 83 f8 01 0f 85 1a 01 00 00 41 f7 c5 00 02 00 00 74 06 fb 0f 1f 44 00 00 <48> 83 c4 10 5d 41 5c 41 5d 41 5e 41 5f c3 65 8b 05 2f 2b e3 54 89
[ 7630.874066] RSP: 0018:ffffa8c184fd7ab8 EFLAGS: 00000246
[ 7630.874378] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000000000
[ 7630.874802] RDX: ffff9da14f9a8000 RSI: ffffffffab42c217 RDI: ffffffffacb9ce40
[ 7630.875221] RBP: ffffffffacb9ce40 R08: 0000000000000000 R09: 0000000000000000
[ 7630.875640] R10: 0000000000000000 R11: ffffffffab3cd75c R12: ffff9da11b94aed8
[ 7630.876069] R13: 0000558886286000 R14: ffffffffab42c217 R15: 800000015d693845
[ 7630.876488]  ? unlock_page_memcg+0x57/0x140
[ 7630.876744]  ? free_unref_page_list+0x12c/0x560
[ 7630.877017]  ? unlock_page_memcg+0x57/0x140
[ 7630.877270]  ? lock_release+0x1ed/0x2c0
[ 7630.877502]  page_remove_rmap+0x8a/0x5c0
[ 7630.877741]  unmap_page_range+0x706/0x10e0
[ 7630.877989]  ? lock_release+0x1ed/0x2c0
[ 7630.878221]  unmap_vmas+0xb3/0x130
[ 7630.878429]  exit_mmap+0x91/0x1b0
[ 7630.878633]  __mmput+0x4a/0x150
[ 7630.878829]  begin_new_exec+0x516/0xcf0
[ 7630.879062]  load_elf_binary+0x69a/0x1720
[ 7630.879306]  ? xfs_iunlock+0x12e/0x180 [xfs]
[ 7630.879791]  ? rcu_read_lock_sched_held+0x12/0x60
[ 7630.880082]  ? lock_acquire+0x176/0x2d0
[ 7630.880320]  ? rcu_read_lock_sched_held+0x12/0x60
[ 7630.880609]  bprm_execve+0x307/0x860
[ 7630.880834]  do_execveat_common+0x1b5/0x230
[ 7630.881092]  __x64_sys_execve+0x39/0x50
[ 7630.881328]  do_syscall_64+0x38/0x90
[ 7630.881551]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 7630.881861] RIP: 0033:0x7f418d67e33b
[ 7630.882085] Code: Unable to access opcode bytes at RIP 0x7f418d67e311.
[ 7630.882468] RSP: 002b:00007fff2bdbc928 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[ 7630.882917] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f418d67e33b
[ 7630.883337] RDX: 000055888653aa40 RSI: 0000558886270850 RDI: 0000558886540480
[ 7630.883760] RBP: 0000558886540480 R08: 0000558886270850 R09: 0000558800000001
[ 7630.884180] R10: 00000000000001b6 R11: 0000000000000246 R12: 00000000ffffffff
[ 7630.884600] R13: 0000558886270850 R14: 000055888653aa40 R15: 0000558886537a20
[ 7630.885026]