[20845.353234] run fstests btrfs/201 at 2022-04-04 02:38:49
[20845.834108] BTRFS: device fsid 055a6fee-ca37-40c8-93e9-59c309263b13 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3531591)
[20845.909323] BTRFS info (device dm-11): flagging fs with big metadata feature
[20845.909560] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[20845.909852] BTRFS info (device dm-11): use zstd compression, level 3
[20845.910096] BTRFS info (device dm-11): turning on async discard
[20845.910293] BTRFS info (device dm-11): disk space caching is enabled
[20845.910493] BTRFS info (device dm-11): has skinny extents
[20845.913647] BTRFS info (device dm-11): checking UUID tree
[20846.092665] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3530715)
[20846.094275] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3530715)
[20846.162451] BTRFS info (device dm-11): flagging fs with big metadata feature
[20846.162784] BTRFS info (device dm-11): use zstd compression, level 3
[20846.163100] BTRFS info (device dm-11): turning on async discard
[20846.163371] BTRFS info (device dm-11): disk space caching is enabled
[20846.163719] BTRFS info (device dm-11): has skinny extents
[20846.166148] BTRFS info (device dm-11): start tree-log replay
[20846.234880] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[20846.235172] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3531707, name: (spawn)
[20846.235453] preempt_count: 101, expected: 0
[20846.235611] RCU nest depth: 1, expected: 0
[20846.235809] INFO: lockdep is turned off.
[20846.235985] Preemption disabled at:
[20846.235986] [<0000000000000000>] 0x0
[20846.236287] CPU: 0 PID: 3531707 Comm: (spawn) Tainted: G W 5.17.0+ #641
[20846.236559] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[20846.236845] Call Trace:
[20846.236985]
[20846.237110] dump_stack_lvl+0x56/0x6f
[20846.237265] __might_resched.cold+0xff/0x13a
[20846.237433] blk_release_queue+0x24/0x120
[20846.237595] kobject_put+0x7c/0x1d0
[20846.237751] ? rcu_do_batch+0x1ac/0x570
[20846.237907] blkg_free.part.0+0x41/0x60
[20846.238066] rcu_do_batch+0x1e1/0x570
[20846.238218] ? rcu_do_batch+0x1ac/0x570
[20846.238373] rcu_core+0x2cb/0x460
[20846.238523] __do_softirq+0x178/0x517
[20846.238680] __irq_exit_rcu+0xe3/0x160
[20846.238839] irq_exit_rcu+0xa/0x20
[20846.238991] sysvec_apic_timer_interrupt+0xa2/0xd0
[20846.239169]
[20846.239292]
[20846.239415] asm_sysvec_apic_timer_interrupt+0x12/0x20
[20846.239599] RIP: 0010:rcu_is_watching+0x23/0x40
[20846.239773] Code: be c0 c3 0f 1f 40 00 53 65 ff 05 78 69 e1 5d e8 e3 ad c4 00 48 c7 c3 90 41 03 00 89 c0 48 03 1c c5 20 5b 93 a3 8b 03 83 e0 01 <65> ff 0d 56 69 e1 5d 74 02 5b c3 0f 1f 44 00 00 5b c3 66 66 2e 0f
[20846.240283] RSP: 0018:ffffb126454a7b78 EFLAGS: 00000202
[20846.240540] RAX: 0000000000000001 RBX: ffff9be2fbc34190 RCX: 0000000000000000
[20846.240790] RDX: 0000000000000000 RSI: ffffffffa387e4d9 RDI: ffffffffa383354b
[20846.241068] RBP: ffffed3004152000 R08: 0000000000000000 R09: 0000000000000000
[20846.241299] R10: 0000000000000000 R11: ffffffffa23cd700 R12: ffff9be2801e7c00
[20846.241515] R13: ffff9be28e6b99c0 R14: ffffb126454a7bd0 R15: ffff9be2801e7c00
[20846.241806] ? free_unref_page_list+0xd0/0x560
[20846.241978] ? rcu_is_watching+0xd/0x40
[20846.242133] memcg_slab_free_hook+0x1b9/0x2b0
[20846.242302] ? memcg_slab_free_hook+0x14a/0x2b0
[20846.242472] ? unlink_anon_vmas+0x79/0x1f0
[20846.242630] kmem_cache_free+0x149/0x3e0
[20846.242793] unlink_anon_vmas+0x79/0x1f0
[20846.242953] free_pgtables+0x8a/0xf0
[20846.243103] exit_mmap+0xa0/0x1b0
[20846.243250] __mmput+0x4a/0x150
[20846.243396] begin_new_exec+0x516/0xcf0
[20846.243553] load_elf_binary+0x69a/0x1720
[20846.243716] ? xfs_iunlock+0x12e/0x180 [xfs]
[20846.243968] ? rcu_read_lock_sched_held+0x12/0x60
[20846.244141] ? lock_acquire+0x176/0x2d0
[20846.244296] ? rcu_read_lock_sched_held+0x12/0x60
[20846.244466] bprm_execve+0x307/0x860
[20846.244619] do_execveat_common+0x1b5/0x230
[20846.244788] __x64_sys_execve+0x39/0x50
[20846.244944] do_syscall_64+0x38/0x90
[20846.245094] entry_SYSCALL_64_after_hwframe+0x44/0xae
[20846.245273] RIP: 0033:0x7fe131f032fb
[20846.245429] Code: Unable to access opcode bytes at RIP 0x7fe131f032d1.
[20846.245629] RSP: 002b:00007ffd60e19048 EFLAGS: 00000202 ORIG_RAX: 000000000000003b
[20846.252153] RAX: ffffffffffffffda RBX: 000055a021ea2df0 RCX: 00007fe131f032fb
[20846.252369] RDX: 000055a021ea6a00 RSI: 000055a021eb0350 RDI: 000055a021eae6c0
[20846.252582] RBP: 00007ffd60e19150 R08: 0000000000000000 R09: 0000000000000000
[20846.252799] R10: 00007ffd60e19020 R11: 0000000000000202 R12: 00007ffd60e191c0
[20846.253011] R13: 000055a021eb0350 R14: 0000000000000000 R15: 000000000aba9500
[20846.253229]
[20846.356789] BTRFS: device fsid 550a9206-7953-4049-a48c-4e9051db93d1 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3531706)
[20846.423369] BTRFS info (device dm-11): flagging fs with big metadata feature
[20846.423608] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[20846.423923] BTRFS info (device dm-11): use zstd compression, level 3
[20846.424140] BTRFS info (device dm-11): turning on async discard
[20846.424338] BTRFS info (device dm-11): disk space caching is enabled
[20846.424542] BTRFS info (device dm-11): has skinny extents
[20846.426660] BTRFS info (device dm-11): checking UUID tree
[20853.549463] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3535760)
[20853.550356] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3535760)
[20853.646234] BTRFS info (device dm-11): flagging fs with big metadata feature
[20853.646541] BTRFS info (device dm-11): use zstd compression, level 3
[20853.646842] BTRFS info (device dm-11): turning on async discard
[20853.647100] BTRFS info (device dm-11): disk space caching is enabled
[20853.647368] BTRFS info (device dm-11): has skinny extents
[20853.650212] BTRFS info (device dm-11): start tree-log replay
[20854.064806] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[20854.065201] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3535802, name: dmsetup
[20854.065566] preempt_count: 102, expected: 0
[20854.065851] RCU nest depth: 0, expected: 0
[20854.066066] INFO: lockdep is turned off.
[20854.066274] Preemption disabled at:
[20854.066275] [<0000000000000000>] 0x0
[20854.066672] CPU: 0 PID: 3535802 Comm: dmsetup Tainted: G W 5.17.0+ #641
[20854.067022] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[20854.067402] Call Trace:
[20854.067572]
[20854.067750] dump_stack_lvl+0x56/0x6f
[20854.067954] __might_resched.cold+0xff/0x13a
[20854.068168] blk_release_queue+0x24/0x120
[20854.068385] kobject_put+0x7c/0x1d0
[20854.068587] ? rcu_do_batch+0x1ac/0x570
[20854.068799] blkg_free.part.0+0x41/0x60
[20854.069012] rcu_do_batch+0x1e1/0x570
[20854.069356] ? rcu_do_batch+0x1ac/0x570
[20854.069592] rcu_core+0x2cb/0x460
[20854.069819] __do_softirq+0x178/0x517
[20854.070105] __irq_exit_rcu+0xe3/0x160
[20854.070337] irq_exit_rcu+0xa/0x20
[20854.070550] sysvec_apic_timer_interrupt+0xa2/0xd0
[20854.070833]
[20854.071016]
[20854.071178] asm_sysvec_apic_timer_interrupt+0x12/0x20
[20854.071421] RIP: 0010:flush_tlb_mm_range+0x18a/0x1a0
[20854.071664] Code: 02 0f 0b 9c 58 0f 1f 40 00 48 89 c2 fa 0f 1f 44 00 00 80 e6 02 75 21 48 89 ef e8 f1 f9 ff ff e8 3c 3e 1a 00 fb 0f 1f 44 00 00 71 ff ff ff 0f 0b 0f 1f 44 00 00 eb 84 e8 a3 3b 1a 00 eb d8 90
[20854.072333] RSP: 0000:ffffb1264548fd10 EFLAGS: 00000246
[20854.072564] RAX: 0000000080000002 RBX: ffff9be2d48be180 RCX: 000000000000000c
[20854.072851] RDX: 00007feaa952a000 RSI: 0000000000000001 RDI: ffffffffa2108414
[20854.073123] RBP: ffff9be2fbc32980 R08: 0000000000000000 R09: 0000000000000000
[20854.073400] R10: 0000000000000002 R11: 0000000000000000 R12: ffff9be2d48be7e8
[20854.073684] R13: 00007feaa9529000 R14: 00007feaa952a000 R15: 0000000000000000
[20854.073967] ? flush_tlb_mm_range+0x184/0x1a0
[20854.074187] ptep_clear_flush+0x55/0x60
[20854.074395] wp_page_copy+0x20e/0x580
[20854.074605] ? _raw_spin_unlock+0x29/0x40
[20854.074826] __handle_mm_fault+0xba9/0x1460
[20854.075042] ? mmap_region+0x2da/0x620
[20854.075254] handle_mm_fault+0x11e/0x3a0
[20854.075468] do_user_addr_fault+0x1e9/0x6b0
[20854.075692] exc_page_fault+0x78/0x280
[20854.075896] ? asm_exc_page_fault+0x8/0x30
[20854.076115] asm_exc_page_fault+0x1e/0x30
[20854.076336] RIP: 0033:0x7feaa953f050
[20854.076541] Code: 0c 48 83 fa 22 77 d3 48 89 04 d1 eb e7 4d 85 d2 74 79 49 8b 44 24 60 48 85 c0 74 04 4c 01 50 08 49 8b 44 24 58 48 85 c0 74 04 <4c> 01 50 08 49 8b 44 24 68 48 85 c0 74 04 4c 01 50 08 49 8b 44 24
[20854.077202] RSP: 002b:00007ffe760bcb20 EFLAGS: 00010202
[20854.077445] RAX: 00007feaa95295c8 RBX: 000000006ffffdff RCX: 00007feaa952e040
[20854.077736] RDX: 0000000000000000 RSI: 0000000000000029 RDI: 000000006fffffff
[20854.078022] RBP: 00007ffe760bcc80 R08: 00000000effffef5 R09: 0000000070000022
[20854.078303] R10: 00007feaa94d1000 R11: 0000000000000032 R12: 00007feaa952e000
[20854.078580] R13: 000000006ffffeff R14: 000000006ffffe35 R15: 0000000000000000
[20854.078869]
[20854.277248] BTRFS: device fsid 2a2472ea-b3fd-4b7e-add0-90261981979d devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3535801)
[20854.348825] BTRFS info (device dm-11): flagging fs with big metadata feature
[20854.349043] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[20854.349299] BTRFS info (device dm-11): use zstd compression, level 3
[20854.349480] BTRFS info (device dm-11): turning on async discard
[20854.349666] BTRFS info (device dm-11): disk space caching is enabled
[20854.349863] BTRFS info (device dm-11): has skinny extents
[20854.351813] BTRFS info (device dm-11): checking UUID tree
[20860.799972] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3539856)
[20860.800983] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3539856)
[20860.895828] BTRFS info (device dm-11): flagging fs with big metadata feature
[20860.896052] BTRFS info (device dm-11): use zstd compression, level 3
[20860.896238] BTRFS info (device dm-11): turning on async discard
[20860.896421] BTRFS info (device dm-11): disk space caching is enabled
[20860.896610] BTRFS info (device dm-11): has skinny extents
[20860.898477] BTRFS info (device dm-11): start tree-log replay
[20861.348729] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[20861.349039] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[20861.349311] preempt_count: 101, expected: 0
[20861.349476] RCU nest depth: 0, expected: 0
[20861.350284] INFO: lockdep is turned off.
[20861.350514] Preemption disabled at:
[20861.350515] [] schedule_preempt_disabled+0x1e/0x20
[20861.351060] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[20861.351469] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[20861.351882] Call Trace:
[20861.352074]
[20861.352253] dump_stack_lvl+0x56/0x6f
[20861.352480] __might_resched.cold+0xff/0x13a
[20861.352725] blk_release_queue+0x24/0x120
[20861.352952] kobject_put+0x7c/0x1d0
[20861.353168] ? rcu_do_batch+0x1ac/0x570
[20861.353402] blkg_free.part.0+0x41/0x60
[20861.353631] rcu_do_batch+0x1e1/0x570
[20861.353795] ? rcu_do_batch+0x1ac/0x570
[20861.353949] rcu_core+0x2cb/0x460
[20861.354092] __do_softirq+0x178/0x517
[20861.354239] __irq_exit_rcu+0xe3/0x160
[20861.354389] irq_exit_rcu+0xa/0x20
[20861.354533] sysvec_apic_timer_interrupt+0xa2/0xd0
[20861.354707]
[20861.354824]
[20861.354944] asm_sysvec_apic_timer_interrupt+0x12/0x20
[20861.355116] RIP: 0010:native_safe_halt+0xb/0x10
[20861.355277] 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
[20861.355779] RSP: 0018:ffffffffa3a03ea8 EFLAGS: 00000246
[20861.355955] RAX: ffffffffa2e64a80 RBX: ffffffffa3a30a00 RCX: 0000000000000000
[20861.356158] RDX: ffffffffa3a30a00 RSI: ffffffffa387e4d9 RDI: ffffffffa2e64bf6
[20861.356360] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[20861.356567] R10: 0000000000000000 R11: ffff9be2fbc20c88 R12: 0000000000000000
[20861.356785] R13: 0000000000000000 R14: ffffffffa3a30118 R15: 0000000000000065
[20861.356985] ? mwait_idle+0x80/0x80
[20861.357138] ? default_idle_call+0x26/0xa0
[20861.357301] default_idle+0xa/0x10
[20861.357447] default_idle_call+0x5e/0xa0
[20861.357610] do_idle+0x1e9/0x240
[20861.357780] cpu_startup_entry+0x19/0x20
[20861.357937] start_kernel+0x98c/0x99e
[20861.358082] ? copy_bootdata+0x18/0x55
[20861.358234] secondary_startup_64_no_verify+0xd5/0xdb
[20861.358413]
[20861.629511] BTRFS: device fsid 9cb2a1b0-9fae-4f33-a644-66db2f9bdbee devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3539898)
[20861.698158] BTRFS info (device dm-11): flagging fs with big metadata feature
[20861.698381] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[20861.698671] BTRFS info (device dm-11): use zstd compression, level 3
[20861.698869] BTRFS info (device dm-11): turning on async discard
[20861.699046] BTRFS info (device dm-11): disk space caching is enabled
[20861.699225] BTRFS info (device dm-11): has skinny extents
[20861.701054] BTRFS info (device dm-11): checking UUID tree
[20867.956498] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3543952)
[20867.957367] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3543952)
[20868.054699] BTRFS info (device dm-11): flagging fs with big metadata feature
[20868.054919] BTRFS info (device dm-11): use zstd compression, level 3
[20868.055102] BTRFS info (device dm-11): turning on async discard
[20868.055276] BTRFS info (device dm-11): disk space caching is enabled
[20868.055457] BTRFS info (device dm-11): has skinny extents
[20868.063315] BTRFS info (device dm-11): start tree-log replay
[20868.524658] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[20868.524954] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[20868.525206] preempt_count: 101, expected: 0
[20868.525357] RCU nest depth: 0, expected: 0
[20868.525506] INFO: lockdep is turned off.
[20868.525665] Preemption disabled at:
[20868.525666] [] ___slab_alloc+0x961/0xf50
[20868.525977] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #641
[20868.526214] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[20868.526473] Call Trace:
[20868.526597]
[20868.526715] dump_stack_lvl+0x56/0x6f
[20868.526855] __might_resched.cold+0xff/0x13a
[20868.527006] blk_release_queue+0x24/0x120
[20868.527155] kobject_put+0x7c/0x1d0
[20868.527294] ? rcu_do_batch+0x1ac/0x570
[20868.527435] blkg_free.part.0+0x41/0x60
[20868.527579] rcu_do_batch+0x1e1/0x570
[20868.527719] ? rcu_do_batch+0x1ac/0x570
[20868.527860] rcu_core+0x2cb/0x460
[20868.527992] __do_softirq+0x178/0x517
[20868.528136] __irq_exit_rcu+0xe3/0x160
[20868.528281] irq_exit_rcu+0xa/0x20
[20868.528416] sysvec_apic_timer_interrupt+0xa2/0xd0
[20868.528580]
[20868.528695]
[20868.528807] asm_sysvec_apic_timer_interrupt+0x12/0x20
[20868.528972] RIP: 0010:native_safe_halt+0xb/0x10
[20868.529128] 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
[20868.529586] RSP: 0018:ffffb12640093ef8 EFLAGS: 00000246
[20868.529749] RAX: ffffffffa2e64a80 RBX: ffff9be280378000 RCX: 0000000000000000
[20868.529943] RDX: ffff9be280378000 RSI: ffffffffa387e4d9 RDI: ffffffffa2e64bf6
[20868.530140] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[20868.530334] R10: 0000000000000000 R11: ffff9be2fbd20c88 R12: 0000000000000000
[20868.530533] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[20868.530727] ? mwait_idle+0x80/0x80
[20868.530866] ? default_idle_call+0x26/0xa0
[20868.531012] default_idle+0xa/0x10
[20868.531145] default_idle_call+0x5e/0xa0
[20868.531290] do_idle+0x1e9/0x240
[20868.531422] cpu_startup_entry+0x19/0x20
[20868.531569] secondary_startup_64_no_verify+0xd5/0xdb
[20868.531767]