[ 2559.709087] run fstests btrfs/056 at 2022-04-01 21:33:10
[ 2559.912259] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 2559.912517] BTRFS info (device dm-0): using free space tree
[ 2559.912743] BTRFS info (device dm-0): has skinny extents
[ 2560.141305] BTRFS: device fsid d5fd0d0f-da2b-4083-bcf9-f649743491c5 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (377914)
[ 2560.200077] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2560.200299] BTRFS info (device dm-9): using free space tree
[ 2560.200473] BTRFS info (device dm-9): has skinny extents
[ 2560.201906] BTRFS info (device dm-9): checking UUID tree
[ 2560.343908] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (374552)
[ 2560.344736] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (374552)
[ 2560.412184] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2560.412403] BTRFS info (device dm-9): using free space tree
[ 2560.412581] BTRFS info (device dm-9): has skinny extents
[ 2560.414391] BTRFS info (device dm-9): start tree-log replay
[ 2560.429076] BTRFS info (device dm-9): checking UUID tree
[ 2560.500965] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2560.501345] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 374552, name: systemd-udevd
[ 2560.501727] preempt_count: 100, expected: 0
[ 2560.501954] RCU nest depth: 0, expected: 0
[ 2560.502162] INFO: lockdep is turned off.
[ 2560.502372] Preemption disabled at:
[ 2560.502373] [] __do_softirq+0x5e/0x517
[ 2560.502829] CPU: 1 PID: 374552 Comm: systemd-udevd Not tainted 5.17.0+ #639
[ 2560.503112] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2560.503513] Call Trace:
[ 2560.503695]
[ 2560.503865] dump_stack_lvl+0x56/0x6f
[ 2560.504069] __might_resched.cold+0xff/0x13a
[ 2560.504284] blk_release_queue+0x24/0x120
[ 2560.505254] kobject_put+0x7c/0x1d0
[ 2560.505483] ? rcu_do_batch+0x1ac/0x570
[ 2560.505707] blkg_free.part.0+0x41/0x60
[ 2560.505928] rcu_do_batch+0x1e1/0x570
[ 2560.506158] ? rcu_do_batch+0x1ac/0x570
[ 2560.506377] rcu_core+0x2cb/0x460
[ 2560.506580] __do_softirq+0x178/0x517
[ 2560.506793] __irq_exit_rcu+0xe3/0x160
[ 2560.506995] irq_exit_rcu+0xa/0x20
[ 2560.507189] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2560.507420]
[ 2560.507664]
[ 2560.507850] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2560.508088] RIP: 0010:anon_vma_interval_tree_insert+0x4a/0xc0
[ 2560.508341] Code: 48 c1 e8 0c 48 8d 74 07 ff 49 8b 02 48 85 c0 74 67 41 b9 01 00 00 00 eb 03 48 89 d0 48 3b 70 18 76 04 48 89 70 18 48 8b 50 e0 <48> 8d 48 10 48 3b ba 98 00 00 00 72 07 48 8d 48 08 45 31 c9 48 8b
[ 2560.509066] RSP: 0018:ffffa59283febc80 EFLAGS: 00000246
[ 2560.509312] RAX: ffff93609667e920 RBX: ffff936084b3ae10 RCX: 0000000000000000
[ 2560.509600] RDX: ffff936096692e10 RSI: 0000000000000074 RDI: 0000000000000073
[ 2560.509904] RBP: ffff936085850578 R08: ffff93608425b900 R09: 0000000000000001
[ 2560.510185] R10: ffff936084b3aec0 R11: ffff93608425b920 R12: ffff93608437bd80
[ 2560.510493] R13: ffff936084b3ae10 R14: ffff93608425b910 R15: ffff93608425b900
[ 2560.510787] anon_vma_clone+0xe3/0x1f0
[ 2560.510993] anon_vma_fork+0x33/0x180
[ 2560.511192] dup_mm+0x461/0x610
[ 2560.511358] copy_process+0x134f/0x1e90
[ 2560.511512] ? wp_page_reuse+0x60/0x70
[ 2560.511661] kernel_clone+0x8b/0x3c0
[ 2560.511802] ? rcu_read_lock_sched_held+0x12/0x60
[ 2560.511965] ? rcu_read_lock_sched_held+0x12/0x60
[ 2560.512152] ? lock_acquire+0x176/0x2d0
[ 2560.512293] ? __seccomp_filter+0x38b/0x5d0
[ 2560.512445] __do_sys_clone+0x52/0x60
[ 2560.512584] do_syscall_64+0x38/0x90
[ 2560.512724] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 2560.512887] RIP: 0033:0x7f79a3748de9
[ 2560.513032] Code: ed 0f 85 1a 01 00 00 64 48 8b 04 25 10 00 00 00 45 31 c0 31 d2 31 f6 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 0f 87 93 00 00 00 41 89 c5 85 c0 0f 85 a0 00 00
[ 2560.513486] RSP: 002b:00007fff1d50c8e0 EFLAGS: 00000246 ORIG_RAX: 0000000000000038
[ 2560.513724] RAX: ffffffffffffffda RBX: 0000000000000045 RCX: 00007f79a3748de9
[ 2560.513915] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011
[ 2560.514109] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 2560.514304] R10: 00007f79a2827750 R11: 0000000000000246 R12: 0000000000000000
[ 2560.514501] R13: 00007fff1d50ca60 R14: 0000000000000040 R15: 00007fff1d50c9e0
[ 2560.514695]
[ 2560.594395] BTRFS: device fsid ea61a12b-e367-41ff-ba6a-03136ae6e71e devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (378051)
[ 2560.660844] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2560.661130] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2560.661495] BTRFS info (device dm-9): force lzo compression, level 0
[ 2560.661677] BTRFS info (device dm-9): using free space tree
[ 2560.661845] BTRFS info (device dm-9): has skinny extents
[ 2560.663986] BTRFS info (device dm-9): checking UUID tree
[ 2560.776978] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (374552)
[ 2560.777724] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (374552)
[ 2560.848999] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2560.849227] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2560.849514] BTRFS info (device dm-9): force lzo compression, level 0
[ 2560.849705] BTRFS info (device dm-9): using free space tree
[ 2560.849878] BTRFS info (device dm-9): has skinny extents
[ 2560.851298] BTRFS info (device dm-9): start tree-log replay
[ 2560.863080] BTRFS info (device dm-9): checking UUID tree
[ 2561.066208] BTRFS: device fsid 82b02d4b-f3c4-4bea-ab66-bcdf45736656 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (378189)
[ 2561.140184] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2561.140513] BTRFS info (device dm-9): using free space tree
[ 2561.140713] BTRFS info (device dm-9): has skinny extents
[ 2561.142261] BTRFS info (device dm-9): checking UUID tree
[ 2561.325213] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (374552)
[ 2561.326140] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (374552)
[ 2561.386586] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2561.386829] BTRFS info (device dm-9): using free space tree
[ 2561.387050] BTRFS info (device dm-9): has skinny extents
[ 2561.388672] BTRFS info (device dm-9): start tree-log replay
[ 2561.402947] BTRFS info (device dm-9): checking UUID tree
[ 2561.578111] BTRFS: device fsid 54ce535a-0f9f-4c66-b02a-cd54fc9e8624 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (378326)
[ 2561.646666] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2561.646908] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2561.647281] BTRFS info (device dm-9): force lzo compression, level 0
[ 2561.647529] BTRFS info (device dm-9): using free space tree
[ 2561.647731] BTRFS info (device dm-9): has skinny extents
[ 2561.649574] BTRFS info (device dm-9): checking UUID tree
[ 2561.758786] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (374552)
[ 2561.759758] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (374552)
[ 2561.837178] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2561.837497] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2561.837862] BTRFS info (device dm-9): force lzo compression, level 0
[ 2561.838167] BTRFS info (device dm-9): using free space tree
[ 2561.838412] BTRFS info (device dm-9): has skinny extents
[ 2561.840275] BTRFS info (device dm-9): start tree-log replay
[ 2561.870579] BTRFS info (device dm-9): checking UUID tree
[ 2561.972948] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2561.973252] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 2561.973521] preempt_count: 101, expected: 0
[ 2561.973679] RCU nest depth: 0, expected: 0
[ 2561.973835] INFO: lockdep is turned off.
[ 2561.973994] Preemption disabled at:
[ 2561.973995] [] schedule_preempt_disabled+0x1e/0x20
[ 2561.974344] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #639
[ 2561.974598] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2561.974877] Call Trace:
[ 2561.975002]
[ 2561.975122] dump_stack_lvl+0x56/0x6f
[ 2561.975272] __might_resched.cold+0xff/0x13a
[ 2561.975434] blk_release_queue+0x24/0x120
[ 2561.976604] kobject_put+0x7c/0x1d0
[ 2561.976777] ? rcu_do_batch+0x1ac/0x570
[ 2561.976923] blkg_free.part.0+0x41/0x60
[ 2561.977077] rcu_do_batch+0x1e1/0x570
[ 2561.977232] ? rcu_do_batch+0x1ac/0x570
[ 2561.977408] rcu_core+0x2cb/0x460
[ 2561.977594] __do_softirq+0x178/0x517
[ 2561.977758] __irq_exit_rcu+0xe3/0x160
[ 2561.977904] irq_exit_rcu+0xa/0x20
[ 2561.978039] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2561.978202]
[ 2561.978320]
[ 2561.978439] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2561.978606] RIP: 0010:native_safe_halt+0xb/0x10
[ 2561.978765] 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
[ 2561.979249] RSP: 0018:ffffffff93a03ea8 EFLAGS: 00000246
[ 2561.979421] RAX: ffffffff92e64a80 RBX: ffffffff93a30a00 RCX: 0000000000000000
[ 2561.979620] RDX: ffffffff93a30a00 RSI: ffffffff9387e4d9 RDI: ffffffff92e64bf6
[ 2561.979815] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 2561.980016] R10: 0000000000000000 R11: ffff9360fbc20c88 R12: 0000000000000000
[ 2561.980227] R13: 0000000000000000 R14: ffffffff93a30118 R15: 000000000000007b
[ 2561.980430] ? mwait_idle+0x80/0x80
[ 2561.980568] ? default_idle_call+0x26/0xa0
[ 2561.980714] default_idle+0xa/0x10
[ 2561.980851] default_idle_call+0x5e/0xa0
[ 2561.980996] do_idle+0x1e9/0x240
[ 2561.981133] cpu_startup_entry+0x19/0x20
[ 2561.981275] start_kernel+0x98c/0x99e
[ 2561.981425] ? copy_bootdata+0x18/0x55
[ 2561.981572] secondary_startup_64_no_verify+0xd5/0xdb
[ 2561.981748]