[30097.232744] run fstests btrfs/056 at 2022-04-04 05:12:31
[30097.437176] BTRFS info (device dm-0): flagging fs with big metadata feature
[30097.437597] BTRFS info (device dm-0): using free space tree
[30097.437915] BTRFS info (device dm-0): has skinny extents
[30097.687004] BTRFS: device fsid acca32ca-b5fe-411c-803f-1b4e33ff89cb devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2681515)
[30097.753359] BTRFS info (device dm-11): flagging fs with big metadata feature
[30097.753800] BTRFS info (device dm-11): using free space tree
[30097.754164] BTRFS info (device dm-11): has skinny extents
[30097.755783] BTRFS info (device dm-11): checking UUID tree
[30097.935362] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2679882)
[30097.936524] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2679882)
[30098.001391] BTRFS info (device dm-11): flagging fs with big metadata feature
[30098.001847] BTRFS info (device dm-11): using free space tree
[30098.002223] BTRFS info (device dm-11): has skinny extents
[30098.003902] BTRFS info (device dm-11): start tree-log replay
[30098.023414] BTRFS info (device dm-11): checking UUID tree
[30098.101041] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[30098.101543] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[30098.102098] preempt_count: 100, expected: 0
[30098.102357] RCU nest depth: 0, expected: 0
[30098.102603] INFO: lockdep is turned off.
[30098.102838] Preemption disabled at:
[30098.102839] [] __do_softirq+0x5e/0x517
[30098.103388] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G W 5.17.0+ #641
[30098.103849] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[30098.104394] Call Trace:
[30098.104548]
[30098.104702] dump_stack_lvl+0x56/0x6f
[30098.104941] __might_resched.cold+0xff/0x13a
[30098.105200] blk_release_queue+0x24/0x120
[30098.105484] kobject_put+0x7c/0x1d0
[30098.105698] ? rcu_do_batch+0x1ac/0x570
[30098.105954] blkg_free.part.0+0x41/0x60
[30098.106205] rcu_do_batch+0x1e1/0x570
[30098.106432] ? rcu_do_batch+0x1ac/0x570
[30098.106667] rcu_core+0x2cb/0x460
[30098.106870] __do_softirq+0x178/0x517
[30098.107094] ? smpboot_thread_fn+0x2c/0x270
[30098.107350] run_ksoftirqd+0x43/0x70
[30098.107570] smpboot_thread_fn+0x1c1/0x270
[30098.107826] ? sort_range+0x20/0x20
[30098.108039] kthread+0xf0/0x120
[30098.108231] ? kthread_complete_and_exit+0x20/0x20
[30098.108649] ret_from_fork+0x1f/0x30
[30098.108869]
[30098.285033] BTRFS: device fsid 7cd2352b-b21b-41bf-90ae-e1b234b28909 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2681653)
[30098.366048] BTRFS info (device dm-11): flagging fs with big metadata feature
[30098.366582] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[30098.367179] BTRFS info (device dm-11): force lzo compression, level 0
[30098.367629] BTRFS info (device dm-11): using free space tree
[30098.368040] BTRFS info (device dm-11): has skinny extents
[30098.369936] BTRFS info (device dm-11): checking UUID tree
[30098.630088] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2679882)
[30098.631239] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2679882)
[30098.695988] BTRFS info (device dm-11): flagging fs with big metadata feature
[30098.696488] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[30098.697017] BTRFS info (device dm-11): force lzo compression, level 0
[30098.697452] BTRFS info (device dm-11): using free space tree
[30098.697797] BTRFS info (device dm-11): has skinny extents
[30098.699640] BTRFS info (device dm-11): start tree-log replay
[30098.719605] BTRFS info (device dm-11): checking UUID tree
[30098.922240] BTRFS: device fsid 56859f9e-831d-4b48-bdcb-66ecd963f989 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2681790)
[30098.990177] BTRFS info (device dm-11): flagging fs with big metadata feature
[30098.990622] BTRFS info (device dm-11): using free space tree
[30098.990956] BTRFS info (device dm-11): has skinny extents
[30098.992931] BTRFS info (device dm-11): checking UUID tree
[30099.183149] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2679882)
[30099.184651] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2679882)
[30099.254699] BTRFS info (device dm-11): flagging fs with big metadata feature
[30099.255323] BTRFS info (device dm-11): using free space tree
[30099.255752] BTRFS info (device dm-11): has skinny extents
[30099.257674] BTRFS info (device dm-11): start tree-log replay
[30099.273188] BTRFS info (device dm-11): checking UUID tree
[30099.357987] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[30099.358521] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2681927, name: mkfs.btrfs
[30099.359040] preempt_count: 100, expected: 0
[30099.359299] RCU nest depth: 0, expected: 0
[30099.359721] INFO: lockdep is turned off.
[30099.359964] Preemption disabled at:
[30099.359980] [] __do_softirq+0x5e/0x517
[30099.360516] CPU: 1 PID: 2681927 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #641
[30099.360983] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[30099.361521] Call Trace:
[30099.361683]
[30099.361806] dump_stack_lvl+0x56/0x6f
[30099.362045] __might_resched.cold+0xff/0x13a
[30099.362319] blk_release_queue+0x24/0x120
[30099.362557] kobject_put+0x7c/0x1d0
[30099.362767] ? rcu_do_batch+0x1ac/0x570
[30099.363023] blkg_free.part.0+0x41/0x60
[30099.363254] rcu_do_batch+0x1e1/0x570
[30099.363598] ? rcu_do_batch+0x1ac/0x570
[30099.363836] rcu_core+0x2cb/0x460
[30099.364056] __do_softirq+0x178/0x517
[30099.364284] __irq_exit_rcu+0xe3/0x160
[30099.364556] irq_exit_rcu+0xa/0x20
[30099.364765] sysvec_apic_timer_interrupt+0xa2/0xd0
[30099.365071]
[30099.365208]
[30099.365349] asm_sysvec_apic_timer_interrupt+0x12/0x20
[30099.365681] RIP: 0010:memcg_slab_post_alloc_hook+0x7b/0x440
[30099.366033] Code: 03 00 00 48 c7 c0 00 00 00 80 48 2b 05 8e ce 52 01 48 01 d0 48 8b 0d 74 ce 52 01 48 c1 e8 0c 48 c1 e0 06 48 01 c8 48 8b 50 08 <49> 89 c7 f6 c2 01 0f 85 e1 02 00 00 0f 1f 44 00 00 49 8b 07 f6 c4
[30099.367176] RSP: 0018:ffffa28443943b88 EFLAGS: 00000282
[30099.367517] RAX: ffffc95d040ecc80 RBX: ffff94fc43b327e0 RCX: ffffc95d00000000
[30099.367960] RDX: 0000000000000000 RSI: ffff94fc5bd2f880 RDI: ffff94fc401f3f00
[30099.368499] RBP: ffff94fc401f3f00 R08: ffffa28443943bd0 R09: ffff94fc43b327e0
[30099.368954] R10: 0000000000000000 R11: 0000000000000199 R12: ffff94fc5bd2f880
[30099.369405] R13: 0000000000000000 R14: ffffa28443943bd0 R15: 0000000000408d40
[30099.369870] kmem_cache_alloc+0x1d1/0x3f0
[30099.370144] alloc_buffer_head+0x1b/0xc0
[30099.370452] alloc_page_buffers+0xc4/0x1e0
[30099.370690] ? blkdev_llseek+0x60/0x60
[30099.370907] create_empty_buffers+0x19/0x250
[30099.371156] create_page_buffers+0x75/0x90
[30099.371395] __block_write_begin_int+0x70/0x500
[30099.371658] ? blkdev_llseek+0x60/0x60
[30099.371874] ? blkdev_llseek+0x60/0x60
[30099.372093] ? blkdev_llseek+0x60/0x60
[30099.372333] block_write_begin+0x50/0x130
[30099.372584] generic_perform_write+0xa9/0x1d0
[30099.372837] __generic_file_write_iter+0xdb/0x1a0
[30099.373134] blkdev_write_iter+0xc1/0x150
[30099.373404] new_sync_write+0xf6/0x160
[30099.373625] vfs_write+0x290/0x390
[30099.373823] __x64_sys_pwrite64+0x6f/0xb0
[30099.374056] do_syscall_64+0x38/0x90
[30099.374264] entry_SYSCALL_64_after_hwframe+0x44/0xae
[30099.374597] RIP: 0033:0x7f5696ba719a
[30099.374806] 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
[30099.375848] RSP: 002b:00007ffc3ad51c08 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[30099.376303] RAX: ffffffffffffffda RBX: 00007f56967a4010 RCX: 00007f5696ba719a
[30099.376724] RDX: 0000000000200000 RSI: 00007f56967a4010 RDI: 0000000000000003
[30099.377179] RBP: 0000000000200000 R08: 00007f56967a4010 R09: 0000000000000000
[30099.377668] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[30099.378080] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[30099.378509]
[30099.546465] BTRFS: device fsid dc91a9d3-b9fc-4d97-803c-c67786dc1597 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2681927)
[30099.612379] BTRFS info (device dm-11): flagging fs with big metadata feature
[30099.612819] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[30099.613338] BTRFS info (device dm-11): force lzo compression, level 0
[30099.613725] BTRFS info (device dm-11): using free space tree
[30099.614097] BTRFS info (device dm-11): has skinny extents
[30099.615749] BTRFS info (device dm-11): checking UUID tree
[30099.755776] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2679882)
[30099.756942] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2679882)
[30099.826933] BTRFS info (device dm-11): flagging fs with big metadata feature
[30099.827391] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[30099.827877] BTRFS info (device dm-11): force lzo compression, level 0
[30099.828270] BTRFS info (device dm-11): using free space tree
[30099.828607] BTRFS info (device dm-11): has skinny extents
[30099.830577] BTRFS info (device dm-11): start tree-log replay
[30099.848159] BTRFS info (device dm-11): checking UUID tree