[20380.172025] run fstests btrfs/192 at 2022-04-04 02:31:04
[20380.751650] BTRFS: device fsid 2e28c545-ec36-4b7e-a28a-e712e0159c2b devid 1 transid 6 /dev/mapper/logwrites-test scanned by mkfs.btrfs (3520885)
[20380.760451] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[20380.760732] BTRFS info (device dm-11): use zstd compression, level 3
[20380.760918] BTRFS info (device dm-11): turning on async discard
[20380.761091] BTRFS info (device dm-11): disk space caching is enabled
[20380.761303] BTRFS info (device dm-11): has skinny extents
[20380.763212] BTRFS info (device dm-11): checking UUID tree
[20412.869777] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[20412.870096] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3521602, name: replay-log
[20412.870360] preempt_count: 102, expected: 0
[20412.870536] RCU nest depth: 0, expected: 0
[20412.870684] INFO: lockdep is turned off.
[20412.870845] Preemption disabled at:
[20412.870846] [] __slab_alloc.constprop.0+0x23/0x80
[20412.871288] CPU: 0 PID: 3521602 Comm: replay-log Tainted: G        W         5.17.0+ #641
[20412.871542] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[20412.871801] Call Trace:
[20412.871933]  
[20412.872045]  dump_stack_lvl+0x56/0x6f
[20412.872190]  __might_resched.cold+0xff/0x13a
[20412.872340]  blk_release_queue+0x24/0x120
[20412.872500]  kobject_put+0x7c/0x1d0
[20412.872642]  ? rcu_do_batch+0x1ac/0x570
[20412.872787]  blkg_free.part.0+0x41/0x60
[20412.872938]  rcu_do_batch+0x1e1/0x570
[20412.873077]  ? rcu_do_batch+0x1ac/0x570
[20412.873219]  rcu_core+0x2cb/0x460
[20412.873352]  __do_softirq+0x178/0x517
[20412.873493]  __irq_exit_rcu+0xe3/0x160
[20412.873637]  irq_exit_rcu+0xa/0x20
[20412.873772]  sysvec_apic_timer_interrupt+0xa2/0xd0
[20412.873932]  
[20412.874054]  
[20412.874169]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[20412.874332] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[20412.874503] 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
[20412.875009] RSP: 0018:ffffb12647697880 EFLAGS: 00000246
[20412.875179] RAX: 0000000080000002 RBX: 0000000000000282 RCX: ffff9be28536cec8
[20412.875397] RDX: ffff9be28536cec8 RSI: ffffffffa24054a9 RDI: ffffffffa2e651fd
[20412.875658] RBP: ffff9be28536ce80 R08: 0000000000000000 R09: 0000000000000000
[20412.875860] R10: ffff9be2fffd3e00 R11: ffffed300409b588 R12: ffff9be28536ce80
[20412.876068] R13: ffff9be2826d7b00 R14: ffff9be28536cec8 R15: ffff9be28536cec0
[20412.876269]  ? get_partial_node.part.0+0x1a9/0x220
[20412.876433]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[20412.876603]  get_partial_node.part.0+0x1a9/0x220
[20412.876783]  ? lock_acquire+0x176/0x2d0
[20412.876936]  ? rcu_read_lock_sched_held+0x12/0x60
[20412.877095]  ? lock_release+0x1ed/0x2c0
[20412.877241]  ___slab_alloc+0x82f/0xf50
[20412.877384]  ? mempool_alloc+0x58/0x190
[20412.877526]  ? trace_hardirqs_on+0x1b/0xe0
[20412.877693]  ? rcu_read_lock_sched_held+0x12/0x60
[20412.877858]  ? lock_acquire+0x176/0x2d0
[20412.878008]  ? rcu_read_lock_sched_held+0x12/0x60
[20412.878166]  ? mempool_alloc+0x58/0x190
[20412.878330]  __slab_alloc.constprop.0+0x4a/0x80
[20412.878485]  ? mempool_alloc+0x58/0x190
[20412.878624]  kmem_cache_alloc+0x343/0x3f0
[20412.878768]  mempool_alloc+0x58/0x190
[20412.878942]  ? lock_release+0x1ed/0x2c0
[20412.879090]  ? rcu_read_lock_sched_held+0x12/0x60
[20412.879251]  bio_alloc_bioset+0x9e/0x3c0
[20412.879414]  bio_alloc_clone+0x20/0x60
[20412.879556]  dm_submit_bio+0x8d/0x630
[20412.879698]  __submit_bio+0x50/0x180
[20412.879836]  submit_bio_noacct_nocheck+0xba/0x280
[20412.880027]  submit_bh_wbc+0x117/0x140
[20412.880174]  __block_write_full_page+0x236/0x550
[20412.880347]  ? bh_uptodate_or_lock+0xa0/0xa0
[20412.880496]  ? blkdev_llseek+0x60/0x60
[20412.887222]  __writepage+0x17/0x70
[20412.887384]  write_cache_pages+0x17d/0x560
[20412.887531]  ? writeout_period+0x80/0x80
[20412.887679]  generic_writepages+0x41/0x60
[20412.887825]  do_writepages+0xbf/0x1b0
[20412.887994]  ? rcu_read_lock_sched_held+0x12/0x60
[20412.888158]  ? lock_release+0x1ed/0x2c0
[20412.888322]  ? lock_release+0x1ed/0x2c0
[20412.888463]  ? _raw_spin_unlock+0x29/0x40
[20412.888611]  filemap_fdatawrite_wbc+0x62/0x90
[20412.888763]  file_write_and_wait_range+0x78/0xc0
[20412.888951]  blkdev_fsync+0x14/0x40
[20412.889091]  __x64_sys_fsync+0x34/0x60
[20412.889238]  do_syscall_64+0x38/0x90
[20412.889397]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[20412.889559] RIP: 0033:0x7f164a62b327
[20412.889698] Code: 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 4a 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 41 c3 48 83 ec 18 89 7c 24 0c e8 b3 ca f8 ff
[20412.890216] RSP: 002b:00007ffcf1faf498 EFLAGS: 00000246 ORIG_RAX: 000000000000004a
[20412.890471] RAX: ffffffffffffffda RBX: 0000000000dbb320 RCX: 00007f164a62b327
[20412.890666] RDX: 0000000000000000 RSI: 0000000000dbb2e0 RDI: 0000000000000004
[20412.890887] RBP: 0000000000dbb2e0 R08: 0000000000dbb580 R09: 00007f164a6f6a60
[20412.891104] R10: 00007f164a724780 R11: 0000000000000246 R12: 0000000000000000
[20412.891301] R13: 0000000000000000 R14: 0000000000dbb370 R15: 0000000000000000
[20412.891500]