[14477.735407] run fstests btrfs/056 at 2022-04-02 00:52:43
[14477.956155] BTRFS info (device dm-0): flagging fs with big metadata feature
[14477.956429] BTRFS info (device dm-0): using free space tree
[14477.956705] BTRFS info (device dm-0): has skinny extents
[14478.105150] BTRFS: device fsid 2857ff4d-a2a2-4351-b611-eaa8f2251887 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2879461)
[14478.162970] BTRFS info (device dm-11): flagging fs with big metadata feature
[14478.163205] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14478.163461] BTRFS info (device dm-11): use zstd compression, level 3
[14478.163647] BTRFS info (device dm-11): turning on async discard
[14478.163825] BTRFS info (device dm-11): disk space caching is enabled
[14478.164013] BTRFS info (device dm-11): has skinny extents
[14478.165583] BTRFS info (device dm-11): checking UUID tree
[14478.450144] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2876084)
[14478.450969] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2876084)
[14478.511177] BTRFS info (device dm-11): flagging fs with big metadata feature
[14478.511402] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14478.511654] BTRFS info (device dm-11): use zstd compression, level 3
[14478.511840] BTRFS info (device dm-11): turning on async discard
[14478.512045] BTRFS info (device dm-11): disk space caching is enabled
[14478.512231] BTRFS info (device dm-11): has skinny extents
[14478.514139] BTRFS info (device dm-11): start tree-log replay
[14478.523933] BTRFS info (device dm-11): checking UUID tree
[14478.607034] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14478.607388] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2879599, name: mkfs.btrfs
[14478.607712] preempt_count: 100, expected: 0
[14478.607894] RCU nest depth: 0, expected: 0
[14478.608106] INFO: lockdep is turned off.
[14478.608290] Preemption disabled at:
[14478.608291] [] __do_softirq+0x5e/0x517
[14478.608674] CPU: 0 PID: 2879599 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #639
[14478.608999] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14478.609319] Call Trace:
[14478.609472]
[14478.609616] dump_stack_lvl+0x56/0x6f
[14478.609796] __might_resched.cold+0xff/0x13a
[14478.609994] blk_release_queue+0x24/0x120
[14478.610178] kobject_put+0x7c/0x1d0
[14478.610351] ? rcu_do_batch+0x1ac/0x570
[14478.610534] blkg_free.part.0+0x41/0x60
[14478.610716] rcu_do_batch+0x1e1/0x570
[14478.610889] ? rcu_do_batch+0x1ac/0x570
[14478.611075] rcu_core+0x2cb/0x460
[14478.611247] __do_softirq+0x178/0x517
[14478.611424] __irq_exit_rcu+0xe3/0x160
[14478.611601] irq_exit_rcu+0xa/0x20
[14478.611773] sysvec_apic_timer_interrupt+0xa2/0xd0
[14478.611975]
[14478.612117]
[14478.612262] asm_sysvec_apic_timer_interrupt+0x12/0x20
[14478.612465] RIP: 0010:__rcu_read_unlock+0x43/0x1b0
[14478.612662] Code: 07 00 00 01 75 0a 8b 85 f8 07 00 00 85 c0 75 23 65 48 8b 04 25 c0 fe 01 00 8b 80 f4 07 00 00 3d ff ff ff 3f 0f 87 ba 00 00 00 <5b> 5d 41 5c 41 5d 41 5e c3 65 8b 1d ed e5 e0 47 f7 c3 00 00 f0 00
[14478.613253] RSP: 0000:ffffb835c0aa3d80 EFLAGS: 00000293
[14478.613462] RAX: 0000000000000000 RBX: ffff941851662000 RCX: 0000000000000000
[14478.613707] RDX: ffffffffb8426480 RSI: ffffffffb987e4d9 RDI: ffffffffb983354b
[14478.613950] RBP: ffff94184418b200 R08: 0000000000000000 R09: 0000000000000000
[14478.614198] R10: 0000000000000000 R11: 0000000000000000 R12: ffffddd185649dc0
[14478.614444] R13: 0000000000000cc0 R14: 0000000000000048 R15: ffff9418444af000
[14478.614694] ? mem_cgroup_css_online+0x140/0x140
[14478.614893] percpu_ref_put_many.constprop.0+0x51/0xf0
[14478.615106] __mem_cgroup_charge+0x4a/0x60
[14478.615294] __handle_mm_fault+0xb68/0x1460
[14478.615487] handle_mm_fault+0x11e/0x3a0
[14478.615668] do_user_addr_fault+0x1e9/0x6b0
[14478.615858] exc_page_fault+0x78/0x280
[14478.616045] ? asm_exc_page_fault+0x8/0x30
[14478.616230] asm_exc_page_fault+0x1e/0x30
[14478.616413] RIP: 0033:0x7f5238ad5411
[14478.616589] Code: 01 00 00 48 83 fa 40 77 77 c5 fe 7f 44 17 e0 c5 fe 7f 07 c5 f8 77 c3 66 90 f3 0f 1e fa c5 f8 77 48 89 d1 40 0f b6 c6 48 89 fa aa 48 89 d0 c3 66 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 48 39 d1
[14478.617174] RSP: 002b:00007ffda5fc9b58 EFLAGS: 00010206
[14478.617379] RAX: 0000000000000000 RBX: 0000000001325c70 RCX: 000000000019ac70
[14478.617627] RDX: 0000000001325c70 RSI: 0000000000000000 RDI: 000000000138b000
[14478.617876] RBP: 0000000000200000 R08: 0000000001325c70 R09: 00007f5238b34a60
[14478.618128] R10: 0000000000200030 R11: 0000000001343000 R12: 0000000000000000
[14478.618375] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[14478.618627]
[14478.706885] BTRFS: device fsid c03b9db5-476b-4eea-9a20-4c4f85ac2c90 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2879599)
[14478.798738] BTRFS info (device dm-11): flagging fs with big metadata feature
[14478.798965] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14478.799250] BTRFS info (device dm-11): use zstd compression, level 3
[14478.799435] BTRFS info (device dm-11): turning on async discard
[14478.799610] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14478.799862] BTRFS info (device dm-11): force lzo compression, level 0
[14478.800069] BTRFS info (device dm-11): disk space caching is enabled
[14478.800256] BTRFS info (device dm-11): has skinny extents
[14478.801849] BTRFS info (device dm-11): checking UUID tree
[14478.922853] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2876084)
[14478.923736] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2876084)
[14478.992669] BTRFS info (device dm-11): flagging fs with big metadata feature
[14478.992891] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14478.993196] BTRFS info (device dm-11): use zstd compression, level 3
[14478.993386] BTRFS info (device dm-11): turning on async discard
[14478.993564] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14478.993822] BTRFS info (device dm-11): force lzo compression, level 0
[14479.184301] BTRFS: device fsid d54cb160-941f-4bae-9fe7-34767e8ea4e7 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2879736)
[14479.690031] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14479.690306] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[14479.690554] preempt_count: 101, expected: 0
[14479.690701] RCU nest depth: 0, expected: 0
[14479.690846] INFO: lockdep is turned off.
[14479.690994] Preemption disabled at:
[14479.690995] [] schedule_preempt_disabled+0x1e/0x20
[14479.691338] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 5.17.0+ #639
[14479.691578] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14479.691840] Call Trace:
[14479.691962]
[14479.692077] dump_stack_lvl+0x56/0x6f
[14479.692220] __might_resched.cold+0xff/0x13a
[14479.692371] blk_release_queue+0x24/0x120
[14479.692518] kobject_put+0x7c/0x1d0
[14479.692659] ? rcu_do_batch+0x1ac/0x570
[14479.692802] blkg_free.part.0+0x41/0x60
[14479.692949] rcu_do_batch+0x1e1/0x570
[14479.693092] ? rcu_do_batch+0x1ac/0x570
[14479.693239] rcu_core+0x2cb/0x460
[14479.693373] __do_softirq+0x178/0x517
[14479.693516] __irq_exit_rcu+0xe3/0x160
[14479.693664] irq_exit_rcu+0xa/0x20
[14479.693798] sysvec_apic_timer_interrupt+0xa2/0xd0
[14479.693960]
[14479.694124]
[14479.694239] asm_sysvec_apic_timer_interrupt+0x12/0x20
[14479.694402] RIP: 0010:native_safe_halt+0xb/0x10
[14479.694557] Code: 47 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
[14479.695076] RSP: 0018:ffffffffb9a03ea8 EFLAGS: 00000246
[14479.695241] RAX: ffffffffb8e64a80 RBX: ffffffffb9a30a00 RCX: 0000000000000000
[14479.695433] RDX: ffffffffb9a30a00 RSI: ffffffffb987e4d9 RDI: ffffffffb8e64bf6
[14479.695628] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[14479.695820] R10: 0000000000000000 R11: ffff9418bbc20c88 R12: 0000000000000000
[14479.696022] R13: 0000000000000000 R14: ffffffffb9a30118 R15: 0000000000000065
[14479.696219] ? mwait_idle+0x80/0x80
[14479.696359] ? default_idle_call+0x26/0xa0
[14479.696511] default_idle+0xa/0x10
[14479.696646] default_idle_call+0x5e/0xa0
[14479.696792] do_idle+0x1e9/0x240
[14479.696928] cpu_startup_entry+0x19/0x20
[14479.697075] start_kernel+0x98c/0x99e
[14479.697215] ? copy_bootdata+0x18/0x55
[14479.697359] secondary_startup_64_no_verify+0xd5/0xdb
[14479.697523]
[14479.801329] BTRFS: device fsid 8025deb7-e082-4dc9-aca1-9f0bc76e9614 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2879873)