[21256.913777] run fstests generic/338 at 2022-04-03 02:45:44
[21257.161762] BTRFS info (device dm-0): flagging fs with big metadata feature
[21257.161998] BTRFS info (device dm-0): using free space tree
[21257.162172] BTRFS info (device dm-0): has skinny extents
[21257.325910] BTRFS: device fsid 2a2c0f64-58b0-4093-acb7-17878d60ec58 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (832509)
[21257.430609] BTRFS info (device dm-11): flagging fs with big metadata feature
[21257.430848] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[21257.431104] BTRFS info (device dm-11): use zstd compression, level 3
[21257.431287] BTRFS info (device dm-11): turning on async discard
[21257.431462] BTRFS info (device dm-11): disk space caching is enabled
[21257.431649] BTRFS info (device dm-11): has skinny extents
[21257.433620] BTRFS info (device dm-11): checking UUID tree
[21257.446668] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[21257.466079] 338 (832297): drop_caches: 3
[21257.492850] btrfs_dev_stat_print_on_error: 630 callbacks suppressed
[21257.492854] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[21257.493369] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[21257.493696] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[21257.494016] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[21257.494331] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[21257.494645] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[21257.494961] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[21257.495278] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[21257.495362] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[21257.496018] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[21257.496799] BTRFS: error (device dm-11: state A) in __btrfs_run_delayed_items:1092: errno=-5 IO failure
[21257.498467] BTRFS info (device dm-11: state EA): forced readonly
[21257.498946] BTRFS warning (device dm-11: state EA): Skipping commit of aborted transaction.
[21257.499459] BTRFS: error (device dm-11: state EA) in cleanup_transaction:1982: errno=-5 IO failure
[21257.535864] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[21257.536141] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 532, name: dbus-broker
[21257.536396] preempt_count: 101, expected: 0
[21257.536545] RCU nest depth: 0, expected: 0
[21257.536695] INFO: lockdep is turned off.
[21257.536847] Preemption disabled at:
[21257.536848] [] schedule+0x47/0xb0
[21257.537147] CPU: 0 PID: 532 Comm: dbus-broker Tainted: G W 5.17.0+ #640
[21257.537392] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[21257.537658] Call Trace:
[21257.537777]
[21257.537895] dump_stack_lvl+0x56/0x6f
[21257.538034] __might_resched.cold+0xff/0x13a
[21257.538186] blk_release_queue+0x24/0x120
[21257.538336] kobject_put+0x7c/0x1d0
[21257.538477] ? rcu_do_batch+0x1ac/0x570
[21257.538624] blkg_free.part.0+0x41/0x60
[21257.538770] rcu_do_batch+0x1e1/0x570
[21257.538912] ? rcu_do_batch+0x1ac/0x570
[21257.539055] rcu_core+0x2cb/0x460
[21257.539190] __do_softirq+0x178/0x517
[21257.539336] __irq_exit_rcu+0xe3/0x160
[21257.539478] irq_exit_rcu+0xa/0x20
[21257.539615] sysvec_apic_timer_interrupt+0xa2/0xd0
[21257.539776]
[21257.539892]
[21257.540004] asm_sysvec_apic_timer_interrupt+0x12/0x20
[21257.540170] RIP: 0010:finish_task_switch.isra.0+0xff/0x3a0
[21257.540340] Code: 48 8b 1b 4c 89 e7 48 c7 02 00 00 00 00 ff d0 0f 1f 00 48 85 db 75 e2 4c 89 e7 e8 dc 0d cc 00 e8 17 7f 10 00 fb 0f 1f 44 00 00 <65> 48 8b 04 25 c0 fe 01 00 0f 1f 44 00 00 4d 85 f6 74 21 65 48 8b
[21257.540803] RSP: 0018:ffffb18fc09abd00 EFLAGS: 00000246
[21257.540969] RAX: 0000000080000001 RBX: 0000000000000000 RCX: 0000000000000000
[21257.541167] RDX: 0000000000000002 RSI: ffffffffb71a4334 RDI: ffffffffb71a4339
[21257.541365] RBP: ffffb18fc09abd30 R08: 0000000000000000 R09: 0000000000000000
[21257.541565] R10: 0000000000000001 R11: ffffd18fbfc182c8 R12: ffff9e5a3bc331c0
[21257.541766] R13: ffff9e59d0b93200 R14: 0000000000000000 R15: ffff9e5a3bc331d8
[21257.541966] ? finish_task_switch.isra.0+0xf4/0x3a0
[21257.542130] ? finish_task_switch.isra.0+0xf9/0x3a0
[21257.542291] __schedule+0x39e/0xd40
[21257.542428] schedule+0x4e/0xb0
[21257.542595] schedule_hrtimeout_range_clock+0x11f/0x130
[21257.542769] ? rcu_read_lock_sched_held+0x12/0x60
[21257.542926] ? lock_release+0x1ed/0x2c0
[21257.543067] ? trace_hardirqs_on+0x1b/0xe0
[21257.543216] do_epoll_wait+0x6f1/0x8c0
[21257.543361] ? sched_clock_cpu+0xb/0xc0
[21257.543507] ? __seccomp_filter+0x38b/0x5d0
[21257.543658] ? dequeue_task_stop+0x70/0x70
[21257.543807] __x64_sys_epoll_wait+0x4e/0xd0
[21257.543955] ? syscall_trace_enter.constprop.0+0x95/0x220
[21257.544124] do_syscall_64+0x38/0x90
[21257.544263] entry_SYSCALL_64_after_hwframe+0x44/0xae
[21257.544428] RIP: 0033:0x7f77199fdeba
[21257.544569] Code: 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 41 89 ca 64 8b 04 25 18 00 00 00 85 c0 75 15 b8 e8 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5e c3 0f 1f 44 00 00 48 83 ec 28 89 54 24 18
[21257.545030] RSP: 002b:00007ffd0b2c5658 EFLAGS: 00000246 ORIG_RAX: 00000000000000e8
[21257.550928] RAX: ffffffffffffffda RBX: 0000560faa103a58 RCX: 00007f77199fdeba
[21257.551126] RDX: 000000000000000c RSI: 00007ffd0b2c5660 RDI: 0000000000000005
[21257.551325] RBP: 00007ffd0b2c5740 R08: 0000560faa14bfd0 R09: ffffffffffffffff
[21257.551519] R10: 00000000ffffffff R11: 0000000000000246 R12: 0000000000000000
[21257.551721] R13: 000000000000000c R14: 00007ffd0b2c5660 R15: 00000000ffffffff
[21257.551922]