[ 3893.374284] run fstests btrfs/159 at 2022-04-03 21:55:47
[ 3893.610597] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3893.611060] BTRFS info (device dm-0): using free space tree
[ 3893.611414] BTRFS info (device dm-0): has skinny extents
[ 3893.794865] BTRFS: device fsid d88292b4-529b-4ea9-ae90-cae432eb4fa9 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (647353)
[ 3893.872302] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3893.872907] BTRFS info (device dm-11): disk space caching is enabled
[ 3893.873368] BTRFS info (device dm-11): has skinny extents
[ 3893.876314] BTRFS info (device dm-11): checking UUID tree
[ 3898.743024] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (649065)
[ 3898.744394] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (649065)
[ 3898.857771] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3898.858340] BTRFS info (device dm-11): disk space caching is enabled
[ 3898.858779] BTRFS info (device dm-11): has skinny extents
[ 3898.861574] BTRFS info (device dm-11): start tree-log replay
[ 3899.939495] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3899.940031] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 649107, name: mkfs.btrfs
[ 3899.940764] preempt_count: 100, expected: 0
[ 3899.941125] RCU nest depth: 0, expected: 0
[ 3899.941502] INFO: lockdep is turned off.
[ 3899.941845] Preemption disabled at:
[ 3899.941847] [] __do_softirq+0x5e/0x517
[ 3899.942574] CPU: 0 PID: 649107 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[ 3899.943175] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3899.943733] Call Trace:
[ 3899.943899]
[ 3899.944037] dump_stack_lvl+0x56/0x6f
[ 3899.944281] __might_resched.cold+0xff/0x13a
[ 3899.944575] blk_release_queue+0x24/0x120
[ 3899.944838] kobject_put+0x7c/0x1d0
[ 3899.945069] ? rcu_do_batch+0x1ac/0x570
[ 3899.945321] blkg_free.part.0+0x41/0x60
[ 3899.945583] rcu_do_batch+0x1e1/0x570
[ 3899.945824] ? rcu_do_batch+0x1ac/0x570
[ 3899.946077] rcu_core+0x2cb/0x460
[ 3899.946296] __do_softirq+0x178/0x517
[ 3899.946554] __irq_exit_rcu+0xe3/0x160
[ 3899.946804] irq_exit_rcu+0xa/0x20
[ 3899.947055] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3899.947427]
[ 3899.947575]
[ 3899.947721] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3899.948054] RIP: 0010:__kmalloc_node+0x0/0x3b0
[ 3899.948345] Code: f0 48 83 28 01 0f 85 9b fd ff ff 49 8b 47 08 4c 89 ff 48 8b 40 08 ff d0 0f 1f 00 e9 86 fd ff ff 66 2e 0f 1f 84 00 00 00 00 00 <0f> 1f 44 00 00 41 57 41 56 49 89 fe 41 55 41 89 f5 41 54 55 53 48
[ 3899.949552] RSP: 0018:ffffa284430f3b60 EFLAGS: 00000206
[ 3899.949889] RAX: 0000000000000000 RBX: ffffc95d044c0700 RCX: 0000000000000000
[ 3899.950350] RDX: 0000000000000000 RSI: 0000000000008d40 RDI: 00000000000000c0
[ 3899.950819] RBP: 0000000000000000 R08: ffffa284430f3bd0 R09: ffff94fc5301c690
[ 3899.951274] R10: ffff94fcbffd5000 R11: 000000000003a560 R12: ffff94fc45878340
[ 3899.951744] R13: 0000000000000000 R14: ffffa284430f3bd0 R15: ffffc95d044c0700
[ 3899.952209] memcg_alloc_slab_cgroups+0x38/0x90
[ 3899.952523] memcg_slab_post_alloc_hook+0xb9/0x440
[ 3899.952835] kmem_cache_alloc+0x1d1/0x3f0
[ 3899.953098] alloc_buffer_head+0x1b/0xc0
[ 3899.953358] alloc_page_buffers+0xc4/0x1e0
[ 3899.953646] ? blkdev_llseek+0x60/0x60
[ 3899.953896] create_empty_buffers+0x19/0x250
[ 3899.954176] create_page_buffers+0x75/0x90
[ 3899.954457] __block_write_begin_int+0x70/0x500
[ 3899.954761] ? blkdev_llseek+0x60/0x60
[ 3899.955009] ? blkdev_llseek+0x60/0x60
[ 3899.955258] ? blkdev_llseek+0x60/0x60
[ 3899.955516] block_write_begin+0x50/0x130
[ 3899.955782] generic_perform_write+0xa9/0x1d0
[ 3899.956070] __generic_file_write_iter+0xdb/0x1a0
[ 3899.956378] blkdev_write_iter+0xc1/0x150
[ 3899.956660] new_sync_write+0xf6/0x160
[ 3899.956908] vfs_write+0x290/0x390
[ 3899.957133] __x64_sys_pwrite64+0x6f/0xb0
[ 3899.957397] do_syscall_64+0x38/0x90
[ 3899.957697] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 3899.958025] RIP: 0033:0x7fcaa7e2919a
[ 3899.958263] Code: d8 64 89 02 48 c7 c0 ff ff ff ff eb ba 0f 1f 00 f3 0f 1e fa 49 89 ca 64 8b 04 25 18 00 00 00 85 c0 75 15 b8 12 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5e c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24
[ 3899.959459] RSP: 002b:00007ffe18f4c288 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[ 3899.959947] RAX: ffffffffffffffda RBX: 00007fcaa7a26010 RCX: 00007fcaa7e2919a
[ 3899.960400] RDX: 0000000000200000 RSI: 00007fcaa7a26010 RDI: 0000000000000003
[ 3899.960863] RBP: 0000000000200000 R08: 00007fcaa7a26010 R09: 0000000000000000
[ 3899.961316] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[ 3899.961781] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[ 3899.962237]
[ 3900.061997] BTRFS: device fsid b24def5a-e191-4464-bd99-02d01bd038d4 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (649107)
[ 3900.133149] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3900.133653] BTRFS info (device dm-11): disk space caching is enabled
[ 3900.134074] BTRFS info (device dm-11): has skinny extents
[ 3900.136236] BTRFS info (device dm-11): checking UUID tree
[ 3904.368399] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (650819)
[ 3904.369817] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (650819)
[ 3904.485387] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 3904.486039] BTRFS info (device dm-11): disk space caching is enabled
[ 3904.486615] BTRFS info (device dm-11): has skinny extents
[ 3904.489476] BTRFS info (device dm-11): start tree-log replay
[ 3905.567443] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3905.567990] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 3905.568502] preempt_count: 101, expected: 0
[ 3905.568777] RCU nest depth: 0, expected: 0
[ 3905.569039] INFO: lockdep is turned off.
[ 3905.569287] Preemption disabled at:
[ 3905.569287] [] schedule_preempt_disabled+0x1e/0x20
[ 3905.569939] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[ 3905.570415] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3905.570949] Call Trace:
[ 3905.571111]
[ 3905.571246] dump_stack_lvl+0x56/0x6f
[ 3905.571487] __might_resched.cold+0xff/0x13a
[ 3905.571764] blk_release_queue+0x24/0x120
[ 3905.572077] kobject_put+0x7c/0x1d0
[ 3905.572386] ? rcu_do_batch+0x1ac/0x570
[ 3905.572703] blkg_free.part.0+0x41/0x60
[ 3905.572976] rcu_do_batch+0x1e1/0x570
[ 3905.573293] ? rcu_do_batch+0x1ac/0x570
[ 3905.573620] rcu_core+0x2cb/0x460
[ 3905.573912] __do_softirq+0x178/0x517
[ 3905.574237] __irq_exit_rcu+0xe3/0x160
[ 3905.574567] irq_exit_rcu+0xa/0x20
[ 3905.574868] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3905.575285]
[ 3905.575481]
[ 3905.575671] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3905.576118] RIP: 0010:native_safe_halt+0xb/0x10
[ 3905.576515] Code: 56 ff ff ff 7f 5b c3 65 48 8b 04 25 c0 fe 01 00 f0 80 48 02 20 48 8b 00 a8 08 75 c3 eb 80 cc eb 07 0f 00 2d 79 b7 5b 00 fb f4 0f 1f 40 00 eb 07 0f 00 2d 69 b7 5b 00 f4 c3 cc cc cc cc cc 0f
[ 3905.578089] RSP: 0018:ffffffffaaa03ea8 EFLAGS: 00000246
[ 3905.578539] RAX: ffffffffa9e64a80 RBX: ffffffffaaa30a00 RCX: 0000000000000000
[ 3905.579145] RDX: ffffffffaaa30a00 RSI: ffffffffaa87e4d9 RDI: ffffffffa9e64bf6
[ 3905.579752] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 3905.580300] R10: 0000000000000000 R11: ffff94fcbbc20c88 R12: 0000000000000000
[ 3905.580759] R13: 0000000000000000 R14: ffffffffaaa30118 R15: 0000000000000073
[ 3905.581208] ? mwait_idle+0x80/0x80
[ 3905.581439] ? default_idle_call+0x26/0xa0
[ 3905.581709] default_idle+0xa/0x10
[ 3905.581929] default_idle_call+0x5e/0xa0
[ 3905.582181] do_idle+0x1e9/0x240
[ 3905.582397] cpu_startup_entry+0x19/0x20
[ 3905.582655] start_kernel+0x98c/0x99e
[ 3905.582890] ? copy_bootdata+0x18/0x55
[ 3905.583130] secondary_startup_64_no_verify+0xd5/0xdb
[ 3905.583460]