[25358.226502] run fstests generic/276 at 2022-04-04 03:54:02
[25358.713208] BTRFS: device fsid a710ae37-d24e-4a55-994b-2ca49b5290a7 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (969174)
[25358.727832] BTRFS info (device dm-9): flagging fs with big metadata feature
[25358.728068] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[25358.728322] BTRFS info (device dm-9): use zstd compression, level 3
[25358.728509] BTRFS info (device dm-9): turning on async discard
[25358.728683] BTRFS info (device dm-9): disk space caching is enabled
[25358.728867] BTRFS info (device dm-9): has skinny extents
[25358.730473] BTRFS info (device dm-9): checking UUID tree
[25359.050858] BTRFS: device fsid b6538d7e-0699-4bba-bdf2-77323323e803 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (969250)
[25359.141558] BTRFS info (device dm-11): flagging fs with big metadata feature
[25359.141796] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[25359.142075] BTRFS info (device dm-11): use zstd compression, level 3
[25359.142271] BTRFS info (device dm-11): turning on async discard
[25359.142537] BTRFS info (device dm-11): disk space caching is enabled
[25359.142808] BTRFS info (device dm-11): has skinny extents
[25359.144520] BTRFS info (device dm-11): checking UUID tree
[25359.265862] BTRFS info (device dm-11): flagging fs with big metadata feature
[25359.266102] BTRFS info (device dm-11): use zstd compression, level 3
[25359.266288] BTRFS info (device dm-11): turning on async discard
[25359.266462] BTRFS info (device dm-11): disk space caching is enabled
[25359.266659] BTRFS info (device dm-11): has skinny extents
[25359.536564] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[25359.552600] btrfs_dev_stat_print_on_error: 128 callbacks suppressed
[25359.552604] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[25359.553167] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[25359.553474] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[25359.553781] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[25359.554096] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[25359.554405] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[25359.554721] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[25359.555914] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[25359.556329] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[25359.556653] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[25359.577327] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (968458)
[25359.578247] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (968458)
[25359.684429] BTRFS info (device dm-11): flagging fs with big metadata feature
[25359.684651] BTRFS info (device dm-11): use zstd compression, level 3
[25359.684842] BTRFS info (device dm-11): turning on async discard
[25359.685049] BTRFS info (device dm-11): disk space caching is enabled
[25359.685233] BTRFS info (device dm-11): has skinny extents
[25359.686643] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 64, rd 0, flush 0, corrupt 0, gen 0
[25359.833978] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[25359.834246] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 969373, name: 276
[25359.834488] preempt_count: 102, expected: 0
[25359.834632] RCU nest depth: 1, expected: 0
[25359.834796] INFO: lockdep is turned off.
[25359.834942] Preemption disabled at:
[25359.834943] [<0000000000000000>] 0x0
[25359.835237] CPU: 0 PID: 969373 Comm: 276 Tainted: G        W         5.17.0+ #641
[25359.835472] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[25359.835752] Call Trace:
[25359.835876]  
[25359.835989]  dump_stack_lvl+0x56/0x6f
[25359.836132]  __might_resched.cold+0xff/0x13a
[25359.836285]  blk_release_queue+0x24/0x120
[25359.836429]  kobject_put+0x7c/0x1d0
[25359.836565]  ? rcu_do_batch+0x1ac/0x570
[25359.836717]  blkg_free.part.0+0x41/0x60
[25359.836862]  rcu_do_batch+0x1e1/0x570
[25359.836999]  ? rcu_do_batch+0x1ac/0x570
[25359.837143]  rcu_core+0x2cb/0x460
[25359.837279]  __do_softirq+0x178/0x517
[25359.837419]  __irq_exit_rcu+0xe3/0x160
[25359.837557]  irq_exit_rcu+0xa/0x20
[25359.837693]  sysvec_apic_timer_interrupt+0xa2/0xd0
[25359.837877]  
[25359.838001]  
[25359.838137]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[25359.838320] RIP: 0010:check_preemption_disabled+0x4/0xe0
[25359.838501] Code: a3 be 30 00 00 00 48 c7 c7 60 73 83 a3 c6 05 31 54 fd 00 01 e8 d9 88 f8 ff e9 79 ff ff ff cc cc cc cc cc cc cc cc 41 54 55 53 <48> 83 ec 08 65 44 8b 25 b0 57 1c 5d 65 8b 05 59 bc 1c 5d a9 ff ff
[25359.839015] RSP: 0018:ffffb12646587ac0 EFLAGS: 00000283
[25359.839188] RAX: ffff9be283b42000 RBX: 0000000000000000 RCX: 0000000000000000
[25359.839396] RDX: ffff9be2bf5f3200 RSI: ffffffffa387e4d9 RDI: ffffffffa383354b
[25359.839596] RBP: ffffed3005ed0480 R08: 0000000000000000 R09: 0000000000000000
[25359.839802] R10: 0000000000000000 R11: ffffffffa23cd75c R12: ffff9be296bac960
[25359.840012] R13: 00005599df744000 R14: ffffb12646587c80 R15: 800000017b412845
[25359.840227]  ? free_unref_page_list+0x12c/0x560
[25359.840393]  rcu_is_watching+0xd/0x40
[25359.840535]  unlock_page_memcg+0x53/0x140
[25359.840699]  page_remove_rmap+0x8a/0x5c0
[25359.840852]  unmap_page_range+0x706/0x10e0
[25359.841008]  ? lock_release+0x1ed/0x2c0
[25359.841167]  unmap_vmas+0xb3/0x130
[25359.841310]  exit_mmap+0x91/0x1b0
[25359.841458]  __mmput+0x4a/0x150
[25359.841601]  begin_new_exec+0x516/0xcf0
[25359.841770]  load_elf_binary+0x69a/0x1720
[25359.841925]  ? xfs_iunlock+0x12e/0x180 [xfs]
[25359.842307]  ? rcu_read_lock_sched_held+0x12/0x60
[25359.842471]  ? lock_acquire+0x176/0x2d0
[25359.842632]  ? rcu_read_lock_sched_held+0x12/0x60
[25359.842801]  bprm_execve+0x307/0x860
[25359.842945]  do_execveat_common+0x1b5/0x230
[25359.843102]  __x64_sys_execve+0x39/0x50
[25359.843247]  do_syscall_64+0x38/0x90
[25359.843390]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[25359.843557] RIP: 0033:0x7fb83aa122fb
[25359.843733] Code: Unable to access opcode bytes at RIP 0x7fb83aa122d1.
[25359.843928] RSP: 002b:00007ffd814cc5b8 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[25359.844197] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fb83aa122fb
[25359.844394] RDX: 00005599df7ea830 RSI: 00005599df7915d0 RDI: 00005599df7ed500
[25359.844586] RBP: 00005599df7ed500 R08: 00005599df7915d0 R09: 0000559900000001
[25359.844790] R10: 00000000000001b6 R11: 0000000000000246 R12: 00000000ffffffff
[25359.844984] R13: 00005599df7915d0 R14: 00005599df7ea830 R15: 00005599df7c1c60
[25359.845178]