[ 4468.927940] run fstests btrfs/201 at 2022-04-03 22:10:57
[ 4469.381336] BTRFS: device fsid 008cea79-999b-4e77-b754-ed740bf04c33 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (856611)
[ 4469.440989] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4469.441223] BTRFS info (device dm-9): using free space tree
[ 4469.441398] BTRFS info (device dm-9): has skinny extents
[ 4469.443421] BTRFS info (device dm-9): checking UUID tree
[ 4469.664688] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (855740)
[ 4469.665787] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (855740)
[ 4469.717858] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4469.718082] BTRFS info (device dm-9): using free space tree
[ 4469.718252] BTRFS info (device dm-9): has skinny extents
[ 4469.720123] BTRFS info (device dm-9): start tree-log replay
[ 4469.795086] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4469.795475] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 856726, name: dmsetup
[ 4469.795839] preempt_count: 100, expected: 0
[ 4469.796061] RCU nest depth: 0, expected: 0
[ 4469.796293] INFO: lockdep is turned off.
[ 4469.796507] Preemption disabled at:
[ 4469.796509] [] __do_softirq+0x5e/0x517
[ 4469.796976] CPU: 1 PID: 856726 Comm: dmsetup Tainted: G W 5.17.0+ #641
[ 4469.797341] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4469.797735] Call Trace:
[ 4469.797909]
[ 4469.798077] dump_stack_lvl+0x56/0x6f
[ 4469.798286] __might_resched.cold+0xff/0x13a
[ 4469.798504] blk_release_queue+0x24/0x120
[ 4469.798726] kobject_put+0x7c/0x1d0
[ 4469.798930] ? rcu_do_batch+0x1ac/0x570
[ 4469.799136] blkg_free.part.0+0x41/0x60
[ 4469.799350] rcu_do_batch+0x1e1/0x570
[ 4469.799563] ? rcu_do_batch+0x1ac/0x570
[ 4469.799786] rcu_core+0x2cb/0x460
[ 4469.799990] __do_softirq+0x178/0x517
[ 4469.800204] __irq_exit_rcu+0xe3/0x160
[ 4469.800420] irq_exit_rcu+0xa/0x20
[ 4469.800626] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4469.800862]
[ 4469.801020]
[ 4469.801186] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4469.801430] RIP: 0010:vma_interval_tree_insert+0x3c/0xb0
[ 4469.801735] Code: f2 4c 8d 5f 58 48 2b 07 48 c1 e8 0c 49 8d 74 00 ff 49 8b 02 48 85 c0 74 60 41 b9 01 00 00 00 eb 03 48 89 d0 48 39 70 18 73 04 <48> 89 70 18 48 8d 48 10 4c 3b 40 40 72 07 48 8d 48 08 45 31 c9 48
[ 4469.802442] RSP: 0018:ffffb94a01da7bd8 EFLAGS: 00000297
[ 4469.802690] RAX: ffff96344ea091e8 RBX: ffff963456760000 RCX: 0000009224171000
[ 4469.802989] RDX: 0000000000000000 RSI: 0000000000000033 RDI: ffff96344c146000
[ 4469.803297] RBP: ffff9634404f6d58 R08: 0000000000000000 R09: 0000000000000001
[ 4469.803610] R10: ffff9634404f6e50 R11: ffff96344c146058 R12: ffff96344c1460f0
[ 4469.803902] R13: ffff96344c1460e8 R14: ffff96344c146000 R15: ffff96344c1460c8
[ 4469.804195] vma_link+0x1a0/0x1c0
[ 4469.804401] mmap_region+0x423/0x620
[ 4469.804630] do_mmap+0x33d/0x530
[ 4469.804828] vm_mmap_pgoff+0xb8/0x150
[ 4469.805036] elf_map+0x9e/0xf0
[ 4469.805238] load_elf_binary+0xb27/0x1720
[ 4469.805444] bprm_execve+0x307/0x860
[ 4469.805655] do_execveat_common+0x1b5/0x230
[ 4469.805875] __x64_sys_execve+0x39/0x50
[ 4469.806080] do_syscall_64+0x38/0x90
[ 4469.806280] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 4469.806518] RIP: 0033:0x7fa5351d504b
[ 4469.806736] Code: Unable to access opcode bytes at RIP 0x7fa5351d5021.
[ 4469.807001] RSP: 002b:00007ffe36da88d8 EFLAGS: 00000202 ORIG_RAX: 000000000000003b
[ 4469.807350] RAX: ffffffffffffffda RBX: 000055748436c910 RCX: 00007fa5351d504b
[ 4469.807622] RDX: 00005574843665e0 RSI: 0000557484308d00 RDI: 000055748436c8a0
[ 4469.807818] RBP: 00007ffe36da89e0 R08: 0000000000000000 R09: 0000000000000000
[ 4469.808011] R10: 00007ffe36da88b0 R11: 0000000000000202 R12: 00007ffe36da8a50
[ 4469.808209] R13: 0000557484308d00 R14: 0000000000000000 R15: 000000000aba9500
[ 4469.808406]
[ 4469.990095] BTRFS: device fsid 5b98df03-50c0-4e17-9bac-9ef1ced03019 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (856725)
[ 4470.058911] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4470.059153] BTRFS info (device dm-9): using free space tree
[ 4470.059328] BTRFS info (device dm-9): has skinny extents
[ 4470.062193] BTRFS info (device dm-9): checking UUID tree
[ 4475.610425] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (860779)
[ 4475.611354] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (860779)
[ 4475.702416] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4475.702641] BTRFS info (device dm-9): using free space tree
[ 4475.702810] BTRFS info (device dm-9): has skinny extents
[ 4475.704943] BTRFS info (device dm-9): start tree-log replay
[ 4476.202026] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4476.202311] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 860779, name: systemd-udevd
[ 4476.203659] preempt_count: 100, expected: 0
[ 4476.203819] RCU nest depth: 0, expected: 0
[ 4476.203967] INFO: lockdep is turned off.
[ 4476.204134] Preemption disabled at:
[ 4476.204135] [] __do_softirq+0x5e/0x517
[ 4476.204461] CPU: 1 PID: 860779 Comm: systemd-udevd Tainted: G W 5.17.0+ #641
[ 4476.204727] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4476.204994] Call Trace:
[ 4476.205138]
[ 4476.205250] dump_stack_lvl+0x56/0x6f
[ 4476.205394] __might_resched.cold+0xff/0x13a
[ 4476.205549] blk_release_queue+0x24/0x120
[ 4476.205702] kobject_put+0x7c/0x1d0
[ 4476.205845] ? rcu_do_batch+0x1ac/0x570
[ 4476.205987] blkg_free.part.0+0x41/0x60
[ 4476.206129] rcu_do_batch+0x1e1/0x570
[ 4476.206271] ? rcu_do_batch+0x1ac/0x570
[ 4476.206416] rcu_core+0x2cb/0x460
[ 4476.206556] __do_softirq+0x178/0x517
[ 4476.206698] __irq_exit_rcu+0xe3/0x160
[ 4476.206840] irq_exit_rcu+0xa/0x20
[ 4476.206974] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4476.207131]
[ 4476.207247]
[ 4476.207361] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4476.207529] RIP: 0010:do_user_addr_fault+0x2ba/0x6b0
[ 4476.207694] Code: 01 00 00 48 83 c4 18 4c 89 ea 48 89 ee 4c 89 e7 5b 5d 41 5c 41 5d 41 5e 41 5f e9 b1 fb ff ff e8 9c 79 1a 00 fb 0f 1f 44 00 00 54 02 00 00 e9 31 fe ff ff 0f 0b e9 4c ff ff ff 31 f6 4c 89 f7
[ 4476.208161] RSP: 0000:ffffb94a01ea7ee0 EFLAGS: 00000246
[ 4476.208327] RAX: 0000000080000000 RBX: 0000000000000000 RCX: 0000000000000000
[ 4476.208521] RDX: 0000557484412000 RSI: 0000000000000006 RDI: ffffffffa21048b4
[ 4476.208720] RBP: 0000000000000006 R08: 0000000000000000 R09: 0000000000000000
[ 4476.208917] R10: 0000000000000000 R11: 0000000000000000 R12: ffffb94a01ea7f58
[ 4476.209114] R13: 0000557484412000 R14: ffff963450933a80 R15: 0000000000000000
[ 4476.209314] ? do_user_addr_fault+0x2b4/0x6b0
[ 4476.209469] exc_page_fault+0x78/0x280
[ 4476.209616] ? asm_exc_page_fault+0x8/0x30
[ 4476.209764] asm_exc_page_fault+0x1e/0x30
[ 4476.209908] RIP: 0033:0x7fa53526bb71
[ 4476.210050] 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
[ 4476.210506] RSP: 002b:00007ffe36da85a8 EFLAGS: 00010206
[ 4476.210673] RAX: 0000000000000000 RBX: 000055748437a310 RCX: 0000000000011008
[ 4476.210872] RDX: 00005574844025c0 RSI: 0000000000000000 RDI: 0000557484412000
[ 4476.211071] RBP: 000055748437a2c0 R08: 00005574844025c0 R09: 00007fa5352cba60
[ 4476.211268] R10: fffffffffffff000 R11: 0000557484423000 R12: 000000027ff80000
[ 4476.211465] R13: 0000000000040000 R14: 00005574843c2590 R15: 000055748437a310
[ 4476.211670]
[ 4476.435084] BTRFS: device fsid 4c3701cf-ef8a-44d5-9bf3-5d9ac3644e8d devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (860820)
[ 4476.502029] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4476.502246] BTRFS info (device dm-9): using free space tree
[ 4476.502420] BTRFS info (device dm-9): has skinny extents
[ 4476.505046] BTRFS info (device dm-9): checking UUID tree
[ 4482.433449] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (864875)
[ 4482.434547] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (864875)
[ 4482.524385] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4482.524614] BTRFS info (device dm-9): using free space tree
[ 4482.524787] BTRFS info (device dm-9): has skinny extents
[ 4482.527085] BTRFS info (device dm-9): start tree-log replay
[ 4482.966951] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4482.967278] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 864875, name: systemd-udevd
[ 4482.967560] preempt_count: 100, expected: 0
[ 4482.967747] RCU nest depth: 0, expected: 0
[ 4482.967982] INFO: lockdep is turned off.
[ 4482.968192] Preemption disabled at:
[ 4482.968193] [] __do_softirq+0x5e/0x517
[ 4482.968634] CPU: 1 PID: 864875 Comm: systemd-udevd Tainted: G W 5.17.0+ #641
[ 4482.969056] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4482.969447] Call Trace:
[ 4482.969662]
[ 4482.969827] dump_stack_lvl+0x56/0x6f
[ 4482.970036] __might_resched.cold+0xff/0x13a
[ 4482.970265] blk_release_queue+0x24/0x120
[ 4482.970488] kobject_put+0x7c/0x1d0
[ 4482.970688] ? rcu_do_batch+0x1ac/0x570
[ 4482.979344] blkg_free.part.0+0x41/0x60
[ 4482.979558] rcu_do_batch+0x1e1/0x570
[ 4482.979765] ? rcu_do_batch+0x1ac/0x570
[ 4482.979976] rcu_core+0x2cb/0x460
[ 4482.980179] __do_softirq+0x178/0x517
[ 4482.980394] __irq_exit_rcu+0xe3/0x160
[ 4482.980614] irq_exit_rcu+0xa/0x20
[ 4482.980814] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4482.981047]
[ 4482.981220]
[ 4482.981387] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4482.981627] RIP: 0010:do_user_addr_fault+0x2ba/0x6b0
[ 4482.981865] Code: 01 00 00 48 83 c4 18 4c 89 ea 48 89 ee 4c 89 e7 5b 5d 41 5c 41 5d 41 5e 41 5f e9 b1 fb ff ff e8 9c 79 1a 00 fb 0f 1f 44 00 00 54 02 00 00 e9 31 fe ff ff 0f 0b e9 4c ff ff ff 31 f6 4c 89 f7
[ 4482.982535] RSP: 0000:ffffb94a02047ee0 EFLAGS: 00000246
[ 4482.982779] RAX: 0000000080000000 RBX: 0000000000000000 RCX: 0000000000000000
[ 4482.983043] RDX: 0000557484365848 RSI: 0000000000000007 RDI: ffffffffa21048b4
[ 4482.983321] RBP: 0000000000000007 R08: 0000000000000000 R09: 0000000000000000
[ 4482.984214] R10: 0000000000000000 R11: 0000000000000000 R12: ffffb94a02047f58
[ 4482.984437] R13: 0000557484365848 R14: ffff963456760000 R15: 0000000000000000
[ 4482.984665] ? do_user_addr_fault+0x2b4/0x6b0
[ 4482.984862] ? trace_hardirqs_off+0xd/0xc0
[ 4482.985013] exc_page_fault+0x78/0x280
[ 4482.985152] ? asm_exc_page_fault+0x8/0x30
[ 4482.985298] asm_exc_page_fault+0x1e/0x30
[ 4482.985444] RIP: 0033:0x7fa535190aa6
[ 4482.985632] Code: 00 00 0f 85 cf 06 00 00 48 83 c4 48 4c 89 e7 5b 5d 41 5c 41 5d 41 5e 41 5f e9 06 f0 ff ff 66 0f 1f 44 00 00 4c 89 c1 83 c0 01 <49> 89 74 24 18 48 c1 e9 0c 48 33 8c d6 80 00 00 00 49 89 4c 24 10
[ 4482.986137] RSP: 002b:00007ffe36da8810 EFLAGS: 00010202
[ 4482.986306] RAX: 0000000000000007 RBX: 0000000000000020 RCX: 0000557484365840
[ 4482.986523] RDX: 0000000000000000 RSI: 00005574842c3010 RDI: 0000000000000007
[ 4482.986719] RBP: 00007fa5352cba00 R08: 0000557484365840 R09: 0000000000000000
[ 4482.986935] R10: 0000000000000010 R11: 0000000000000246 R12: 0000557484365830
[ 4482.987128] R13: 0000000000000000 R14: 000000010b352131 R15: 0000000000000000
[ 4482.987325]
[ 4483.211056] BTRFS: device fsid 74315b3c-6ad7-4e4d-806e-43a5d2981172 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (864916)
[ 4483.298851] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4483.299083] BTRFS info (device dm-9): using free space tree
[ 4483.299256] BTRFS info (device dm-9): has skinny extents
[ 4483.301710] BTRFS info (device dm-9): checking UUID tree
[ 4488.926408] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (868971)
[ 4488.927451] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (868971)
[ 4489.016559] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4489.016786] BTRFS info (device dm-9): using free space tree
[ 4489.016981] BTRFS info (device dm-9): has skinny extents
[ 4489.019994] BTRFS info (device dm-9): start tree-log replay
[ 4489.417885] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4489.418283] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 4489.418644] preempt_count: 101, expected: 0
[ 4489.418864] RCU nest depth: 0, expected: 0
[ 4489.419088] INFO: lockdep is turned off.
[ 4489.419291] Preemption disabled at:
[ 4489.419292] [] schedule_preempt_disabled+0x1e/0x20
[ 4489.419787] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[ 4489.420155] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4489.420557] Call Trace:
[ 4489.420737]
[ 4489.420904] dump_stack_lvl+0x56/0x6f
[ 4489.421114] __might_resched.cold+0xff/0x13a
[ 4489.421342] blk_release_queue+0x24/0x120
[ 4489.421567] kobject_put+0x7c/0x1d0
[ 4489.421777] ? rcu_do_batch+0x1ac/0x570
[ 4489.421988] blkg_free.part.0+0x41/0x60
[ 4489.422200] rcu_do_batch+0x1e1/0x570
[ 4489.422411] ? rcu_do_batch+0x1ac/0x570
[ 4489.422631] rcu_core+0x2cb/0x460
[ 4489.422831] __do_softirq+0x178/0x517
[ 4489.423035] __irq_exit_rcu+0xe3/0x160
[ 4489.423241] irq_exit_rcu+0xa/0x20
[ 4489.423442] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4489.423668]
[ 4489.423831]
[ 4489.423990] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4489.424228] RIP: 0010:native_safe_halt+0xb/0x10
[ 4489.424422] 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
[ 4489.424893] RSP: 0018:ffffffffa3a03ea8 EFLAGS: 00000246
[ 4489.425062] RAX: ffffffffa2e64a80 RBX: ffffffffa3a30a00 RCX: 0000000000000000
[ 4489.425256] RDX: ffffffffa3a30a00 RSI: ffffffffa387e4d9 RDI: ffffffffa2e64bf6
[ 4489.425456] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 4489.425651] R10: 0000000000000000 R11: ffff9634bbc20c88 R12: 0000000000000000
[ 4489.425847] R13: 0000000000000000 R14: ffffffffa3a30118 R15: 000000000000007b
[ 4489.426054] ? mwait_idle+0x80/0x80
[ 4489.426194] ? default_idle_call+0x26/0xa0
[ 4489.426343] default_idle+0xa/0x10
[ 4489.426483] default_idle_call+0x5e/0xa0
[ 4489.426627] do_idle+0x1e9/0x240
[ 4489.426764] cpu_startup_entry+0x19/0x20
[ 4489.426914] start_kernel+0x98c/0x99e
[ 4489.427056] ? copy_bootdata+0x18/0x55
[ 4489.427199] secondary_startup_64_no_verify+0xd5/0xdb
[ 4489.427363]