[25363.692437] run fstests generic/279 at 2022-04-04 03:54:08
[25363.956864] BTRFS info (device dm-0): flagging fs with big metadata feature
[25363.957100] BTRFS info (device dm-0): using free space tree
[25363.957276] BTRFS info (device dm-0): has skinny extents
[25364.198845] BTRFS: device fsid 2ce8d6fb-1f50-4779-a194-7613ac314308 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (970487)
[25364.211995] BTRFS info (device dm-9): flagging fs with big metadata feature
[25364.212236] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[25364.212501] BTRFS info (device dm-9): use zstd compression, level 3
[25364.212719] BTRFS info (device dm-9): turning on async discard
[25364.212904] BTRFS info (device dm-9): disk space caching is enabled
[25364.213108] BTRFS info (device dm-9): has skinny extents
[25364.214628] BTRFS info (device dm-9): checking UUID tree
[25364.535981] BTRFS: device fsid b3da0426-9002-478b-9a40-eb6612a78ee9 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (970560)
[25364.624652] BTRFS info (device dm-11): flagging fs with big metadata feature
[25364.624880] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[25364.625168] BTRFS info (device dm-11): use zstd compression, level 3
[25364.625358] BTRFS info (device dm-11): turning on async discard
[25364.625547] BTRFS info (device dm-11): disk space caching is enabled
[25364.625740] BTRFS info (device dm-11): has skinny extents
[25364.627359] BTRFS info (device dm-11): checking UUID tree
[25364.764492] BTRFS info (device dm-11): flagging fs with big metadata feature
[25364.764804] BTRFS info (device dm-11): use zstd compression, level 3
[25364.765093] BTRFS info (device dm-11): turning on async discard
[25364.765346] BTRFS info (device dm-11): disk space caching is enabled
[25364.765562] BTRFS info (device dm-11): has skinny extents
[25365.010094] Buffer I/O error on dev dm-11, logical block 2621424, async page read
[25365.083793] btrfs_dev_stat_print_on_error: 118 callbacks suppressed
[25365.083798] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[25365.084568] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[25365.085044] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[25365.085496] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[25365.085975] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[25365.086426] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[25365.086892] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[25365.087359] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[25365.087815] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[25365.088174] BTRFS error (device dm-11): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[25365.133417] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-11 scanned by systemd-udevd (968458)
[25365.134604] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/error-test scanned by systemd-udevd (968458)
[25365.201996] BTRFS info (device dm-11): flagging fs with big metadata feature
[25365.202225] BTRFS info (device dm-11): use zstd compression, level 3
[25365.202409] BTRFS info (device dm-11): turning on async discard
[25365.202589] BTRFS info (device dm-11): disk space caching is enabled
[25365.202779] BTRFS info (device dm-11): has skinny extents
[25365.204006] BTRFS info (device dm-11): bdev /dev/mapper/error-test errs: wr 320, rd 0, flush 0, corrupt 0, gen 0
[25365.342927] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[25365.343322] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 970681, name: fsck
[25365.343720] preempt_count: 103, expected: 0
[25365.343954] RCU nest depth: 0, expected: 0
[25365.344170] INFO: lockdep is turned off.
[25365.344374] Preemption disabled at:
[25365.344375] [<0000000000000000>] 0x0
[25365.344754] CPU: 1 PID: 970681 Comm: fsck Tainted: G        W         5.17.0+ #641
[25365.345115] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[25365.345484] Call Trace:
[25365.345668]  
[25365.345833]  dump_stack_lvl+0x56/0x6f
[25365.346039]  __might_resched.cold+0xff/0x13a
[25365.346262]  blk_release_queue+0x24/0x120
[25365.353533]  kobject_put+0x7c/0x1d0
[25365.353689]  ? rcu_do_batch+0x1ac/0x570
[25365.353853]  blkg_free.part.0+0x41/0x60
[25365.354012]  rcu_do_batch+0x1e1/0x570
[25365.354174]  ? rcu_do_batch+0x1ac/0x570
[25365.354337]  rcu_core+0x2cb/0x460
[25365.354485]  __do_softirq+0x178/0x517
[25365.354644]  __irq_exit_rcu+0xe3/0x160
[25365.354802]  irq_exit_rcu+0xa/0x20
[25365.354960]  sysvec_apic_timer_interrupt+0xa2/0xd0
[25365.355131]  
[25365.355259]  
[25365.355380]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[25365.355560] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[25365.355759] 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 5d 85 c0 74 03 5b
[25365.356278] RSP: 0018:ffffb12640befa10 EFLAGS: 00000246
[25365.356452] RAX: 0000000080000003 RBX: 0000000000000246 RCX: 000000000000038f
[25365.356672] RDX: ffff9be284715000 RSI: ffffffffa236c46f RDI: ffffffffa2e651fd
[25365.356892] RBP: ffff9be284715050 R08: 0000000000000000 R09: 0000000000000000
[25365.357103] R10: 0000000000000000 R11: 0000000000000000 R12: 000000000000000f
[25365.357313] R13: ffffed3005d97c40 R14: ffffed3005c41048 R15: ffff9be284715000
[25365.357538]  ? __pagevec_lru_add+0x28f/0x5d0
[25365.357705]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[25365.357883]  __pagevec_lru_add+0x28f/0x5d0
[25365.358048]  folio_add_lru+0xd5/0x220
[25365.358207]  __handle_mm_fault+0x1288/0x1460
[25365.358373]  ? lock_release+0x1ed/0x2c0
[25365.358530]  handle_mm_fault+0x11e/0x3a0
[25365.358692]  do_user_addr_fault+0x1e9/0x6b0
[25365.358864]  exc_page_fault+0x78/0x280
[25365.359035]  asm_exc_page_fault+0x1e/0x30
[25365.359190] RIP: 0010:copy_user_enhanced_fast_string+0xe/0x40
[25365.359389] Code: 89 d1 c1 e9 03 83 e2 07 f3 48 a5 89 d1 f3 a4 31 c0 0f 01 ca c3 8d 0c ca 89 ca eb 20 0f 01 cb 83 fa 40 0f 82 60 ff ff ff 89 d1  a4 31 c0 0f 01 ca c3 89 ca eb 06 66 0f 1f 44 00 00 83 f8 12 74
[25365.359908] RSP: 0018:ffffb12640befcc8 EFLAGS: 00050206
[25365.360084] RAX: 00007fffffffe000 RBX: 0000000000001000 RCX: 0000000000000038
[25365.360302] RDX: 0000000000001000 RSI: ffff9be285556fc8 RDI: 00007fc9511b6000
[25365.360517] RBP: ffffb12640befe68 R08: 0000000000000001 R09: ffffb12640befe58
[25365.360750] R10: 00007fc9511b5038 R11: 0000000000000020 R12: 0000000000001000
[25365.360980] R13: 0000000000001000 R14: 0000000000000000 R15: 0000000000016000
[25365.361212]  copyout+0x3d/0x50
[25365.361367]  copy_page_to_iter+0x104/0x4a0
[25365.361541]  filemap_read+0x18c/0x340
[25365.361714]  ? register_handler_proc+0xef/0x130
[25365.361920]  blkdev_read_iter+0xac/0x1c0
[25365.362080]  new_sync_read+0xf3/0x160
[25365.362234]  vfs_read+0x15f/0x1b0
[25365.362378]  ksys_read+0x55/0xd0
[25365.362519]  do_syscall_64+0x38/0x90
[25365.362687]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[25365.362870] RIP: 0033:0x7fc9513cc4f2
[25365.363021] Code: c0 e9 b2 fe ff ff 50 48 8d 3d b2 39 0a 00 e8 95 f0 01 00 0f 1f 44 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 0f 05 <48> 3d 00 f0 ff ff 77 56 c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24
[25365.363541] RSP: 002b:00007ffcdb7149a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[25365.363814] RAX: ffffffffffffffda RBX: 00007fc95119f028 RCX: 00007fc9513cc4f2
[25365.364019] RDX: 0000000000040000 RSI: 00007fc95119f038 RDI: 0000000000000003
[25365.364237] RBP: 0000560e6d8fd7d0 R08: 00007fc95119f010 R09: 0000000000000000
[25365.364455] R10: 0000000000000022 R11: 0000000000000246 R12: 0000000000000000
[25365.364665] R13: 0000000000040000 R14: 00007fc95119f010 R15: 0000560e6d8fd820
[25365.364891]