[29267.875877] run fstests generic/479 at 2022-04-04 05:04:16
[29268.422479] BTRFS: device fsid d3ab596b-5194-4ff5-985a-781c9eb81625 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (1441838)
[29268.522779] BTRFS info (device dm-9): flagging fs with big metadata feature
[29268.523045] BTRFS info (device dm-9): use zlib compression, level 3
[29268.523240] BTRFS info (device dm-9): using free space tree
[29268.523416] BTRFS info (device dm-9): has skinny extents
[29268.525197] BTRFS info (device dm-9): checking UUID tree
[29268.699173] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (1441049)
[29268.699977] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1441049)
[29268.755018] BTRFS info (device dm-9): flagging fs with big metadata feature
[29268.755327] BTRFS info (device dm-9): use zlib compression, level 3
[29268.755542] BTRFS info (device dm-9): using free space tree
[29268.755711] BTRFS info (device dm-9): has skinny extents
[29268.757694] BTRFS info (device dm-9): start tree-log replay
[29268.829014] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[29268.829294] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1441928, name: mkfs.btrfs
[29268.830338] preempt_count: 101, expected: 0
[29268.830553] RCU nest depth: 1, expected: 0
[29268.830767] INFO: lockdep is turned off.
[29268.830970] Preemption disabled at:
[29268.830971] [<0000000000000000>] 0x0
[29268.831420] CPU: 1 PID: 1441928 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[29268.831874] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[29268.832172] Call Trace:
[29268.832334]
[29268.832508] dump_stack_lvl+0x56/0x6f
[29268.832776] __might_resched.cold+0xff/0x13a
[29268.833065] blk_release_queue+0x24/0x120
[29268.833265] kobject_put+0x7c/0x1d0
[29268.833469] ? rcu_do_batch+0x1ac/0x570
[29268.833686] blkg_free.part.0+0x41/0x60
[29268.833869] rcu_do_batch+0x1e1/0x570
[29268.834017] ? rcu_do_batch+0x1ac/0x570
[29268.834173] rcu_core+0x2cb/0x460
[29268.834324] __do_softirq+0x178/0x517
[29268.834572] __irq_exit_rcu+0xe3/0x160
[29268.834737] irq_exit_rcu+0xa/0x20
[29268.834890] sysvec_apic_timer_interrupt+0xa2/0xd0
[29268.835111]
[29268.835226]
[29268.835342] asm_sysvec_apic_timer_interrupt+0x12/0x20
[29268.835504] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[29268.835725] 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
[29268.841779] RSP: 0018:ffffb94a03417c80 EFLAGS: 00000246
[29268.841948] RAX: 0000000080000001 RBX: 0000000000000286 RCX: 0000000000000000
[29268.842149] RDX: 0000000000000002 RSI: ffffffffa249e718 RDI: ffffffffa2e651fd
[29268.842346] RBP: ffff96344052ca90 R08: 0000000000000000 R09: 0000000000000000
[29268.842549] R10: ffff963455fa7540 R11: 0000000000000000 R12: 0000000000000000
[29268.842745] R13: 0000000000001000 R14: 0000000000000000 R15: ffff963455fa7540
[29268.842947] ? mark_buffer_dirty+0x178/0x1d0
[29268.843101] ? _raw_spin_unlock_irqrestore+0x2d/0x60
[29268.843265] mark_buffer_dirty+0x178/0x1d0
[29268.843411] __block_commit_write.constprop.0.isra.0+0x51/0xb0
[29268.843619] block_write_end+0x30/0xb0
[29268.843809] ? blkdev_llseek+0x60/0x60
[29268.843960] blkdev_write_end+0x14/0xb0
[29268.844108] generic_perform_write+0xf7/0x1d0
[29268.844271] __generic_file_write_iter+0xdb/0x1a0
[29268.844464] blkdev_write_iter+0xc1/0x150
[29268.844630] new_sync_write+0xf6/0x160
[29268.844779] vfs_write+0x290/0x390
[29268.844924] __x64_sys_pwrite64+0x6f/0xb0
[29268.845103] do_syscall_64+0x38/0x90
[29268.845249] entry_SYSCALL_64_after_hwframe+0x44/0xae
[29268.845423] RIP: 0033:0x7f74a476525a
[29268.845580] 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
[29268.846080] RSP: 002b:00007ffdc4495728 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[29268.846345] RAX: ffffffffffffffda RBX: 00007f74a4362010 RCX: 00007f74a476525a
[29268.846575] RDX: 0000000000200000 RSI: 00007f74a4362010 RDI: 0000000000000003
[29268.846781] RBP: 0000000000200000 R08: 00007f74a4362010 R09: 0000000000000000
[29268.847010] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[29268.847231] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[29268.847441]
[29268.977072] BTRFS: device fsid eddb1798-f376-435c-b589-89a9efc9749e devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (1441928)
[29269.058275] BTRFS info (device dm-9): flagging fs with big metadata feature
[29269.058507] BTRFS info (device dm-9): use zlib compression, level 3
[29269.058700] BTRFS info (device dm-9): using free space tree
[29269.058869] BTRFS info (device dm-9): has skinny extents
[29269.078243] BTRFS info (device dm-9): checking UUID tree
[29269.272377] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (1441049)
[29269.273403] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1441049)
[29269.322977] BTRFS info (device dm-9): flagging fs with big metadata feature
[29269.323213] BTRFS info (device dm-9): use zlib compression, level 3
[29269.323400] BTRFS info (device dm-9): using free space tree
[29269.323605] BTRFS info (device dm-9): has skinny extents
[29269.325666] BTRFS info (device dm-9): start tree-log replay
[29269.488143] BTRFS: device fsid be3b9018-f29b-444d-8540-32b085766eb2 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (1442018)
[29269.586562] BTRFS info (device dm-9): flagging fs with big metadata feature
[29269.586779] BTRFS info (device dm-9): use zlib compression, level 3
[29269.586967] BTRFS info (device dm-9): using free space tree
[29269.587165] BTRFS info (device dm-9): has skinny extents
[29269.588858] BTRFS info (device dm-9): checking UUID tree
[29269.702694] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (1441049)
[29269.703472] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1441049)
[29269.752189] BTRFS info (device dm-9): flagging fs with big metadata feature
[29269.752411] BTRFS info (device dm-9): use zlib compression, level 3
[29269.752617] BTRFS info (device dm-9): using free space tree
[29269.752790] BTRFS info (device dm-9): has skinny extents
[29269.754689] BTRFS info (device dm-9): start tree-log replay
[29269.833008] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[29269.833280] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[29269.833534] preempt_count: 101, expected: 0
[29269.833682] RCU nest depth: 0, expected: 0
[29269.833828] INFO: lockdep is turned off.
[29269.833968] Preemption disabled at:
[29269.833969] [] schedule_preempt_disabled+0x1e/0x20
[29269.834306] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #641
[29269.834557] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[29269.834815] Call Trace:
[29269.834941]
[29269.835055] dump_stack_lvl+0x56/0x6f
[29269.835198] __might_resched.cold+0xff/0x13a
[29269.835349] blk_release_queue+0x24/0x120
[29269.835493] kobject_put+0x7c/0x1d0
[29269.835639] ? rcu_do_batch+0x1ac/0x570
[29269.835785] blkg_free.part.0+0x41/0x60
[29269.835930] rcu_do_batch+0x1e1/0x570
[29269.836067] ? rcu_do_batch+0x1ac/0x570
[29269.836212] rcu_core+0x2cb/0x460
[29269.836348] __do_softirq+0x178/0x517
[29269.836488] __irq_exit_rcu+0xe3/0x160
[29269.836632] irq_exit_rcu+0xa/0x20
[29269.836769] sysvec_apic_timer_interrupt+0xa2/0xd0
[29269.836926]
[29269.837037]
[29269.837150] asm_sysvec_apic_timer_interrupt+0x12/0x20
[29269.837314] RIP: 0010:native_safe_halt+0xb/0x10
[29269.837468] 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
[29269.837939] RSP: 0018:ffffffffa3a03ea8 EFLAGS: 00000246
[29269.838112] RAX: ffffffffa2e64a80 RBX: ffffffffa3a30a00 RCX: 0000000000000000
[29269.838306] RDX: ffffffffa3a30a00 RSI: ffffffffa387e4d9 RDI: ffffffffa2e64bf6
[29269.838498] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[29269.838702] R10: 0000000000000000 R11: ffff9634bbc20c88 R12: 0000000000000000
[29269.838894] R13: 0000000000000000 R14: ffffffffa3a30118 R15: 000000000000007b
[29269.839090] ? mwait_idle+0x80/0x80
[29269.839226] ? default_idle_call+0x26/0xa0
[29269.839378] default_idle+0xa/0x10
[29269.839522] default_idle_call+0x5e/0xa0
[29269.839669] do_idle+0x1e9/0x240
[29269.839804] cpu_startup_entry+0x19/0x20
[29269.839950] start_kernel+0x98c/0x99e
[29269.840089] ? copy_bootdata+0x18/0x55
[29269.840231] secondary_startup_64_no_verify+0xd5/0xdb
[29269.840398]