[ 4647.743579] run fstests btrfs/201 at 2022-04-01 22:07:58
[ 4648.231525] BTRFS: device fsid 7afa1833-1f19-44bf-8adf-7ceed1c17ba7 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (868531)
[ 4648.289443] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4648.289661] BTRFS info (device dm-9): using free space tree
[ 4648.289831] BTRFS info (device dm-9): has skinny extents
[ 4648.292047] BTRFS info (device dm-9): checking UUID tree
[ 4648.517626] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (867749)
[ 4648.518452] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (867749)
[ 4648.568757] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4648.568981] BTRFS info (device dm-9): using free space tree
[ 4648.569148] BTRFS info (device dm-9): has skinny extents
[ 4648.571056] BTRFS info (device dm-9): start tree-log replay
[ 4648.667316] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4648.667691] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 4648.668057] preempt_count: 101, expected: 0
[ 4648.668321] RCU nest depth: 0, expected: 0
[ 4648.668531] INFO: lockdep is turned off.
[ 4648.668727] Preemption disabled at:
[ 4648.668728] [] ___slab_alloc+0x961/0xf50
[ 4648.669197] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #639
[ 4648.669527] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4648.669893] Call Trace:
[ 4648.670072]
[ 4648.670229] dump_stack_lvl+0x56/0x6f
[ 4648.670436] __might_resched.cold+0xff/0x13a
[ 4648.670645] blk_release_queue+0x24/0x120
[ 4648.670850] kobject_put+0x7c/0x1d0
[ 4648.671069] ? rcu_do_batch+0x1ac/0x570
[ 4648.671265] blkg_free.part.0+0x41/0x60
[ 4648.671464] rcu_do_batch+0x1e1/0x570
[ 4648.671665] ? rcu_do_batch+0x1ac/0x570
[ 4648.671889] rcu_core+0x2cb/0x460
[ 4648.672103] __do_softirq+0x178/0x517
[ 4648.672299] __irq_exit_rcu+0xe3/0x160
[ 4648.672498] irq_exit_rcu+0xa/0x20
[ 4648.672683] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4648.672908]
[ 4648.673067]
[ 4648.673226] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4648.673462] RIP: 0010:native_safe_halt+0xb/0x10
[ 4648.673678] Code: 6d 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
[ 4648.674340] RSP: 0018:ffffa59280093ef8 EFLAGS: 00000246
[ 4648.674567] RAX: ffffffff92e64a80 RBX: ffff936080340000 RCX: 0000000000000000
[ 4648.674882] RDX: ffff936080340000 RSI: ffffffff9387e4d9 RDI: ffffffff92e64bf6
[ 4648.675227] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 4648.675498] R10: 0000000000000000 R11: ffff9360fbd20c88 R12: 0000000000000000
[ 4648.675805] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 4648.676128] ? mwait_idle+0x80/0x80
[ 4648.676322] ? default_idle_call+0x26/0xa0
[ 4648.676530] default_idle+0xa/0x10
[ 4648.676733] default_idle_call+0x5e/0xa0
[ 4648.676916] do_idle+0x1e9/0x240
[ 4648.677079] cpu_startup_entry+0x19/0x20
[ 4648.677255] secondary_startup_64_no_verify+0xd5/0xdb
[ 4648.677456]
[ 4648.825060] BTRFS: device fsid 0a42dccf-6218-473b-8109-671950f74bf2 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (868645)
[ 4648.905321] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4648.905541] BTRFS info (device dm-9): using free space tree
[ 4648.905707] BTRFS info (device dm-9): has skinny extents
[ 4648.907793] BTRFS info (device dm-9): checking UUID tree
[ 4654.399308] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (872700)
[ 4654.400554] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (872700)
[ 4654.490391] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4654.490610] BTRFS info (device dm-9): using free space tree
[ 4654.490782] BTRFS info (device dm-9): has skinny extents
[ 4654.492998] BTRFS info (device dm-9): start tree-log replay
[ 4654.895255] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4654.895735] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[ 4654.896005] preempt_count: 101, expected: 0
[ 4654.896158] RCU nest depth: 0, expected: 0
[ 4654.896328] INFO: lockdep is turned off.
[ 4654.896482] Preemption disabled at:
[ 4654.896483] [] ___slab_alloc+0x961/0xf50
[ 4654.896805] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #639
[ 4654.897161] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4654.897425] Call Trace:
[ 4654.897552]
[ 4654.897671] dump_stack_lvl+0x56/0x6f
[ 4654.897823] __might_resched.cold+0xff/0x13a
[ 4654.897985] blk_release_queue+0x24/0x120
[ 4654.898138] kobject_put+0x7c/0x1d0
[ 4654.898282] ? rcu_do_batch+0x1ac/0x570
[ 4654.898432] blkg_free.part.0+0x41/0x60
[ 4654.898582] rcu_do_batch+0x1e1/0x570
[ 4654.898726] ? rcu_do_batch+0x1ac/0x570
[ 4654.898876] rcu_core+0x2cb/0x460
[ 4654.899018] __do_softirq+0x178/0x517
[ 4654.899165] __irq_exit_rcu+0xe3/0x160
[ 4654.899311] irq_exit_rcu+0xa/0x20
[ 4654.899455] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4654.899621]
[ 4654.899737]
[ 4654.899860] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4654.900035] RIP: 0010:native_safe_halt+0xb/0x10
[ 4654.900195] Code: 6d 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
[ 4654.900667] RSP: 0018:ffffa59280093ef8 EFLAGS: 00000246
[ 4654.900858] RAX: ffffffff92e64a80 RBX: ffff936080340000 RCX: 0000000000000000
[ 4654.901065] RDX: ffff936080340000 RSI: ffffffff9387e4d9 RDI: ffffffff92e64bf6
[ 4654.901277] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 4654.901557] R10: 0000000000000000 R11: ffff9360fbd20c88 R12: 0000000000000000
[ 4654.901808] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 4654.902012] ? mwait_idle+0x80/0x80
[ 4654.902154] ? default_idle_call+0x26/0xa0
[ 4654.902311] default_idle+0xa/0x10
[ 4654.902453] default_idle_call+0x5e/0xa0
[ 4654.902603] do_idle+0x1e9/0x240
[ 4654.902739] cpu_startup_entry+0x19/0x20
[ 4654.902895] secondary_startup_64_no_verify+0xd5/0xdb
[ 4654.903070]
[ 4655.032194] BTRFS: device fsid 15788812-0a77-45c3-be68-c27d98663599 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (872742)
[ 4655.093938] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4655.094156] BTRFS info (device dm-9): using free space tree
[ 4655.094358] BTRFS info (device dm-9): has skinny extents
[ 4655.096219] BTRFS info (device dm-9): checking UUID tree
[ 4660.677209] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (876796)
[ 4660.678031] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (876796)
[ 4660.775538] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4660.781399] BTRFS info (device dm-9): using free space tree
[ 4660.781574] BTRFS info (device dm-9): has skinny extents
[ 4660.783761] BTRFS info (device dm-9): start tree-log replay
[ 4661.195174] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4661.195460] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 4661.195735] preempt_count: 101, expected: 0
[ 4661.195891] RCU nest depth: 0, expected: 0
[ 4661.196032] INFO: lockdep is turned off.
[ 4661.196311] Preemption disabled at:
[ 4661.196312] [] schedule_preempt_disabled+0x1e/0x20
[ 4661.196640] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #639
[ 4661.196889] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4661.197140] Call Trace:
[ 4661.197260]
[ 4661.197374] dump_stack_lvl+0x56/0x6f
[ 4661.197513] __might_resched.cold+0xff/0x13a
[ 4661.197665] blk_release_queue+0x24/0x120
[ 4661.197817] kobject_put+0x7c/0x1d0
[ 4661.197953] ? rcu_do_batch+0x1ac/0x570
[ 4661.198094] blkg_free.part.0+0x41/0x60
[ 4661.198236] rcu_do_batch+0x1e1/0x570
[ 4661.198406] ? rcu_do_batch+0x1ac/0x570
[ 4661.198548] rcu_core+0x2cb/0x460
[ 4661.198686] __do_softirq+0x178/0x517
[ 4661.198842] __irq_exit_rcu+0xe3/0x160
[ 4661.198999] irq_exit_rcu+0xa/0x20
[ 4661.199151] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4661.199312]
[ 4661.199460]
[ 4661.199573] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4661.199776] RIP: 0010:native_safe_halt+0xb/0x10
[ 4661.199929] Code: 6d 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
[ 4661.200410] RSP: 0018:ffffffff93a03ea8 EFLAGS: 00000246
[ 4661.200588] RAX: ffffffff92e64a80 RBX: ffffffff93a30a00 RCX: 0000000000000000
[ 4661.200793] RDX: ffffffff93a30a00 RSI: ffffffff9387e4d9 RDI: ffffffff92e64bf6
[ 4661.200999] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 4661.201193] R10: 0000000000000000 R11: ffff9360fbc20c88 R12: 0000000000000000
[ 4661.201411] R13: 0000000000000000 R14: ffffffff93a30118 R15: 000000000000007b
[ 4661.201602] ? mwait_idle+0x80/0x80
[ 4661.201737] ? default_idle_call+0x26/0xa0
[ 4661.201894] default_idle+0xa/0x10
[ 4661.202031] default_idle_call+0x5e/0xa0
[ 4661.202174] do_idle+0x1e9/0x240
[ 4661.202305] cpu_startup_entry+0x19/0x20
[ 4661.202449] start_kernel+0x98c/0x99e
[ 4661.202590] ? copy_bootdata+0x18/0x55
[ 4661.202730] secondary_startup_64_no_verify+0xd5/0xdb
[ 4661.202897]
[ 4661.332556] BTRFS: device fsid 4bbdb17b-aabe-43fd-8df0-3a7b4cb1be80 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (876837)
[ 4661.396431] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4661.396646] BTRFS info (device dm-9): using free space tree
[ 4661.396821] BTRFS info (device dm-9): has skinny extents
[ 4661.399323] BTRFS info (device dm-9): checking UUID tree
[ 4666.872410] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (880892)
[ 4666.873400] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (880892)
[ 4666.962540] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4666.962764] BTRFS info (device dm-9): using free space tree
[ 4666.962935] BTRFS info (device dm-9): has skinny extents
[ 4666.964833] BTRFS info (device dm-9): start tree-log replay
[ 4667.363117] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4667.363501] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 880934, name: udevadm
[ 4667.363875] preempt_count: 100, expected: 0
[ 4667.364085] RCU nest depth: 0, expected: 0
[ 4667.364312] INFO: lockdep is turned off.
[ 4667.364506] Preemption disabled at:
[ 4667.364507] [] __do_softirq+0x5e/0x517
[ 4667.364942] CPU: 0 PID: 880934 Comm: udevadm Tainted: G W 5.17.0+ #639
[ 4667.365311] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4667.365678] Call Trace:
[ 4667.365859]
[ 4667.366028] dump_stack_lvl+0x56/0x6f
[ 4667.366235] __might_resched.cold+0xff/0x13a
[ 4667.366455] blk_release_queue+0x24/0x120
[ 4667.366671] kobject_put+0x7c/0x1d0
[ 4667.366877] ? rcu_do_batch+0x1ac/0x570
[ 4667.367082] blkg_free.part.0+0x41/0x60
[ 4667.367292] rcu_do_batch+0x1e1/0x570
[ 4667.367487] ? rcu_do_batch+0x1ac/0x570
[ 4667.367643] rcu_core+0x2cb/0x460
[ 4667.367846] __do_softirq+0x178/0x517
[ 4667.368057] __irq_exit_rcu+0xe3/0x160
[ 4667.368261] irq_exit_rcu+0xa/0x20
[ 4667.368461] sysvec_apic_timer_interrupt+0x54/0xd0
[ 4667.368698] ? asm_sysvec_apic_timer_interrupt+0xa/0x20
[ 4667.368936] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4667.369178] RIP: 0033:0x7fb04b05fd27
[ 4667.369380] Code: 48 d3 ee 89 f9 48 d3 e8 48 21 c6 83 e6 01 0f 85 ff 01 00 00 48 83 c5 01 49 39 ec 0f 86 ca 02 00 00 49 8b 44 ed 00 48 8b 58 28 <4c> 39 fb 74 e5 44 89 c0 83 e0 02 89 44 24 08 74 09 f6 83 1c 03 00
[ 4667.370058] RSP: 002b:00007ffeda80e370 EFLAGS: 00000206
[ 4667.370295] RAX: 00007fb04b053000 RBX: 00007fb04b053000 RCX: 00000000a7ec1274
[ 4667.370580] RDX: 0000000000000043 RSI: 0000000000000000 RDI: 00000000a7ec1274
[ 4667.370864] RBP: 0000000000000001 R08: 0000000000000001 R09: 00007fb04b0884a0
[ 4667.371142] R10: 00007fb04a067bd8 R11: 00005634658f7660 R12: 000000000000002e
[ 4667.371421] R13: 00007fb049dd1868 R14: 00007ffeda80e4a0 R15: 0000000000000000
[ 4667.371646]