[19119.079639] run fstests generic/283 at 2022-04-03 02:21:05
[19119.304957] BTRFS info (device dm-0): flagging fs with big metadata feature
[19119.305379] BTRFS info (device dm-0): using free space tree
[19119.305725] BTRFS info (device dm-0): has skinny extents
[19119.491138] BTRFS: device fsid 05aa26c0-1d63-4fd5-858d-5b959755a5f9 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (816361)
[19119.506852] BTRFS info (device dm-9): flagging fs with big metadata feature
[19119.507288] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[19119.507802] BTRFS info (device dm-9): use lzo compression, level 0
[19119.508190] BTRFS info (device dm-9): disk space caching is enabled
[19119.508577] BTRFS info (device dm-9): has skinny extents
[19119.523759] BTRFS info (device dm-9): checking UUID tree
[19119.653197] BTRFS: device fsid f5f1a4f2-8ae6-4ec0-9bed-8200a3e650c4 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (816434)
[19119.743658] BTRFS info (device dm-11): flagging fs with big metadata feature
[19119.744078] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[19119.744577] BTRFS info (device dm-11): use lzo compression, level 0
[19119.745067] BTRFS info (device dm-11): disk space caching is enabled
[19119.745457] BTRFS info (device dm-11): has skinny extents
[19119.747639] BTRFS info (device dm-11): checking UUID tree
[19119.853033] BTRFS info (device dm-11): flagging fs with big metadata feature
[19119.853476] BTRFS info (device dm-11): use lzo compression, level 0
[19119.853838] BTRFS info (device dm-11): disk space caching is enabled
[19119.854197] BTRFS info (device dm-11): has skinny extents
[19120.126871] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[19120.236014] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (812781)
[19120.237141] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (812781)
[19120.410334] BTRFS info (device dm-11): flagging fs with big metadata feature
[19120.410790] BTRFS info (device dm-11): use lzo compression, level 0
[19120.411164] BTRFS info (device dm-11): disk space caching is enabled
[19120.411558] BTRFS info (device dm-11): has skinny extents
[19120.413052] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 320, rd 0, flush 0, corrupt 0, gen 0
[19120.566384] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[19120.566874] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 816561, name: 283
[19120.567336] preempt_count: 102, expected: 0
[19120.567601] RCU nest depth: 0, expected: 0
[19120.567847] INFO: lockdep is turned off.
[19120.568083] Preemption disabled at:
[19120.568084] [] lru_add_drain+0x13/0x130
[19120.568638] CPU: 1 PID: 816561 Comm: 283 Tainted: G        W         5.17.0+ #640
[19120.569080] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[19120.569582] Call Trace:
[19120.569735]  
[19120.569864]  dump_stack_lvl+0x56/0x6f
[19120.570088]  __might_resched.cold+0xff/0x13a
[19120.570350]  blk_release_queue+0x24/0x120
[19120.570594]  kobject_put+0x7c/0x1d0
[19120.570807]  ? rcu_do_batch+0x1ac/0x570
[19120.571039]  blkg_free.part.0+0x41/0x60
[19120.571272]  rcu_do_batch+0x1e1/0x570
[19120.571511]  ? rcu_do_batch+0x1ac/0x570
[19120.571745]  rcu_core+0x2cb/0x460
[19120.571947]  __do_softirq+0x178/0x517
[19120.572171]  __irq_exit_rcu+0xe3/0x160
[19120.572420]  irq_exit_rcu+0xa/0x20
[19120.572648]  sysvec_apic_timer_interrupt+0xa2/0xd0
[19120.572963]  
[19120.573117]  
[19120.573288]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[19120.573601] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[19120.573952] Code: 48 83 c7 18 53 48 89 f3 48 8b 74 24 10 e8 45 1b 38 ff 48 89 ef e8 dd 44 38 ff 80 e7 02 74 0b e8 53 70 44 ff fb 0f 1f 44 00 00  01 00 00 00 e8 93 d8 33 ff 65 8b 05 6c ac 1b 54 85 c0 74 03 5b
[19120.575052] RSP: 0018:ffffa8c18735fba8 EFLAGS: 00000246
[19120.575369] RAX: 0000000080000002 RBX: 0000000000000246 RCX: 0000000000000005
[19120.575788] RDX: ffff9da112190010 RSI: ffffffffab36c46f RDI: ffffffffabe651fd
[19120.576207] RBP: ffff9da112190050 R08: 0000000000000000 R09: 0000000000000000
[19120.576682] R10: 0000000000000000 R11: 00000000000001ff R12: 0000000000000009
[19120.577183] R13: ffffeeb20429acc0 R14: ffffeeb205422d88 R15: ffff9da112190010
[19120.577703]  ? __pagevec_lru_add+0x28f/0x5d0
[19120.578018]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[19120.578393]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[19120.578762]  __pagevec_lru_add+0x28f/0x5d0
[19120.579020]  lru_add_drain_cpu+0x1a9/0x270
[19120.579304]  lru_add_drain+0x77/0x130
[19120.579553]  exit_mmap+0x70/0x1b0
[19120.579783]  ? lock_acquire+0x176/0x2d0
[19120.580016]  ? __mutex_unlock_slowpath+0x35/0x260
[19120.580298]  ? uprobe_clear_state+0x68/0x120
[19120.580651]  __mmput+0x4a/0x150
[19120.580846]  begin_new_exec+0x516/0xcf0
[19120.581078]  load_elf_binary+0x69a/0x1720
[19120.581344]  ? xfs_iunlock+0x12e/0x180 [xfs]
[19120.581707]  ? rcu_read_lock_sched_held+0x12/0x60
[19120.581988]  ? lock_acquire+0x176/0x2d0
[19120.582229]  ? rcu_read_lock_sched_held+0x12/0x60
[19120.582517]  bprm_execve+0x307/0x860
[19120.582734]  do_execveat_common+0x1b5/0x230
[19120.582986]  __x64_sys_execve+0x39/0x50
[19120.583217]  do_syscall_64+0x38/0x90
[19120.583440]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[19120.583739] RIP: 0033:0x7f145d34b33b
[19120.583961] Code: Unable to access opcode bytes at RIP 0x7f145d34b311.
[19120.584348] RSP: 002b:00007ffc6af58cc8 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[19120.584791] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f145d34b33b
[19120.585208] RDX: 00005612ff5e1d20 RSI: 00005612ff5dded0 RDI: 00005612ff5df9f0
[19120.585645] RBP: 00005612ff5df9f0 R08: 00005612ff5dded0 R09: 0000000000000000
[19120.586065] R10: 0000000000000008 R11: 0000000000000246 R12: 00000000ffffffff
[19120.586522] R13: 00005612ff5dded0 R14: 00005612ff5e1d20 R15: 00005612ff5dd270
[19120.586943]