[14485.075324] run fstests generic/479 at 2022-04-04 00:52:18
[14485.617166] BTRFS: device fsid 39136545-21bc-42be-9bef-7e9f7c753980 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2479429)
[14485.712394] BTRFS info (device dm-11): flagging fs with big metadata feature
[14485.712837] BTRFS info (device dm-11): disk space caching is enabled
[14485.713226] BTRFS info (device dm-11): has skinny extents
[14485.715442] BTRFS info (device dm-11): checking UUID tree
[14485.935762] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2479233)
[14485.936928] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2479233)
[14485.985311] BTRFS info (device dm-11): flagging fs with big metadata feature
[14485.985761] BTRFS info (device dm-11): disk space caching is enabled
[14485.986173] BTRFS info (device dm-11): has skinny extents
[14485.987784] BTRFS info (device dm-11): start tree-log replay
[14486.063222] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14486.063805] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2479519, name: mkfs.btrfs
[14486.064350] preempt_count: 102, expected: 0
[14486.064757] RCU nest depth: 0, expected: 0
[14486.065042] INFO: lockdep is turned off.
[14486.065415] Preemption disabled at:
[14486.065416] [] folio_add_lru+0x40/0x220
[14486.066051] CPU: 0 PID: 2479519 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[14486.066558] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14486.067061] Call Trace:
[14486.067217]
[14486.067348] dump_stack_lvl+0x56/0x6f
[14486.067573] __might_resched.cold+0xff/0x13a
[14486.067831] blk_release_queue+0x24/0x120
[14486.068072] kobject_put+0x7c/0x1d0
[14486.068285] ? rcu_do_batch+0x1ac/0x570
[14486.068514] blkg_free.part.0+0x41/0x60
[14486.068747] rcu_do_batch+0x1e1/0x570
[14486.068967] ? rcu_do_batch+0x1ac/0x570
[14486.069197] rcu_core+0x2cb/0x460
[14486.069397] __do_softirq+0x178/0x517
[14486.069637] __irq_exit_rcu+0xe3/0x160
[14486.069863] irq_exit_rcu+0xa/0x20
[14486.070068] sysvec_apic_timer_interrupt+0xa2/0xd0
[14486.070353]
[14486.070482]
[14486.070610] asm_sysvec_apic_timer_interrupt+0x12/0x20
[14486.070917] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[14486.071246] 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 56 85 c0 74 03 5b
[14486.072325] RSP: 0018:ffffa28443013b98 EFLAGS: 00000246
[14486.072637] RAX: 0000000080000002 RBX: 0000000000000246 RCX: 000000000000023f
[14486.073056] RDX: ffff94fc9db8c820 RSI: ffffffffa936c46f RDI: ffffffffa9e651fd
[14486.073471] RBP: ffff94fc9db8c850 R08: 0000000000000000 R09: 0000000000000000
[14486.073890] R10: 0000000000000000 R11: 0000000000000000 R12: 000000000000000f
[14486.074305] R13: ffffc95d04517800 R14: ffffc95d05537fc8 R15: ffff94fc9db8c820
[14486.074726] ? __pagevec_lru_add+0x28f/0x5d0
[14486.074979] ? _raw_spin_unlock_irqrestore+0x2d/0x60
[14486.075273] ? _raw_spin_unlock_irqrestore+0x2d/0x60
[14486.075569] __pagevec_lru_add+0x28f/0x5d0
[14486.075817] folio_add_lru+0xd5/0x220
[14486.076036] filemap_add_folio+0x54/0x80
[14486.076272] __filemap_get_folio+0x1ac/0x4d0
[14486.076529] ? blkdev_llseek+0x60/0x60
[14486.076755] pagecache_get_page+0x15/0x90
[14486.076995] block_write_begin+0x24/0x130
[14486.077239] generic_perform_write+0xa9/0x1d0
[14486.077502] __generic_file_write_iter+0xdb/0x1a0
[14486.077785] blkdev_write_iter+0xc1/0x150
[14486.078029] new_sync_write+0xf6/0x160
[14486.078254] vfs_write+0x290/0x390
[14486.078460] __x64_sys_pwrite64+0x6f/0xb0
[14486.078702] do_syscall_64+0x38/0x90
[14486.078918] entry_SYSCALL_64_after_hwframe+0x44/0xae
[14486.079216] RIP: 0033:0x7fbc97ca719a
[14486.079435] 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
[14486.080517] RSP: 002b:00007ffe1d0852c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[14486.080962] RAX: ffffffffffffffda RBX: 00007fbc978a4010 RCX: 00007fbc97ca719a
[14486.081379] RDX: 0000000000200000 RSI: 00007fbc978a4010 RDI: 0000000000000003
[14486.081796] RBP: 0000000000200000 R08: 00007fbc978a4010 R09: 0000000000000000
[14486.082212] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[14486.082629] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[14486.083049]
[14486.268607] BTRFS: device fsid 73015d5e-a8fc-40bd-8c21-03cdeaae1750 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2479519)
[14486.353179] BTRFS info (device dm-11): flagging fs with big metadata feature
[14486.353631] BTRFS info (device dm-11): disk space caching is enabled
[14486.354009] BTRFS info (device dm-11): has skinny extents
[14486.356046] BTRFS info (device dm-11): checking UUID tree
[14486.506102] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2479233)
[14486.507358] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2479233)
[14486.556442] BTRFS info (device dm-11): flagging fs with big metadata feature
[14486.556888] BTRFS info (device dm-11): disk space caching is enabled
[14486.557282] BTRFS info (device dm-11): has skinny extents
[14486.559112] BTRFS info (device dm-11): start tree-log replay
[14487.050023] BTRFS: device fsid 95ea5c53-b42a-492c-b813-7fa68312ff69 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2479609)
[14487.154047] BTRFS info (device dm-11): flagging fs with big metadata feature
[14487.154503] BTRFS info (device dm-11): disk space caching is enabled
[14487.154891] BTRFS info (device dm-11): has skinny extents
[14487.156631] BTRFS info (device dm-11): checking UUID tree
[14487.364648] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2479233)
[14487.365762] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2479233)
[14487.414178] BTRFS info (device dm-11): flagging fs with big metadata feature
[14487.414655] BTRFS info (device dm-11): disk space caching is enabled
[14487.415047] BTRFS info (device dm-11): has skinny extents
[14487.416503] BTRFS info (device dm-11): start tree-log replay
[14487.489213] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14487.489738] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[14487.490233] preempt_count: 101, expected: 0
[14487.490582] RCU nest depth: 0, expected: 0
[14487.490935] INFO: lockdep is turned off.
[14487.491274] Preemption disabled at:
[14487.491275] [] ___slab_alloc+0x961/0xf50
[14487.491929] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #641
[14487.492385] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14487.492903] Call Trace:
[14487.493056]
[14487.493182] dump_stack_lvl+0x56/0x6f
[14487.493405] __might_resched.cold+0xff/0x13a
[14487.493667] blk_release_queue+0x24/0x120
[14487.493908] kobject_put+0x7c/0x1d0
[14487.494119] ? rcu_do_batch+0x1ac/0x570
[14487.494349] blkg_free.part.0+0x41/0x60
[14487.494581] rcu_do_batch+0x1e1/0x570
[14487.494825] ? rcu_do_batch+0x1ac/0x570
[14487.495143] rcu_core+0x2cb/0x460
[14487.495424] __do_softirq+0x178/0x517
[14487.495740] __irq_exit_rcu+0xe3/0x160
[14487.496041] irq_exit_rcu+0xa/0x20
[14487.496323] sysvec_apic_timer_interrupt+0xa2/0xd0
[14487.496716]
[14487.496894]
[14487.497072] asm_sysvec_apic_timer_interrupt+0x12/0x20
[14487.497490] RIP: 0010:native_safe_halt+0xb/0x10
[14487.497863] Code: 56 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
[14487.499223] RSP: 0018:ffffa28440093ef8 EFLAGS: 00000246
[14487.499622] RAX: ffffffffa9e64a80 RBX: ffff94fc40340000 RCX: 0000000000000000
[14487.500167] RDX: ffff94fc40340000 RSI: ffffffffaa87e4d9 RDI: ffffffffa9e64bf6
[14487.500588] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[14487.501011] R10: 0000000000000000 R11: ffff94fcbbd20c88 R12: 0000000000000000
[14487.501460] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[14487.501886] ? mwait_idle+0x80/0x80
[14487.502114] ? default_idle_call+0x26/0xa0
[14487.502385] default_idle+0xa/0x10
[14487.502592] default_idle_call+0x5e/0xa0
[14487.502870] do_idle+0x1e9/0x240
[14487.503108] cpu_startup_entry+0x19/0x20
[14487.503378] secondary_startup_64_no_verify+0xd5/0xdb
[14487.503699]