[19325.605395] run fstests btrfs/211 at 2022-04-04 02:12:59
[19325.846646] BTRFS info (device dm-0): flagging fs with big metadata feature
[19325.847249] BTRFS info (device dm-0): using free space tree
[19325.847677] BTRFS info (device dm-0): has skinny extents
[19326.079174] BTRFS: device fsid e419aec7-e69b-4260-bd05-9900633b7e57 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3596595)
[19326.155925] BTRFS info (device dm-11): flagging fs with big metadata feature
[19326.156487] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[19326.157062] BTRFS info (device dm-11): use lzo compression, level 0
[19326.157508] BTRFS info (device dm-11): disk space caching is enabled
[19326.157944] BTRFS info (device dm-11): has skinny extents
[19326.160186] BTRFS info (device dm-11): checking UUID tree
[19326.218039] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3594379)
[19326.219397] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3594379)
[19326.279851] BTRFS info (device dm-11): flagging fs with big metadata feature
[19326.280395] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[19326.281105] BTRFS info (device dm-11): use lzo compression, level 0
[19326.281599] BTRFS info (device dm-11): disk space caching is enabled
[19326.282132] BTRFS info (device dm-11): has skinny extents
[19326.284460] BTRFS info (device dm-11): start tree-log replay
[19326.294849] BTRFS info (device dm-11): checking UUID tree
[19326.335254] BTRFS info (device dm-11): flagging fs with big metadata feature
[19326.335755] BTRFS info (device dm-11): use lzo compression, level 0
[19326.336273] BTRFS info (device dm-11): disk space caching is enabled
[19326.336707] BTRFS info (device dm-11): has skinny extents
[19326.409137] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[19326.409675] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3594379, name: systemd-udevd
[19326.410258] preempt_count: 100, expected: 0
[19326.410536] RCU nest depth: 0, expected: 0
[19326.410805] INFO: lockdep is turned off.
[19326.411067] Preemption disabled at:
[19326.411068] [] __do_softirq+0x5e/0x517
[19326.411661] CPU: 0 PID: 3594379 Comm: systemd-udevd Tainted: G        W         5.17.0+ #641
[19326.412218] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[19326.412793] Call Trace:
[19326.412967]  
[19326.413117]  dump_stack_lvl+0x56/0x6f
[19326.413374]  __might_resched.cold+0xff/0x13a
[19326.413673]  blk_release_queue+0x24/0x120
[19326.413958]  kobject_put+0x7c/0x1d0
[19326.414310]  ? rcu_do_batch+0x1ac/0x570
[19326.414710]  blkg_free.part.0+0x41/0x60
[19326.415043]  rcu_do_batch+0x1e1/0x570
[19326.415385]  ? rcu_do_batch+0x1ac/0x570
[19326.415756]  rcu_core+0x2cb/0x460
[19326.416037]  __do_softirq+0x178/0x517
[19326.416326]  __irq_exit_rcu+0xe3/0x160
[19326.416599]  irq_exit_rcu+0xa/0x20
[19326.416845]  sysvec_apic_timer_interrupt+0xa2/0xd0
[19326.417193]  
[19326.417348]  
[19326.417496]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[19326.417856] RIP: 0010:clear_page_erms+0x7/0x10
[19326.418195] Code: 48 89 47 18 48 89 47 20 48 89 47 28 48 89 47 30 48 89 47 38 48 8d 7f 40 75 d9 90 c3 0f 1f 80 00 00 00 00 b9 00 10 00 00 31 c0  aa c3 cc cc cc cc cc cc 49 89 f1 48 85 ff 0f 84 fa 00 00 00 0f
[19326.419492] RSP: 0018:ffffa28443f0b8c8 EFLAGS: 00010246
[19326.419859] RAX: 0000000000000000 RBX: ffff94fcbffd5e00 RCX: 0000000000000380
[19326.420337] RDX: ffffc95d05951dc0 RSI: ffffc95d05951e00 RDI: ffff94fca5477c80
[19326.420807] RBP: ffff94fcbbc2f200 R08: 0000000000100000 R09: 0000000000000000
[19326.421281] R10: 0000000000000000 R11: 0000000000000000 R12: ffff94fcbbc3a480
[19326.421752] R13: 0000000000000000 R14: ffff94fcbffd5e00 R15: ffffc95d05951dc0
[19326.422232]  post_alloc_hook+0x8d/0x110
[19326.422493]  get_page_from_freelist+0x126e/0x16d0
[19326.422815]  ? rcu_read_lock_sched_held+0x12/0x60
[19326.423140]  ? prepare_alloc_pages+0xbc/0x1b0
[19326.423438]  __alloc_pages+0xd6/0x290
[19326.423690]  alloc_pages_vma+0x8f/0x2e0
[19326.423953]  __handle_mm_fault+0xb31/0x1460
[19326.424245]  handle_mm_fault+0x11e/0x3a0
[19326.424533]  do_user_addr_fault+0x1e9/0x6b0
[19326.424839]  exc_page_fault+0x78/0x280
[19326.425117]  asm_exc_page_fault+0x1e/0x30
[19326.425392] RIP: 0010:copy_user_enhanced_fast_string+0xe/0x40
[19326.425808] 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
[19326.427216] RSP: 0018:ffffa28443f0bcc8 EFLAGS: 00050206
[19326.427588] RAX: 00007fffffffe000 RBX: 0000000000001000 RCX: 0000000000000cc8
[19326.428083] RDX: 0000000000001000 RSI: ffff94fc554cb338 RDI: 0000562aabe7e000
[19326.428593] RBP: ffffa28443f0be68 R08: 0000000000000001 R09: ffffa28443f0be58
[19326.429102] R10: 0000562aabe7dcc8 R11: 0000000000000000 R12: 0000000000001000
[19326.429575] R13: 0000000000001000 R14: 0000000000000000 R15: 000000000002f000
[19326.430052]  copyout+0x3d/0x50
[19326.430265]  copy_page_to_iter+0x104/0x4a0
[19326.430543]  filemap_read+0x18c/0x340
[19326.430794]  ? register_handler_proc+0xef/0x130
[19326.431106]  blkdev_read_iter+0xac/0x1c0
[19326.431379]  new_sync_read+0xf3/0x160
[19326.431633]  vfs_read+0x15f/0x1b0
[19326.431861]  ksys_read+0x55/0xd0
[19326.432088]  do_syscall_64+0x38/0x90
[19326.432334]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[19326.432676] RIP: 0033:0x7f114b8ae6c2
[19326.432924] Code: c0 e9 b2 fe ff ff 50 48 8d 3d 0a 2b 0a 00 e8 b5 e8 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
[19326.434213] RSP: 002b:00007ffe2f13e4c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[19326.434748] RAX: ffffffffffffffda RBX: 0000562aabe4ecb8 RCX: 00007f114b8ae6c2
[19326.435244] RDX: 0000000000040000 RSI: 0000562aabe4ecc8 RDI: 0000000000000006
[19326.435754] RBP: 0000562aabe02b90 R08: 0000562aabe4eca0 R09: 00007f114b980a60
[19326.436246] R10: fffffffffffff000 R11: 0000000000000246 R12: 0000000000040000
[19326.436769] R13: 0000000000040000 R14: 0000562aabe4eca0 R15: 0000562aabe02be0
[19326.437250]  
[19326.510585] BTRFS: device fsid 454920f0-89ba-4c82-b1ce-387a0995071a devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3596724)
[19326.587467] BTRFS info (device dm-11): flagging fs with big metadata feature
[19326.587966] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[19326.588562] BTRFS info (device dm-11): use lzo compression, level 0
[19326.589000] BTRFS info (device dm-11): disk space caching is enabled
[19326.589443] BTRFS info (device dm-11): has skinny extents
[19326.591431] BTRFS info (device dm-11): checking UUID tree
[19326.646835] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3594379)
[19326.648228] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3594379)
[19326.704436] BTRFS info (device dm-11): flagging fs with big metadata feature
[19326.704951] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[19326.705561] BTRFS info (device dm-11): use lzo compression, level 0
[19326.705994] BTRFS info (device dm-11): disk space caching is enabled
[19326.706447] BTRFS info (device dm-11): has skinny extents
[19326.708149] BTRFS info (device dm-11): start tree-log replay
[19326.718306] BTRFS info (device dm-11): checking UUID tree
[19326.753809] BTRFS info (device dm-11): flagging fs with big metadata feature
[19326.754338] BTRFS info (device dm-11): use lzo compression, level 0
[19326.754769] BTRFS info (device dm-11): disk space caching is enabled
[19326.755224] BTRFS info (device dm-11): has skinny extents