[ 2161.287402] run fstests btrfs/056 at 2022-04-03 21:32:29
[ 2161.466523] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 2161.466764] BTRFS info (device dm-0): using free space tree
[ 2161.466941] BTRFS info (device dm-0): has skinny extents
[ 2161.638745] BTRFS: device fsid 7a0f8a43-0107-475d-8ba5-150d54414862 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (367865)
[ 2161.704161] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2161.704399] BTRFS info (device dm-9): using free space tree
[ 2161.704662] BTRFS info (device dm-9): has skinny extents
[ 2161.706568] BTRFS info (device dm-9): checking UUID tree
[ 2161.801080] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (364498)
[ 2161.802235] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (364498)
[ 2161.861773] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2161.861999] BTRFS info (device dm-9): using free space tree
[ 2161.862170] BTRFS info (device dm-9): has skinny extents
[ 2161.863905] BTRFS info (device dm-9): start tree-log replay
[ 2161.887104] BTRFS info (device dm-9): checking UUID tree
[ 2162.003548] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2162.009681] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 368002, name: mkfs.btrfs
[ 2162.009960] preempt_count: 101, expected: 0
[ 2162.010118] RCU nest depth: 0, expected: 0
[ 2162.010297] INFO: lockdep is turned off.
[ 2162.010454] Preemption disabled at:
[ 2162.010455] [] copy_page_from_iter_atomic+0x24/0x8d0
[ 2162.010823] CPU: 0 PID: 368002 Comm: mkfs.btrfs Not tainted 5.17.0+ #641
[ 2162.011058] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2162.011328] Call Trace:
[ 2162.011452]
[ 2162.011590] dump_stack_lvl+0x56/0x6f
[ 2162.011737] __might_resched.cold+0xff/0x13a
[ 2162.011896] blk_release_queue+0x24/0x120
[ 2162.012079] kobject_put+0x7c/0x1d0
[ 2162.012224] ? rcu_do_batch+0x1ac/0x570
[ 2162.012374] blkg_free.part.0+0x41/0x60
[ 2162.012524] rcu_do_batch+0x1e1/0x570
[ 2162.012669] ? rcu_do_batch+0x1ac/0x570
[ 2162.012817] rcu_core+0x2cb/0x460
[ 2162.012963] __do_softirq+0x178/0x517
[ 2162.013116] __irq_exit_rcu+0xe3/0x160
[ 2162.013263] irq_exit_rcu+0xa/0x20
[ 2162.013406] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2162.013568]
[ 2162.013688]
[ 2162.013805] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2162.013979] RIP: 0010:copy_user_enhanced_fast_string+0xe/0x40
[ 2162.014161] Code: 89 d1 c1 e9 03 83 e2 07 f3 48 a5 89 d1 f3 a4 31 c0 0f 01 ca c3 8d 0c ca 89 ca eb 20 0f 01 cb 83 fa 40 0f 82 60 ff ff ff 89 d1 a4 31 c0 0f 01 ca c3 89 ca eb 06 66 0f 1f 44 00 00 83 f8 12 74
[ 2162.014643] RSP: 0018:ffffb94a01427cb0 EFLAGS: 00050206
[ 2162.014804] RAX: 00007fffffffe000 RBX: 0000000000001000 RCX: 0000000000000d40
[ 2162.015001] RDX: 0000000000001000 RSI: 00007f70e85bc2d0 RDI: ffff9634515512c0
[ 2162.015195] RBP: 0000000000000000 R08: 0000000000000001 R09: 0000000000001000
[ 2162.015389] R10: ffff96344f853498 R11: 0000000000000000 R12: 0000000000000000
[ 2162.015586] R13: ffffb94a01427e60 R14: 0000000000111000 R15: ffffb94a01427e60
[ 2162.015784] copyin+0x3d/0x50
[ 2162.015915] copy_page_from_iter_atomic+0xf9/0x8d0
[ 2162.016076] ? blkdev_llseek+0x60/0x60
[ 2162.016215] ? block_write_begin+0x50/0x130
[ 2162.016366] generic_perform_write+0xd4/0x1d0
[ 2162.016521] __generic_file_write_iter+0xdb/0x1a0
[ 2162.016676] blkdev_write_iter+0xc1/0x150
[ 2162.016821] new_sync_write+0xf6/0x160
[ 2162.016968] vfs_write+0x290/0x390
[ 2162.017108] __x64_sys_pwrite64+0x6f/0xb0
[ 2162.017251] do_syscall_64+0x38/0x90
[ 2162.017395] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 2162.017559] RIP: 0033:0x7f70e88ae25a
[ 2162.017708] 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
[ 2162.018179] RSP: 002b:00007fffe4ab42e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[ 2162.018430] RAX: ffffffffffffffda RBX: 00007f70e84ab010 RCX: 00007f70e88ae25a
[ 2162.018625] RDX: 0000000000200000 RSI: 00007f70e84ab010 RDI: 0000000000000003
[ 2162.018816] RBP: 0000000000200000 R08: 00007f70e84ab010 R09: 0000000000000000
[ 2162.019026] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[ 2162.019218] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[ 2162.019432]
[ 2162.188994] BTRFS: device fsid 778990d2-4628-4b8b-88e2-bd5d2211ec44 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (368002)
[ 2162.257438] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2162.257673] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2162.257933] BTRFS info (device dm-9): force lzo compression, level 0
[ 2162.258120] BTRFS info (device dm-9): using free space tree
[ 2162.258292] BTRFS info (device dm-9): has skinny extents
[ 2162.260392] BTRFS info (device dm-9): checking UUID tree
[ 2162.585475] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (364498)
[ 2162.586240] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (364498)
[ 2162.648779] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2162.649001] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2162.649257] BTRFS info (device dm-9): force lzo compression, level 0
[ 2162.649440] BTRFS info (device dm-9): using free space tree
[ 2162.649626] BTRFS info (device dm-9): has skinny extents
[ 2162.651298] BTRFS info (device dm-9): start tree-log replay
[ 2162.674636] BTRFS info (device dm-9): checking UUID tree
[ 2163.062692] BTRFS: device fsid 730c1b42-9f2d-490b-9327-5fe5f9720ffb devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (368139)
[ 2163.156246] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2163.156470] BTRFS info (device dm-9): using free space tree
[ 2163.156659] BTRFS info (device dm-9): has skinny extents
[ 2163.158651] BTRFS info (device dm-9): checking UUID tree
[ 2163.374080] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (364498)
[ 2163.374873] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (364498)
[ 2163.439390] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2163.439734] BTRFS info (device dm-9): using free space tree
[ 2163.439998] BTRFS info (device dm-9): has skinny extents
[ 2163.442248] BTRFS info (device dm-9): start tree-log replay
[ 2163.474079] BTRFS info (device dm-9): checking UUID tree
[ 2163.541532] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2163.541932] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 368277, name: dmsetup
[ 2163.542295] preempt_count: 100, expected: 0
[ 2163.542535] RCU nest depth: 0, expected: 0
[ 2163.542734] INFO: lockdep is turned off.
[ 2163.542950] Preemption disabled at:
[ 2163.542951] [] __do_softirq+0x5e/0x517
[ 2163.543413] CPU: 0 PID: 368277 Comm: dmsetup Tainted: G W 5.17.0+ #641
[ 2163.543783] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2163.544169] Call Trace:
[ 2163.544344]
[ 2163.544514] dump_stack_lvl+0x56/0x6f
[ 2163.544725] __might_resched.cold+0xff/0x13a
[ 2163.544950] blk_release_queue+0x24/0x120
[ 2163.545163] kobject_put+0x7c/0x1d0
[ 2163.545367] ? rcu_do_batch+0x1ac/0x570
[ 2163.545576] blkg_free.part.0+0x41/0x60
[ 2163.545791] rcu_do_batch+0x1e1/0x570
[ 2163.546007] ? rcu_do_batch+0x1ac/0x570
[ 2163.546226] rcu_core+0x2cb/0x460
[ 2163.546430] __do_softirq+0x178/0x517
[ 2163.546648] __irq_exit_rcu+0xe3/0x160
[ 2163.546866] irq_exit_rcu+0xa/0x20
[ 2163.547074] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2163.547304]
[ 2163.547472]
[ 2163.547643] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2163.547971] RIP: 0010:up_write+0x5d/0x130
[ 2163.548212] Code: c0 fe 01 00 48 83 e0 fc 4c 39 c8 74 08 48 8b 45 08 a8 02 74 1b 48 c7 45 08 00 00 00 00 48 c7 c0 ff ff ff ff f0 48 0f c1 45 00 02 75 2c 5d c3 80 3d 33 9e c4 01 00 74 78 e8 8f 34 5b 00 eb d5
[ 2163.548927] RSP: 0018:ffffb94a0186fe80 EFLAGS: 00000257
[ 2163.549173] RAX: 0000000000000001 RBX: 0000000000000812 RCX: ffff963450ce27b8
[ 2163.549482] RDX: ffffb94a0186feb8 RSI: 0000000000000000 RDI: ffff963450ce2828
[ 2163.549776] RBP: ffff963450ce27b8 R08: 00007fc4388ac000 R09: ffff96344a33b200
[ 2163.550111] R10: ffff963455469a80 R11: ffff96344ff14b48 R12: 0000000000000003
[ 2163.550357] R13: ffffb94a0186feb8 R14: 000000000000001a R15: ffff963450ce2700
[ 2163.550579] vm_mmap_pgoff+0xcd/0x150
[ 2163.550723] ksys_mmap_pgoff+0x18c/0x1f0
[ 2163.550867] do_syscall_64+0x38/0x90
[ 2163.551013] entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 2163.551176] RIP: 0033:0x7fc438ca2416
[ 2163.551316] Code: 5d 41 5c c3 f3 0f 1e fa 41 f7 c1 ff 0f 00 00 75 2b 55 48 89 fd 53 89 cb 48 85 ff 74 37 41 89 da 48 89 ef b8 09 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5a 5b 5d c3 0f 1f 80 00 00 00 00 c7 05 76 1d
[ 2163.551772] RSP: 002b:00007ffff750f688 EFLAGS: 00000206 ORIG_RAX: 0000000000000009
[ 2163.552014] RAX: ffffffffffffffda RBX: 0000000000000812 RCX: 00007fc438ca2416
[ 2163.552205] RDX: 0000000000000003 RSI: 0000000000002000 RDI: 00007fc4388ac000
[ 2163.552398] RBP: 00007fc4388ac000 R08: 0000000000000003 R09: 000000000001a000
[ 2163.552597] R10: 0000000000000812 R11: 0000000000000206 R12: 00007ffff750f730
[ 2163.552790] R13: 00007ffff750faf0 R14: 0000000000000000 R15: 00007fc438c7b900
[ 2163.552989]
[ 2163.726129] BTRFS: device fsid b9905dc1-661d-4db7-b1e6-326a9ec93998 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (368276)
[ 2163.818796] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2163.819029] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2163.819290] BTRFS info (device dm-9): force lzo compression, level 0
[ 2163.819476] BTRFS info (device dm-9): using free space tree
[ 2163.819674] BTRFS info (device dm-9): has skinny extents
[ 2163.821658] BTRFS info (device dm-9): checking UUID tree
[ 2164.076309] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (364498)
[ 2164.077076] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (364498)
[ 2164.134464] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 2164.134702] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2164.134960] BTRFS info (device dm-9): force lzo compression, level 0
[ 2164.135139] BTRFS info (device dm-9): using free space tree
[ 2164.135308] BTRFS info (device dm-9): has skinny extents
[ 2164.137272] BTRFS info (device dm-9): start tree-log replay
[ 2164.153790] BTRFS info (device dm-9): checking UUID tree