[35057.388338] run fstests btrfs/201 at 2022-04-04 06:35:41
[35057.841099] BTRFS: device fsid 2bc4c53f-0539-48cf-845a-665e9c10cf95 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3172094)
[35057.923729] BTRFS info (device dm-11): flagging fs with big metadata feature
[35057.923966] BTRFS info (device dm-11): turning on sync discard
[35057.924155] BTRFS info (device dm-11): enabling free space tree
[35057.924334] BTRFS info (device dm-11): using free space tree
[35057.924506] BTRFS info (device dm-11): has skinny extents
[35057.926474] BTRFS info (device dm-11): creating free space tree
[35057.932424] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[35057.932689] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[35057.942979] BTRFS info (device dm-11): cleaning free space cache v1
[35057.951992] BTRFS info (device dm-11): checking UUID tree
[35058.144078] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3171322)
[35058.144838] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3171322)
[35058.195046] BTRFS info (device dm-11): flagging fs with big metadata feature
[35058.195268] BTRFS info (device dm-11): turning on sync discard
[35058.195453] BTRFS info (device dm-11): using free space tree
[35058.195622] BTRFS info (device dm-11): has skinny extents
[35058.198009] BTRFS info (device dm-11): start tree-log replay
[35058.273928] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[35058.274204] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3172208, name: mkfs.btrfs
[35058.274544] preempt_count: 100, expected: 0
[35058.274702] RCU nest depth: 0, expected: 0
[35058.274940] INFO: lockdep is turned off.
[35058.275166] Preemption disabled at:
[35058.275167] [] __do_softirq+0x5e/0x517
[35058.275524] CPU: 0 PID: 3172208 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[35058.275777] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[35058.276043] Call Trace:
[35058.276163]
[35058.276275] dump_stack_lvl+0x56/0x6f
[35058.276423] __might_resched.cold+0xff/0x13a
[35058.276579] blk_release_queue+0x24/0x120
[35058.276727] kobject_put+0x7c/0x1d0
[35058.276866] ? rcu_do_batch+0x1ac/0x570
[35058.277008] blkg_free.part.0+0x41/0x60
[35058.277150] rcu_do_batch+0x1e1/0x570
[35058.277291] ? rcu_do_batch+0x1ac/0x570
[35058.277441] rcu_core+0x2cb/0x460
[35058.277574] __do_softirq+0x178/0x517
[35058.277719] __irq_exit_rcu+0xe3/0x160
[35058.277863] irq_exit_rcu+0xa/0x20
[35058.277995] sysvec_apic_timer_interrupt+0xa2/0xd0
[35058.278152]
[35058.278267]
[35058.278387] asm_sysvec_apic_timer_interrupt+0x12/0x20
[35058.278551] RIP: 0010:percpu_ref_get_many+0x1/0xf0
[35058.278711] Code: ff 48 89 3c 24 e8 ff e1 ff ff 48 8b 3c 24 eb ae 48 89 3c 24 e8 f0 e1 ff ff 48 8b 3c 24 eb c2 0f 0b 0f 0b 66 0f 1f 44 00 00 55 <48> 89 f5 53 48 89 fb e8 83 30 de ff 31 d2 45 31 c9 45 31 c0 68 50
[35058.279174] RSP: 0000:ffffb12644d0fd90 EFLAGS: 00000246
[35058.280033] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000
[35058.280305] RDX: 000000000000001a RSI: 0000000000000001 RDI: ffff9be29284a010
[35058.280595] RBP: ffffed3005ccdc40 R08: 0000000000000000 R09: 0000000000000000
[35058.280906] R10: 0000000000000000 R11: 0000000000000000 R12: ffff9be29284a000
[35058.281196] R13: 0000000000000cc0 R14: 0000000000000078 R15: ffff9be2e2b3a000
[35058.281496] charge_memcg+0xf7/0x1a0
[35058.281706] __mem_cgroup_charge+0x2d/0x60
[35058.281929] __handle_mm_fault+0xb68/0x1460
[35058.282155] handle_mm_fault+0x11e/0x3a0
[35058.282378] do_user_addr_fault+0x1e9/0x6b0
[35058.282602] exc_page_fault+0x78/0x280
[35058.282813] ? asm_exc_page_fault+0x8/0x30
[35058.283034] asm_exc_page_fault+0x1e/0x30
[35058.283254] RIP: 0033:0x7fe58bc9e411
[35058.283450] Code: 01 00 00 48 83 fa 40 77 77 c5 fe 7f 44 17 e0 c5 fe 7f 07 c5 f8 77 c3 66 90 f3 0f 1e fa c5 f8 77 48 89 d1 40 0f b6 c6 48 89 fa aa 48 89 d0 c3 66 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 48 39 d1
[35058.283904] RSP: 002b:00007fff85efc1f8 EFLAGS: 00010206
[35058.284068] RAX: 0000000000000000 RBX: 0000000001e33c70 RCX: 0000000000139c70
[35058.284265] RDX: 0000000001e33c70 RSI: 0000000000000000 RDI: 0000000001efa000
[35058.284464] RBP: 0000000000200000 R08: 0000000001e33c70 R09: 00007fe58bcfda60
[35058.284661] R10: 0000000000200030 R11: 0000000001e51000 R12: 0000000000000000
[35058.284855] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[35058.285054]
[35058.391560] BTRFS: device fsid b074c21a-ce2e-44b2-a281-f2575b7362ab devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3172208)
[35058.451680] BTRFS info (device dm-11): flagging fs with big metadata feature
[35058.451906] BTRFS info (device dm-11): turning on sync discard
[35058.452105] BTRFS info (device dm-11): enabling free space tree
[35058.452285] BTRFS info (device dm-11): using free space tree
[35058.452461] BTRFS info (device dm-11): has skinny extents
[35058.454272] BTRFS info (device dm-11): creating free space tree
[35058.454570] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[35058.454839] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[35058.464009] BTRFS info (device dm-11): cleaning free space cache v1
[35058.472634] BTRFS info (device dm-11): checking UUID tree
[35064.073752] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3176262)
[35064.074672] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3176262)
[35064.166265] BTRFS info (device dm-11): flagging fs with big metadata feature
[35064.166526] BTRFS info (device dm-11): turning on sync discard
[35064.166701] BTRFS info (device dm-11): using free space tree
[35064.166898] BTRFS info (device dm-11): has skinny extents
[35064.169204] BTRFS info (device dm-11): start tree-log replay
[35064.554884] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[35064.555278] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[35064.555652] preempt_count: 101, expected: 0
[35064.555886] RCU nest depth: 0, expected: 0
[35064.556089] INFO: lockdep is turned off.
[35064.556333] Preemption disabled at:
[35064.556335] [] schedule_preempt_disabled+0x1e/0x20
[35064.556802] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[35064.557161] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[35064.557536] Call Trace:
[35064.557712]
[35064.557881] dump_stack_lvl+0x56/0x6f
[35064.558089] __might_resched.cold+0xff/0x13a
[35064.558322] blk_release_queue+0x24/0x120
[35064.558541] kobject_put+0x7c/0x1d0
[35064.558742] ? rcu_do_batch+0x1ac/0x570
[35064.558930] blkg_free.part.0+0x41/0x60
[35064.559083] rcu_do_batch+0x1e1/0x570
[35064.559219] ? rcu_do_batch+0x1ac/0x570
[35064.559362] rcu_core+0x2cb/0x460
[35064.559494] __do_softirq+0x178/0x517
[35064.559633] __irq_exit_rcu+0xe3/0x160
[35064.559768] irq_exit_rcu+0xa/0x20
[35064.559904] sysvec_apic_timer_interrupt+0xa2/0xd0
[35064.560061]
[35064.560168]
[35064.560276] asm_sysvec_apic_timer_interrupt+0x12/0x20
[35064.560442] RIP: 0010:native_safe_halt+0xb/0x10
[35064.560591] Code: 5d 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
[35064.561039] RSP: 0018:ffffffffa3a03ea8 EFLAGS: 00000246
[35064.561201] RAX: ffffffffa2e64a80 RBX: ffffffffa3a30a00 RCX: 0000000000000000
[35064.561395] RDX: ffffffffa3a30a00 RSI: ffffffffa387e4d9 RDI: ffffffffa2e64bf6
[35064.561586] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[35064.561773] R10: 0000000000000000 R11: ffff9be2fbc20c88 R12: 0000000000000000
[35064.561973] R13: 0000000000000000 R14: ffffffffa3a30118 R15: 0000000000000065
[35064.562159] ? mwait_idle+0x80/0x80
[35064.562297] ? default_idle_call+0x26/0xa0
[35064.562439] default_idle+0xa/0x10
[35064.562571] default_idle_call+0x5e/0xa0
[35064.562711] do_idle+0x1e9/0x240
[35064.562839] cpu_startup_entry+0x19/0x20
[35064.562985] start_kernel+0x98c/0x99e
[35064.563123] ? copy_bootdata+0x18/0x55
[35064.563259] secondary_startup_64_no_verify+0xd5/0xdb
[35064.563423]
[35064.704648] BTRFS: device fsid b77dddb6-3548-49b8-a166-c204b881a705 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3176303)
[35064.779878] BTRFS info (device dm-11): flagging fs with big metadata feature
[35064.780100] BTRFS info (device dm-11): turning on sync discard
[35064.780276] BTRFS info (device dm-11): enabling free space tree
[35064.780456] BTRFS info (device dm-11): using free space tree
[35064.780633] BTRFS info (device dm-11): has skinny extents
[35064.782541] BTRFS info (device dm-11): creating free space tree
[35064.782829] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[35064.783112] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[35064.798408] BTRFS info (device dm-11): cleaning free space cache v1
[35064.809753] BTRFS info (device dm-11): checking UUID tree
[35070.393561] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3180359)
[35070.394457] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3180359)
[35070.500166] BTRFS info (device dm-11): flagging fs with big metadata feature
[35070.500392] BTRFS info (device dm-11): turning on sync discard
[35070.500568] BTRFS info (device dm-11): using free space tree
[35070.500737] BTRFS info (device dm-11): has skinny extents
[35070.503106] BTRFS info (device dm-11): start tree-log replay
[35070.940823] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[35070.941217] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3180401, name: mkfs.btrfs
[35070.942177] preempt_count: 101, expected: 0
[35070.942401] RCU nest depth: 0, expected: 0
[35070.942612] INFO: lockdep is turned off.
[35070.942833] Preemption disabled at:
[35070.942835] [<0000000000000000>] 0x0
[35070.951364] CPU: 1 PID: 3180401 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[35070.951727] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[35070.952100] Call Trace:
[35070.952239]
[35070.952355] dump_stack_lvl+0x56/0x6f
[35070.952496] __might_resched.cold+0xff/0x13a
[35070.952647] blk_release_queue+0x24/0x120
[35070.952799] kobject_put+0x7c/0x1d0
[35070.952939] ? rcu_do_batch+0x1ac/0x570
[35070.953083] blkg_free.part.0+0x41/0x60
[35070.953234] rcu_do_batch+0x1e1/0x570
[35070.953374] ? rcu_do_batch+0x1ac/0x570
[35070.953515] rcu_core+0x2cb/0x460
[35070.953649] __do_softirq+0x178/0x517
[35070.953790] __irq_exit_rcu+0xe3/0x160
[35070.953937] irq_exit_rcu+0xa/0x20
[35070.954073] sysvec_apic_timer_interrupt+0xa2/0xd0
[35070.954262]
[35070.954376]
[35070.954491] asm_sysvec_apic_timer_interrupt+0x12/0x20
[35070.954656] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[35070.954836] 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
[35070.955307] RSP: 0018:ffffb12645057b60 EFLAGS: 00000246
[35070.955474] RAX: 0000000080000001 RBX: 0000000000000202 RCX: 0000000000000000
[35070.955694] RDX: 0000000000000002 RSI: ffffffffa242f1ee RDI: ffffffffa2e651fd
[35070.955896] RBP: ffffffffa3ca5940 R08: 0000000000000000 R09: 0000000000000000
[35070.956092] R10: 0000000000000002 R11: 0000000000000000 R12: ffffb12645057c08
[35070.956289] R13: ffffb12645057c18 R14: ffffb12645057ca8 R15: 000000000000022a
[35070.956484] ? mem_cgroup_wb_stats+0xee/0x100
[35070.956632] ? _raw_spin_unlock_irqrestore+0x2d/0x60
[35070.956794] mem_cgroup_wb_stats+0xee/0x100
[35070.956948] ? rcu_read_lock_sched_held+0x12/0x60
[35070.957110] balance_dirty_pages+0x47c/0xeb0
[35070.957264] ? xas_set_mark+0x59/0xc0
[35070.957403] ? __xa_set_mark+0x53/0x60
[35070.957550] balance_dirty_pages_ratelimited+0x507/0xa50
[35070.957718] generic_perform_write+0x141/0x1d0
[35070.957878] __generic_file_write_iter+0xdb/0x1a0
[35070.958034] blkdev_write_iter+0xc1/0x150
[35070.958181] new_sync_write+0xf6/0x160
[35070.958329] vfs_write+0x290/0x390
[35070.958464] __x64_sys_pwrite64+0x6f/0xb0
[35070.958605] do_syscall_64+0x38/0x90
[35070.958745] entry_SYSCALL_64_after_hwframe+0x44/0xae
[35070.958915] RIP: 0033:0x7ff433a3c53a
[35070.959053] 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
[35070.959519] RSP: 002b:00007fff762f38b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[35070.959758] RAX: ffffffffffffffda RBX: 00007ff43363d010 RCX: 00007ff433a3c53a
[35070.959957] RDX: 0000000000200000 RSI: 00007ff43363d010 RDI: 0000000000000003
[35070.960150] RBP: 0000000000200000 R08: 00007ff43363d010 R09: 0000000000000000
[35070.960351] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[35070.960545] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[35070.960743]
[35071.091227] BTRFS: device fsid 3c7f1a58-ddc7-4c62-b984-e62cfb15fd39 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3180401)
[35071.161949] BTRFS info (device dm-11): flagging fs with big metadata feature
[35071.162173] BTRFS info (device dm-11): turning on sync discard
[35071.162361] BTRFS info (device dm-11): enabling free space tree
[35071.162542] BTRFS info (device dm-11): using free space tree
[35071.162714] BTRFS info (device dm-11): has skinny extents
[35071.164666] BTRFS info (device dm-11): creating free space tree
[35071.164959] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[35071.165229] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[35071.186246] BTRFS info (device dm-11): cleaning free space cache v1
[35071.194883] BTRFS info (device dm-11): checking UUID tree
[35076.868290] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3184455)
[35076.869598] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3184455)
[35076.958763] BTRFS info (device dm-11): flagging fs with big metadata feature
[35076.958984] BTRFS info (device dm-11): turning on sync discard
[35076.959158] BTRFS info (device dm-11): using free space tree
[35076.959333] BTRFS info (device dm-11): has skinny extents
[35076.961464] BTRFS info (device dm-11): start tree-log replay
[35077.336814] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[35077.337089] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[35077.337338] preempt_count: 100, expected: 0
[35077.337487] RCU nest depth: 0, expected: 0
[35077.337631] INFO: lockdep is turned off.
[35077.337784] Preemption disabled at:
[35077.337784] [] __do_softirq+0x5e/0x517
[35077.338090] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G W 5.17.0+ #641
[35077.338336] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[35077.338604] Call Trace:
[35077.338722]
[35077.338840] dump_stack_lvl+0x56/0x6f
[35077.338981] __might_resched.cold+0xff/0x13a
[35077.339130] blk_release_queue+0x24/0x120
[35077.339291] kobject_put+0x7c/0x1d0
[35077.339431] ? rcu_do_batch+0x1ac/0x570
[35077.339577] blkg_free.part.0+0x41/0x60
[35077.339718] rcu_do_batch+0x1e1/0x570
[35077.339858] ? rcu_do_batch+0x1ac/0x570
[35077.339998] rcu_core+0x2cb/0x460
[35077.340130] __do_softirq+0x178/0x517
[35077.340281] ? smpboot_thread_fn+0x2c/0x270
[35077.340432] run_ksoftirqd+0x43/0x70
[35077.340568] smpboot_thread_fn+0x1c1/0x270
[35077.340713] ? sort_range+0x20/0x20
[35077.340855] kthread+0xf0/0x120
[35077.340984] ? kthread_complete_and_exit+0x20/0x20
[35077.341139] ret_from_fork+0x1f/0x30
[35077.341285]