[ 5212.537953] run fstests btrfs/201 at 2022-04-03 22:17:46
[ 5213.112135] BTRFS: device fsid 6aa23a29-bd8c-4b34-ae0b-012421d8e0ef devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1016931)
[ 5213.169686] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5213.170137] BTRFS info (device dm-11): disk space caching is enabled
[ 5213.170515] BTRFS info (device dm-11): has skinny extents
[ 5213.172269] BTRFS info (device dm-11): checking UUID tree
[ 5213.343055] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1016711)
[ 5213.344199] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1016711)
[ 5213.393081] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5213.393559] BTRFS info (device dm-11): disk space caching is enabled
[ 5213.393987] BTRFS info (device dm-11): has skinny extents
[ 5213.395768] BTRFS info (device dm-11): start tree-log replay
[ 5213.471857] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 5213.472360] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1017045, name: mkfs.btrfs
[ 5213.472972] preempt_count: 100, expected: 0
[ 5213.473283] RCU nest depth: 0, expected: 0
[ 5213.473592] INFO: lockdep is turned off.
[ 5213.473915] Preemption disabled at:
[ 5213.473916] [] __do_softirq+0x5e/0x517
[ 5213.474531] CPU: 0 PID: 1017045 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[ 5213.475049] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 5213.475560] Call Trace:
[ 5213.475733]
[ 5213.475862] dump_stack_lvl+0x56/0x6f
[ 5213.476087] __might_resched.cold+0xff/0x13a
[ 5213.476344] blk_release_queue+0x24/0x120
[ 5213.476585] kobject_put+0x7c/0x1d0
[ 5213.476804] ? rcu_do_batch+0x1ac/0x570
[ 5213.477035] blkg_free.part.0+0x41/0x60
[ 5213.477266] rcu_do_batch+0x1e1/0x570
[ 5213.477487] ? rcu_do_batch+0x1ac/0x570
[ 5213.477722] rcu_core+0x2cb/0x460
[ 5213.477924] __do_softirq+0x178/0x517
[ 5213.478148] __irq_exit_rcu+0xe3/0x160
[ 5213.478379] irq_exit_rcu+0xa/0x20
[ 5213.478585] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 5213.478873]
[ 5213.479004]
[ 5213.479134] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 5213.479441] RIP: 0010:obj_cgroup_charge+0x104/0x330
[ 5213.479735] Code: 01 00 00 48 c7 43 28 00 00 00 00 48 c7 c6 3d ea 42 a9 48 89 df e8 dc 82 db ff 4d 85 e4 74 0b e8 f2 d7 e7 ff fb 0f 1f 44 00 00 <45> 31 e4 45 84 ff 0f 85 99 00 00 00 49 89 ef 81 e5 ff 0f 00 00 49
[ 5213.480819] RSP: 0018:ffffa284420c3b98 EFLAGS: 00000246
[ 5213.481160] RAX: 0000000080000000 RBX: ffff94fcbbc2f620 RCX: 0000000000000000
[ 5213.481576] RDX: 0000000000000788 RSI: ffffffffa942ea3d RDI: ffffffffa942ea5e
[ 5213.481994] RBP: 00000000000000b0 R08: 0000000000000000 R09: 0000000000000000
[ 5213.482410] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000200
[ 5213.482828] R13: ffff94fc43fdc5c0 R14: 0000000000408d40 R15: 0000000000000001
[ 5213.483246] ? obj_cgroup_charge+0xdd/0x330
[ 5213.483496] ? obj_cgroup_charge+0xfe/0x330
[ 5213.483758] ? obj_cgroup_charge+0xfe/0x330
[ 5213.484008] ? alloc_buffer_head+0x1b/0xc0
[ 5213.484256] kmem_cache_alloc+0x93/0x3f0
[ 5213.484491] alloc_buffer_head+0x1b/0xc0
[ 5213.484732] alloc_page_buffers+0xc4/0x1e0
[ 5213.484978] ? blkdev_llseek+0x60/0x60
[ 5213.485204] create_empty_buffers+0x19/0x250
[ 5213.485459] create_page_buffers+0x75/0x90
[ 5213.485708] __block_write_begin_int+0x70/0x500
[ 5213.485979] ? blkdev_llseek+0x60/0x60
[ 5213.486205] ? blkdev_llseek+0x60/0x60
[ 5213.486431] ? blkdev_llseek+0x60/0x60
[ 5213.486655] block_write_begin+0x50/0x130
[ 5213.486900] generic_perform_write+0xa9/0x1d0
[ 5213.487165] __generic_file_write_iter+0xdb/0x1a0
[ 5213.487445] blkdev_write_iter+0xc1/0x150
[ 5213.487693] new_sync_write+0xf6/0x160
[ 5213.487920] vfs_write+0x290/0x390
[ 5213.488127] __x64_sys_pwrite64+0x6f/0xb0
[ 5213.488367] do_syscall_64+0x38/0x90
[ 5213.488583] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 5213.488886] RIP: 0033:0x7f0ea674019a
[ 5213.489101] 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
[ 5213.490183] RSP: 002b:00007fff2ef02fa8 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[ 5213.490625] RAX: ffffffffffffffda RBX: 0000000001edcc70 RCX: 00007f0ea674019a
[ 5213.491046] RDX: 0000000000200000 RSI: 0000000001edcc70 RDI: 0000000000000003
[ 5213.491460] RBP: 0000000000200000 R08: 0000000001edcc70 R09: 00007f0ea6721a60
[ 5213.491882] R10: 000000027fe00000 R11: 0000000000000246 R12: 0000000000000000
[ 5213.492304] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[ 5213.492730]
[ 5213.621158] BTRFS: device fsid 695880c5-6ac5-49f8-8179-a7c159b65c80 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1017045)
[ 5213.678446] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5213.678905] BTRFS info (device dm-11): disk space caching is enabled
[ 5213.679283] BTRFS info (device dm-11): has skinny extents
[ 5213.681187] BTRFS info (device dm-11): checking UUID tree
[ 5220.206666] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1021099)
[ 5220.207899] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1021099)
[ 5220.296503] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5220.296957] BTRFS info (device dm-11): disk space caching is enabled
[ 5220.297331] BTRFS info (device dm-11): has skinny extents
[ 5220.299687] BTRFS info (device dm-11): start tree-log replay
[ 5221.043787] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 5221.044275] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 5221.044743] preempt_count: 101, expected: 0
[ 5221.045005] RCU nest depth: 0, expected: 0
[ 5221.045250] INFO: lockdep is turned off.
[ 5221.045484] Preemption disabled at:
[ 5221.045485] [] ___slab_alloc+0x961/0xf50
[ 5221.046040] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #641
[ 5221.046484] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 5221.046991] Call Trace:
[ 5221.047144]
[ 5221.047271] dump_stack_lvl+0x56/0x6f
[ 5221.047496] __might_resched.cold+0xff/0x13a
[ 5221.047761] blk_release_queue+0x24/0x120
[ 5221.048003] kobject_put+0x7c/0x1d0
[ 5221.048216] ? rcu_do_batch+0x1ac/0x570
[ 5221.048448] blkg_free.part.0+0x41/0x60
[ 5221.048688] rcu_do_batch+0x1e1/0x570
[ 5221.048908] ? rcu_do_batch+0x1ac/0x570
[ 5221.049139] rcu_core+0x2cb/0x460
[ 5221.049340] __do_softirq+0x178/0x517
[ 5221.049563] __irq_exit_rcu+0xe3/0x160
[ 5221.049791] irq_exit_rcu+0xa/0x20
[ 5221.049998] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 5221.050315]
[ 5221.050477]
[ 5221.050615] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 5221.050920] RIP: 0010:native_safe_halt+0xb/0x10
[ 5221.051190] 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
[ 5221.052285] RSP: 0018:ffffa28440093ef8 EFLAGS: 00000246
[ 5221.052600] RAX: ffffffffa9e64a80 RBX: ffff94fc40340000 RCX: 0000000000000000
[ 5221.053016] RDX: ffff94fc40340000 RSI: ffffffffaa87e4d9 RDI: ffffffffa9e64bf6
[ 5221.053442] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 5221.053862] R10: 0000000000000000 R11: ffff94fcbbd20c88 R12: 0000000000000000
[ 5221.054277] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 5221.054700] ? mwait_idle+0x80/0x80
[ 5221.054909] ? default_idle_call+0x26/0xa0
[ 5221.055154] default_idle+0xa/0x10
[ 5221.055359] default_idle_call+0x5e/0xa0
[ 5221.055600] do_idle+0x1e9/0x240
[ 5221.055800] cpu_startup_entry+0x19/0x20
[ 5221.056064] secondary_startup_64_no_verify+0xd5/0xdb
[ 5221.056368]
[ 5221.212228] BTRFS: device fsid d0985b4d-da92-4501-96ae-21a9388d4f3b devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1021140)
[ 5221.281319] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5221.281766] BTRFS info (device dm-11): disk space caching is enabled
[ 5221.282168] BTRFS info (device dm-11): has skinny extents
[ 5221.285044] BTRFS info (device dm-11): checking UUID tree
[ 5227.927842] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1025195)
[ 5227.929205] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1025195)
[ 5228.021678] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5228.022193] BTRFS info (device dm-11): disk space caching is enabled
[ 5228.022626] BTRFS info (device dm-11): has skinny extents
[ 5228.025019] BTRFS info (device dm-11): start tree-log replay
[ 5228.857712] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 5228.858202] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1025237, name: mkfs.btrfs
[ 5228.858724] preempt_count: 100, expected: 0
[ 5228.858991] RCU nest depth: 0, expected: 0
[ 5228.859250] INFO: lockdep is turned off.
[ 5228.859485] Preemption disabled at:
[ 5228.859486] [] __do_softirq+0x5e/0x517
[ 5228.860036] CPU: 0 PID: 1025237 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[ 5228.860601] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 5228.861105] Call Trace:
[ 5228.861257]
[ 5228.861384] dump_stack_lvl+0x56/0x6f
[ 5228.861616] __might_resched.cold+0xff/0x13a
[ 5228.861876] blk_release_queue+0x24/0x120
[ 5228.862127] kobject_put+0x7c/0x1d0
[ 5228.862341] ? rcu_do_batch+0x1ac/0x570
[ 5228.862606] blkg_free.part.0+0x41/0x60
[ 5228.862865] rcu_do_batch+0x1e1/0x570
[ 5228.863088] ? rcu_do_batch+0x1ac/0x570
[ 5228.863319] rcu_core+0x2cb/0x460
[ 5228.863613] __do_softirq+0x178/0x517
[ 5228.863839] __irq_exit_rcu+0xe3/0x160
[ 5228.864068] irq_exit_rcu+0xa/0x20
[ 5228.864276] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 5228.864573]
[ 5228.864704]
[ 5228.864835] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 5228.865139] RIP: 0010:charge_memcg+0x75/0x1a0
[ 5228.865402] Code: 07 01 00 00 48 8b 55 00 a8 02 0f 85 df 00 00 00 48 83 e0 fc 80 e6 02 0f 85 10 01 00 00 48 85 c0 0f 85 f6 00 00 00 4c 89 65 38 <9c> 58 0f 1f 40 00 48 89 c2 fa 0f 1f 44 00 00 80 e6 02 0f 85 bf 00
[ 5228.866492] RSP: 0018:ffffa284422abb68 EFLAGS: 00000246
[ 5228.866810] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
[ 5228.867258] RDX: 0017ffffc0000001 RSI: ffffffffa942629f RDI: ffffffffaab9ce40
[ 5228.867687] RBP: ffffc95d040da600 R08: 0000000000000000 R09: 0000000000000000
[ 5228.868106] R10: 0000000000000000 R11: 0000000000000000 R12: ffff94fc43c9c000
[ 5228.868534] R13: 0000000000101cc0 R14: ffffa284422abc30 R15: 0000000000000000
[ 5228.868955] ? percpu_ref_get_many+0x4f/0xf0
[ 5228.869215] ? charge_memcg+0xf7/0x1a0
[ 5228.869442] __mem_cgroup_charge+0x2d/0x60
[ 5228.869697] __filemap_add_folio+0x375/0x6a0
[ 5228.869955] ? mod_lruvec_state+0x60/0x60
[ 5228.870198] filemap_add_folio+0x27/0x80
[ 5228.870435] __filemap_get_folio+0x1ac/0x4d0
[ 5228.870703] ? blkdev_llseek+0x60/0x60
[ 5228.870931] pagecache_get_page+0x15/0x90
[ 5228.871179] block_write_begin+0x24/0x130
[ 5228.871422] generic_perform_write+0xa9/0x1d0
[ 5228.871695] __generic_file_write_iter+0xdb/0x1a0
[ 5228.871976] blkdev_write_iter+0xc1/0x150
[ 5228.872219] new_sync_write+0xf6/0x160
[ 5228.872446] vfs_write+0x290/0x390
[ 5228.872662] __x64_sys_pwrite64+0x6f/0xb0
[ 5228.872905] do_syscall_64+0x38/0x90
[ 5228.873122] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 5228.873424] RIP: 0033:0x7fb7536f919a
[ 5228.873650] 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
[ 5228.874744] RSP: 002b:00007ffef7367e78 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[ 5228.875190] RAX: ffffffffffffffda RBX: 00007fb7532f6010 RCX: 00007fb7536f919a
[ 5228.875634] RDX: 0000000000200000 RSI: 00007fb7532f6010 RDI: 0000000000000003
[ 5228.876070] RBP: 0000000000200000 R08: 00007fb7532f6010 R09: 0000000000000000
[ 5228.876491] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[ 5228.876917] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[ 5228.877336]
[ 5229.753471] BTRFS: device fsid d81fab8e-6585-4e49-a50c-2a9578f5c14e devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1025237)
[ 5229.818778] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5229.819251] BTRFS info (device dm-11): disk space caching is enabled
[ 5229.819778] BTRFS info (device dm-11): has skinny extents
[ 5229.821950] BTRFS info (device dm-11): checking UUID tree
[ 5235.653920] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1029292)
[ 5235.655183] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1029292)
[ 5235.747413] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 5235.747887] BTRFS info (device dm-11): disk space caching is enabled
[ 5235.748306] BTRFS info (device dm-11): has skinny extents
[ 5235.750283] BTRFS info (device dm-11): start tree-log replay
[ 5236.184648] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 5236.185140] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 5236.185609] preempt_count: 101, expected: 0
[ 5236.185877] RCU nest depth: 0, expected: 0
[ 5236.186125] INFO: lockdep is turned off.
[ 5236.186361] Preemption disabled at:
[ 5236.186361] [] schedule_preempt_disabled+0x1e/0x20
[ 5236.186966] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[ 5236.187413] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 5236.187921] Call Trace:
[ 5236.188072]
[ 5236.188202] dump_stack_lvl+0x56/0x6f
[ 5236.188430] __might_resched.cold+0xff/0x13a
[ 5236.188689] blk_release_queue+0x24/0x120
[ 5236.188936] kobject_put+0x7c/0x1d0
[ 5236.189152] ? rcu_do_batch+0x1ac/0x570
[ 5236.189385] blkg_free.part.0+0x41/0x60
[ 5236.189624] rcu_do_batch+0x1e1/0x570
[ 5236.189848] ? rcu_do_batch+0x1ac/0x570
[ 5236.190080] rcu_core+0x2cb/0x460
[ 5236.190284] __do_softirq+0x178/0x517
[ 5236.190513] __irq_exit_rcu+0xe3/0x160
[ 5236.190742] irq_exit_rcu+0xa/0x20
[ 5236.190949] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 5236.191237]
[ 5236.191367]
[ 5236.191509] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 5236.191819] RIP: 0010:native_safe_halt+0xb/0x10
[ 5236.192091] 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
[ 5236.193557] RSP: 0018:ffffffffaaa03ea8 EFLAGS: 00000246
[ 5236.193870] RAX: ffffffffa9e64a80 RBX: ffffffffaaa30a00 RCX: 0000000000000000
[ 5236.194290] RDX: ffffffffaaa30a00 RSI: ffffffffaa87e4d9 RDI: ffffffffa9e64bf6
[ 5236.194714] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 5236.195133] R10: 0000000000000000 R11: ffff94fcbbc20c88 R12: 0000000000000000
[ 5236.195557] R13: 0000000000000000 R14: ffffffffaaa30118 R15: 0000000000000073
[ 5236.196128] ? mwait_idle+0x80/0x80
[ 5236.196431] ? default_idle_call+0x26/0xa0
[ 5236.196858] default_idle+0xa/0x10
[ 5236.197216] default_idle_call+0x5e/0xa0
[ 5236.197583] do_idle+0x1e9/0x240
[ 5236.197851] cpu_startup_entry+0x19/0x20
[ 5236.198169] start_kernel+0x98c/0x99e
[ 5236.198521] ? copy_bootdata+0x18/0x55
[ 5236.198831] secondary_startup_64_no_verify+0xd5/0xdb
[ 5236.199274]