[15463.563132] run fstests btrfs/201 at 2022-04-03 01:20:10
[15463.940369] BTRFS: device fsid 0ef7fb69-9685-4c6f-b3d9-90c151830f57 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3432282)
[15463.999154] BTRFS info (device dm-11): flagging fs with big metadata feature
[15463.999602] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[15464.000100] BTRFS info (device dm-11): use lzo compression, level 0
[15464.000489] BTRFS info (device dm-11): disk space caching is enabled
[15464.001113] BTRFS info (device dm-11): has skinny extents
[15464.003224] BTRFS info (device dm-11): checking UUID tree
[15464.175246] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3431491)
[15464.176468] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3431491)
[15464.234970] BTRFS info (device dm-11): flagging fs with big metadata feature
[15464.235428] BTRFS info (device dm-11): use lzo compression, level 0
[15464.235803] BTRFS info (device dm-11): disk space caching is enabled
[15464.236194] BTRFS info (device dm-11): has skinny extents
[15464.237959] BTRFS info (device dm-11): start tree-log replay
[15464.295174] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15464.295698] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3432397, name: dmsetup
[15464.296268] preempt_count: 100, expected: 0
[15464.296534] RCU nest depth: 0, expected: 0
[15464.296792] INFO: lockdep is turned off.
[15464.297089] Preemption disabled at:
[15464.297090] [] __do_softirq+0x5e/0x517
[15464.297668] CPU: 1 PID: 3432397 Comm: dmsetup Tainted: G W 5.17.0+ #640
[15464.298174] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15464.298712] Call Trace:
[15464.298879]
[15464.299012] dump_stack_lvl+0x56/0x6f
[15464.299252] __might_resched.cold+0xff/0x13a
[15464.299526] blk_release_queue+0x24/0x120
[15464.299858] kobject_put+0x7c/0x1d0
[15464.300102] ? rcu_do_batch+0x1ac/0x570
[15464.300374] blkg_free.part.0+0x41/0x60
[15464.300717] rcu_do_batch+0x1e1/0x570
[15464.301018] ? rcu_do_batch+0x1ac/0x570
[15464.301318] rcu_core+0x2cb/0x460
[15464.301556] __do_softirq+0x178/0x517
[15464.301872] __irq_exit_rcu+0xe3/0x160
[15464.302164] irq_exit_rcu+0xa/0x20
[15464.302455] sysvec_apic_timer_interrupt+0xa2/0xd0
[15464.302866]
[15464.303058]
[15464.303225] asm_sysvec_apic_timer_interrupt+0x12/0x20
[15464.303535] RIP: 0010:__seccomp_filter+0xc8/0x5d0
[15464.303816] Code: 00 00 48 c7 c7 35 6c 82 ac e8 d4 d7 f1 ff 0f 1f 44 00 00 49 8b 47 30 49 8d 77 48 4c 89 ef ff d0 0f 1f 00 89 c3 e8 58 4f f2 ff <89> d9 44 89 e2 44 89 e0 66 31 c9 66 31 d2 39 d1 7d 09 48 89 2c 24
[15464.304928] RSP: 0018:ffffa8c183effe88 EFLAGS: 00000282
[15464.305275] RAX: 0000000080000000 RBX: 000000007fff0000 RCX: 0000000000000000
[15464.305694] RDX: 0000000000000001 RSI: ffffffffac87e4d9 RDI: 00000000ffffffff
[15464.306115] RBP: ffff9da11059cc00 R08: 0000000000080000 R09: 00007f229f6193e0
[15464.306533] R10: 00000000ffffff9c R11: 0000000000000000 R12: 000000007fff0000
[15464.306951] R13: ffffa8c183effe98 R14: 0000000000000000 R15: ffffa8c1805b5000
[15464.307384] ? __seccomp_filter+0xc8/0x5d0
[15464.307685] syscall_trace_enter.constprop.0+0x95/0x220
[15464.308001] do_syscall_64+0x16/0x90
[15464.308219] entry_SYSCALL_64_after_hwframe+0x44/0xae
[15464.308522] RIP: 0033:0x7f229f63e228
[15464.308742] Code: f9 41 89 f0 41 83 e2 40 75 30 89 f0 25 00 00 41 00 3d 00 00 41 00 74 22 44 89 c2 4c 89 ce bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 30 c3 0f 1f 80 00 00 00 00 48 8d 44 24 08 c7
[15464.309837] RSP: 002b:00007ffffb042ac8 EFLAGS: 00000287 ORIG_RAX: 0000000000000101
[15464.310350] RAX: ffffffffffffffda RBX: 00007ffffb042b8f RCX: 00007f229f63e228
[15464.310770] RDX: 0000000000080000 RSI: 00007f229f6193e0 RDI: 00000000ffffff9c
[15464.311191] RBP: 00007ffffb042b30 R08: 0000000000080000 R09: 00007f229f6193e0
[15464.311611] R10: 0000000000000000 R11: 0000000000000287 R12: 00007f229f618000
[15464.312033] R13: 00007ffffb042ba0 R14: 00007f229f6193e0 R15: 00007ffffb042ba0
[15464.312486]
[15464.350153] BTRFS: device fsid 29d2f313-1ddc-4a67-9174-ee99e9354849 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3432396)
[15464.412572] BTRFS info (device dm-11): flagging fs with big metadata feature
[15464.413012] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[15464.413572] BTRFS info (device dm-11): use lzo compression, level 0
[15464.413988] BTRFS info (device dm-11): disk space caching is enabled
[15464.414422] BTRFS info (device dm-11): has skinny extents
[15464.416424] BTRFS info (device dm-11): checking UUID tree
[15470.098141] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3436451)
[15470.099526] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3436451)
[15470.192229] BTRFS info (device dm-11): flagging fs with big metadata feature
[15470.192666] BTRFS info (device dm-11): use lzo compression, level 0
[15470.193044] BTRFS info (device dm-11): disk space caching is enabled
[15470.193435] BTRFS info (device dm-11): has skinny extents
[15470.195397] BTRFS info (device dm-11): start tree-log replay
[15470.554113] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15470.554604] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3436493, name: (spawn)
[15470.555094] preempt_count: 100, expected: 0
[15470.555364] RCU nest depth: 0, expected: 0
[15470.555612] INFO: lockdep is turned off.
[15470.555875] Preemption disabled at:
[15470.555876] [] __do_softirq+0x5e/0x517
[15470.556502] CPU: 0 PID: 3436493 Comm: (spawn) Tainted: G W 5.17.0+ #640
[15470.557094] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15470.557613] Call Trace:
[15470.557765]
[15470.557893] dump_stack_lvl+0x56/0x6f
[15470.558316] __might_resched.cold+0xff/0x13a
[15470.558751] blk_release_queue+0x24/0x120
[15470.559095] kobject_put+0x7c/0x1d0
[15470.559375] ? rcu_do_batch+0x1ac/0x570
[15470.559653] blkg_free.part.0+0x41/0x60
[15470.560009] rcu_do_batch+0x1e1/0x570
[15470.560233] ? rcu_do_batch+0x1ac/0x570
[15470.560466] rcu_core+0x2cb/0x460
[15470.560668] __do_softirq+0x178/0x517
[15470.560891] __irq_exit_rcu+0xe3/0x160
[15470.561126] irq_exit_rcu+0xa/0x20
[15470.561334] sysvec_apic_timer_interrupt+0xa2/0xd0
[15470.561620]
[15470.561751]
[15470.561884] asm_sysvec_apic_timer_interrupt+0x12/0x20
[15470.562198] RIP: 0010:kmem_cache_free+0x49/0x3e0
[15470.562476] Code: aa 03 00 00 48 c7 c0 00 00 00 80 31 db 48 2b 05 4d 49 52 01 4c 01 e8 48 c1 e8 0c 48 c1 e0 06 48 03 05 2b 49 52 01 48 8b 50 08 c2 01 0f 85 07 03 00 00 0f 1f 44 00 00 48 8b 10 80 e6 02 0f 84
[15470.563585] RSP: 0018:ffffa8c183f1fbb8 EFLAGS: 00000282
[15470.563905] RAX: ffffeeb2040f8a40 RBX: 0000000000000000 RCX: ffff9da109d78000
[15470.564335] RDX: ffffeeb2041656c0 RSI: ffff9da103e293e8 RDI: ffff9da1001e8a00
[15470.564755] RBP: ffff9da11a167190 R08: ffff9da1034e9cc0 R09: ffff9da109d78000
[15470.565181] R10: 0000000000000000 R11: ffff9da105286b00 R12: ffff9da103e293e8
[15470.565602] R13: ffff9da183e293e8 R14: dead000000000100 R15: ffff9da1034e9190
[15470.566035] __put_anon_vma+0x4c/0xd0
[15470.566258] unlink_anon_vmas+0x1a3/0x1f0
[15470.566500] free_pgtables+0x8a/0xf0
[15470.566717] exit_mmap+0xa0/0x1b0
[15470.566975] __mmput+0x4a/0x150
[15470.567168] begin_new_exec+0x516/0xcf0
[15470.567401] load_elf_binary+0x69a/0x1720
[15470.567646] ? xfs_iunlock+0x12e/0x180 [xfs]
[15470.567978] ? rcu_read_lock_sched_held+0x12/0x60
[15470.568262] ? lock_acquire+0x176/0x2d0
[15470.568495] ? rcu_read_lock_sched_held+0x12/0x60
[15470.568777] bprm_execve+0x307/0x860
[15470.568999] do_execveat_common+0x1b5/0x230
[15470.569250] __x64_sys_execve+0x39/0x50
[15470.569482] do_syscall_64+0x38/0x90
[15470.569699] entry_SYSCALL_64_after_hwframe+0x44/0xae
[15470.570003] RIP: 0033:0x7f87d36fe33b
[15470.570225] Code: Unable to access opcode bytes at RIP 0x7f87d36fe311.
[15470.570609] RSP: 002b:00007fff440815f8 EFLAGS: 00000206 ORIG_RAX: 000000000000003b
[15470.571059] RAX: ffffffffffffffda RBX: 000055cc060a3640 RCX: 00007f87d36fe33b
[15470.571478] RDX: 000055cc06061360 RSI: 000055cc06071ac0 RDI: 000055cc06066b80
[15470.571895] RBP: 00007fff44081700 R08: 0000000000000000 R09: 0000000000000000
[15470.572316] R10: 00007fff440815d0 R11: 0000000000000206 R12: 00007fff44081770
[15470.572735] R13: 000055cc06071ac0 R14: 0000000000000000 R15: 000000000aba9500
[15470.573166]
[15470.623137] BTRFS: device fsid de8d44d0-5fab-48b6-8f93-fab160f91928 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3436492)
[15470.692752] BTRFS info (device dm-11): flagging fs with big metadata feature
[15470.693241] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[15470.693755] BTRFS info (device dm-11): use lzo compression, level 0
[15470.694170] BTRFS info (device dm-11): disk space caching is enabled
[15470.694568] BTRFS info (device dm-11): has skinny extents
[15470.697255] BTRFS info (device dm-11): checking UUID tree
[15476.469359] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3440547)
[15476.470619] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3440547)
[15476.560289] BTRFS info (device dm-11): flagging fs with big metadata feature
[15476.560723] BTRFS info (device dm-11): use lzo compression, level 0
[15476.561126] BTRFS info (device dm-11): disk space caching is enabled
[15476.561502] BTRFS info (device dm-11): has skinny extents
[15476.564075] BTRFS info (device dm-11): start tree-log replay
[15476.930052] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15476.930541] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[15476.931005] preempt_count: 101, expected: 0
[15476.931270] RCU nest depth: 0, expected: 0
[15476.931517] INFO: lockdep is turned off.
[15476.931751] Preemption disabled at:
[15476.931752] [] schedule_preempt_disabled+0x1e/0x20
[15476.932351] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #640
[15476.932800] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15476.933305] Call Trace:
[15476.933458]
[15476.933585] dump_stack_lvl+0x56/0x6f
[15476.933810] __might_resched.cold+0xff/0x13a
[15476.934072] blk_release_queue+0x24/0x120
[15476.934315] kobject_put+0x7c/0x1d0
[15476.934532] ? rcu_do_batch+0x1ac/0x570
[15476.934770] blkg_free.part.0+0x41/0x60
[15476.935006] rcu_do_batch+0x1e1/0x570
[15476.935229] ? rcu_do_batch+0x1ac/0x570
[15476.935464] rcu_core+0x2cb/0x460
[15476.935668] __do_softirq+0x178/0x517
[15476.935896] __irq_exit_rcu+0xe3/0x160
[15476.936124] irq_exit_rcu+0xa/0x20
[15476.936332] sysvec_apic_timer_interrupt+0xa2/0xd0
[15476.936621]
[15476.936754]
[15476.936891] asm_sysvec_apic_timer_interrupt+0x12/0x20
[15476.937201] RIP: 0010:native_safe_halt+0xb/0x10
[15476.937474] Code: 54 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
[15476.938572] RSP: 0018:ffffffffaca03ea8 EFLAGS: 00000246
[15476.938887] RAX: ffffffffabe64a80 RBX: ffffffffaca30a00 RCX: 0000000000000000
[15476.939310] RDX: ffffffffaca30a00 RSI: ffffffffac87e4d9 RDI: ffffffffabe64bf6
[15476.939731] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[15476.940164] R10: 0000000000000000 R11: ffff9da17bc20c88 R12: 0000000000000000
[15476.940585] R13: 0000000000000000 R14: ffffffffaca30118 R15: 0000000000000073
[15476.941013] ? mwait_idle+0x80/0x80
[15476.941227] ? default_idle_call+0x26/0xa0
[15476.941476] default_idle+0xa/0x10
[15476.941683] default_idle_call+0x5e/0xa0
[15476.941923] do_idle+0x1e9/0x240
[15476.942121] cpu_startup_entry+0x19/0x20
[15476.942359] start_kernel+0x98c/0x99e
[15476.942583] ? copy_bootdata+0x18/0x55
[15476.942813] secondary_startup_64_no_verify+0xd5/0xdb
[15476.943121]
[15476.998238] BTRFS: device fsid a310207d-fa48-42ed-b8f8-c806e76d045c devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3440589)
[15477.060730] BTRFS info (device dm-11): flagging fs with big metadata feature
[15477.061207] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[15477.061703] BTRFS info (device dm-11): use lzo compression, level 0
[15477.062095] BTRFS info (device dm-11): disk space caching is enabled
[15477.062511] BTRFS info (device dm-11): has skinny extents
[15477.065230] BTRFS info (device dm-11): checking UUID tree
[15482.793048] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3444644)
[15482.794307] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3444644)
[15482.886262] BTRFS info (device dm-11): flagging fs with big metadata feature
[15482.886734] BTRFS info (device dm-11): use lzo compression, level 0
[15482.887163] BTRFS info (device dm-11): disk space caching is enabled
[15482.887549] BTRFS info (device dm-11): has skinny extents
[15482.890238] BTRFS info (device dm-11): start tree-log replay
[15483.260987] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15483.261478] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[15483.261941] preempt_count: 101, expected: 0
[15483.262204] RCU nest depth: 0, expected: 0
[15483.262450] INFO: lockdep is turned off.
[15483.262684] Preemption disabled at:
[15483.262685] [] ___slab_alloc+0x961/0xf50
[15483.263238] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #640
[15483.263687] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15483.264191] Call Trace:
[15483.264343]
[15483.264470] dump_stack_lvl+0x56/0x6f
[15483.264693] __might_resched.cold+0xff/0x13a
[15483.264956] blk_release_queue+0x24/0x120
[15483.265199] kobject_put+0x7c/0x1d0
[15483.265412] ? rcu_do_batch+0x1ac/0x570
[15483.265649] blkg_free.part.0+0x41/0x60
[15483.265888] rcu_do_batch+0x1e1/0x570
[15483.266109] ? rcu_do_batch+0x1ac/0x570
[15483.266341] rcu_core+0x2cb/0x460
[15483.266543] __do_softirq+0x178/0x517
[15483.266764] __irq_exit_rcu+0xe3/0x160
[15483.266995] irq_exit_rcu+0xa/0x20
[15483.267200] sysvec_apic_timer_interrupt+0xa2/0xd0
[15483.267484]
[15483.267615]
[15483.267746] asm_sysvec_apic_timer_interrupt+0x12/0x20
[15483.268052] RIP: 0010:native_safe_halt+0xb/0x10
[15483.268323] Code: 54 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
[15483.269404] RSP: 0018:ffffa8c180093ef8 EFLAGS: 00000246
[15483.269714] RAX: ffffffffabe64a80 RBX: ffff9da100340000 RCX: 0000000000000000
[15483.270133] RDX: ffff9da100340000 RSI: ffffffffac87e4d9 RDI: ffffffffabe64bf6
[15483.270550] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[15483.270975] R10: 0000000000000000 R11: ffff9da17bd20c88 R12: 0000000000000000
[15483.271392] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[15483.271820] ? mwait_idle+0x80/0x80
[15483.272033] ? default_idle_call+0x26/0xa0
[15483.272283] default_idle+0xa/0x10
[15483.272488] default_idle_call+0x5e/0xa0
[15483.272723] do_idle+0x1e9/0x240
[15483.272924] cpu_startup_entry+0x19/0x20
[15483.273159] secondary_startup_64_no_verify+0xd5/0xdb
[15483.273462]