[17781.483299] run fstests btrfs/118 at 2022-04-04 01:47:15
[17781.682167] BTRFS info (device dm-0): flagging fs with big metadata feature
[17781.682604] BTRFS info (device dm-0): using free space tree
[17781.682933] BTRFS info (device dm-0): has skinny extents
[17781.837827] BTRFS: device fsid 1318926b-f645-4702-8c19-1f03e7f10273 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3162787)
[17781.904165] BTRFS info (device dm-11): flagging fs with big metadata feature
[17781.904782] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[17781.905500] BTRFS info (device dm-11): use lzo compression, level 0
[17781.906047] BTRFS info (device dm-11): disk space caching is enabled
[17781.906581] BTRFS info (device dm-11): has skinny extents
[17781.908813] BTRFS info (device dm-11): checking UUID tree
[17781.952130] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3161868)
[17781.953266] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3161868)
[17782.000499] BTRFS info (device dm-11): flagging fs with big metadata feature
[17782.000958] BTRFS info (device dm-11): use lzo compression, level 0
[17782.001379] BTRFS info (device dm-11): disk space caching is enabled
[17782.001789] BTRFS info (device dm-11): has skinny extents
[17782.045524] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3161868)
[17782.046774] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3161868)
[17782.102029] BTRFS info (device dm-11): flagging fs with big metadata feature
[17782.102504] BTRFS info (device dm-11): use lzo compression, level 0
[17782.102896] BTRFS info (device dm-11): disk space caching is enabled
[17782.103311] BTRFS info (device dm-11): has skinny extents
[17782.143005] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[17782.143517] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3162909, name: systemctl
[17782.144055] preempt_count: 100, expected: 0
[17782.144331] RCU nest depth: 0, expected: 0
[17782.144623] INFO: lockdep is turned off.
[17782.144973] Preemption disabled at:
[17782.144974] [] __do_softirq+0x5e/0x517
[17782.145715] CPU: 1 PID: 3162909 Comm: systemctl Tainted: G        W         5.17.0+ #641
[17782.146191] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[17782.146693] Call Trace:
[17782.146845]  
[17782.146971]  dump_stack_lvl+0x56/0x6f
[17782.147194]  __might_resched.cold+0xff/0x13a
[17782.147492]  blk_release_queue+0x24/0x120
[17782.147734]  kobject_put+0x7c/0x1d0
[17782.147947]  ? rcu_do_batch+0x1ac/0x570
[17782.148179]  blkg_free.part.0+0x41/0x60
[17782.148416]  rcu_do_batch+0x1e1/0x570
[17782.148636]  ? rcu_do_batch+0x1ac/0x570
[17782.148869]  rcu_core+0x2cb/0x460
[17782.149071]  __do_softirq+0x178/0x517
[17782.149293]  __irq_exit_rcu+0xe3/0x160
[17782.149525]  irq_exit_rcu+0xa/0x20
[17782.149731]  sysvec_apic_timer_interrupt+0xa2/0xd0
[17782.150054]  
[17782.150184]  
[17782.150315]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[17782.150623] RIP: 0010:mod_objcg_state+0x15e/0x2e0
[17782.150905] Code: 00 48 c7 43 28 00 00 00 00 48 c7 c6 b5 e7 42 a9 48 89 df e8 64 85 db ff 48 83 3c 24 00 74 0b e8 78 da e7 ff fb 0f 1f 44 00 00 <4d> 85 ff 0f 84 a2 00 00 00 48 83 c4 18 4c 89 ff 5b 5d 41 5c 41 5d
[17782.151989] RSP: 0018:ffffa28441793c50 EFLAGS: 00000246
[17782.152297] RAX: 0000000080000000 RBX: ffff94fcbbd2f620 RCX: 0000000000000006
[17782.152804] RDX: 0000000000000000 RSI: ffffffffa942e7b5 RDI: ffffffffa942e7d8
[17782.153220] RBP: ffff94fc436ec840 R08: 0000000000000000 R09: 0000000000000000
[17782.153645] R10: 0000000000000003 R11: 0000000000000100 R12: ffff94fcbffd5000
[17782.154062] R13: 0000000000000006 R14: 0000000000000208 R15: 0000000000000000
[17782.154485]  ? mod_objcg_state+0x135/0x2e0
[17782.154786]  ? mod_objcg_state+0x158/0x2e0
[17782.155033]  ? lock_release+0x1ed/0x2c0
[17782.155263]  memcg_slab_post_alloc_hook+0x1a8/0x440
[17782.155559]  kmem_cache_alloc+0x1d1/0x3f0
[17782.155800]  __alloc_file+0x23/0x100
[17782.156036]  alloc_empty_file+0x41/0xb0
[17782.156268]  path_openat+0x39/0xab0
[17782.156483]  ? rcu_read_lock_sched_held+0x12/0x60
[17782.156767]  ? xfs_iunlock+0x12e/0x180 [xfs]
[17782.157103]  do_filp_open+0x96/0x120
[17782.157320]  ? lock_acquire+0x176/0x2d0
[17782.157598]  ? lock_release+0x1ed/0x2c0
[17782.157830]  ? _raw_spin_unlock+0x29/0x40
[17782.158070]  ? alloc_fd+0x130/0x1f0
[17782.158283]  do_sys_openat2+0x7b/0x140
[17782.158533]  __x64_sys_openat+0x46/0x70
[17782.158779]  do_syscall_64+0x38/0x90
[17782.158996]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[17782.159296] RIP: 0033:0x7fdc20a42228
[17782.159516] Code: f9 41 89 f0 41 83 e2 40 75 30 89 f0 25 00 00 41 00 3d 00 00 41 00 74 22 44 89 c2 4c 89 ce bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 30 c3 0f 1f 80 00 00 00 00 48 8d 44 24 08 c7
[17782.160598] RSP: 002b:00007ffc5b547aa8 EFLAGS: 00000287 ORIG_RAX: 0000000000000101
[17782.161060] RAX: ffffffffffffffda RBX: 00007ffc5b547d0f RCX: 00007fdc20a42228
[17782.161494] RDX: 0000000000080000 RSI: 00007ffc5b547b20 RDI: 00000000ffffff9c
[17782.161911] RBP: 00007ffc5b547b10 R08: 0000000000080000 R09: 00007ffc5b547b20
[17782.162328] R10: 0000000000000000 R11: 0000000000000287 R12: 00007ffc5b547b31
[17782.162746] R13: 00007ffc5b547d20 R14: 00007ffc5b547b20 R15: 00007fdc20a53ea0
[17782.163165]