[ 2517.213829] run fstests btrfs/056 at 2022-04-01 21:33:23
[ 2517.408323] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 2517.408598] BTRFS info (device dm-0): using free space tree
[ 2517.408781] BTRFS info (device dm-0): has skinny extents
[ 2517.631948] BTRFS: device fsid 801fe407-a83a-41ec-acaf-5a8416697d43 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (365683)
[ 2517.697443] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2517.697675] BTRFS info (device dm-11): turning on async discard
[ 2517.697860] BTRFS info (device dm-11): disk space caching is enabled
[ 2517.698050] BTRFS info (device dm-11): has skinny extents
[ 2517.700020] BTRFS info (device dm-11): checking UUID tree
[ 2517.923517] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (362308)
[ 2517.924274] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (362308)
[ 2517.984020] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2517.984236] BTRFS info (device dm-11): turning on async discard
[ 2517.984440] BTRFS info (device dm-11): disk space caching is enabled
[ 2517.984627] BTRFS info (device dm-11): has skinny extents
[ 2517.985929] BTRFS info (device dm-11): start tree-log replay
[ 2517.998464] BTRFS info (device dm-11): checking UUID tree
[ 2518.073418] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2518.073797] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 20, name: ksoftirqd/1
[ 2518.074128] preempt_count: 100, expected: 0
[ 2518.074275] RCU nest depth: 0, expected: 0
[ 2518.074442] INFO: lockdep is turned off.
[ 2518.074590] Preemption disabled at:
[ 2518.074591] [] __do_softirq+0x5e/0x517
[ 2518.074978] CPU: 1 PID: 20 Comm: ksoftirqd/1 Not tainted 5.17.0+ #639
[ 2518.075162] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2518.075433] Call Trace:
[ 2518.075554]
[ 2518.075670] dump_stack_lvl+0x56/0x6f
[ 2518.075818] __might_resched.cold+0xff/0x13a
[ 2518.076013] blk_release_queue+0x24/0x120
[ 2518.076173] kobject_put+0x7c/0x1d0
[ 2518.076321] ? rcu_do_batch+0x1ac/0x570
[ 2518.076465] blkg_free.part.0+0x41/0x60
[ 2518.076630] rcu_do_batch+0x1e1/0x570
[ 2518.076782] ? rcu_do_batch+0x1ac/0x570
[ 2518.076964] rcu_core+0x2cb/0x460
[ 2518.077141] __do_softirq+0x178/0x517
[ 2518.083009] ? smpboot_thread_fn+0x2c/0x270
[ 2518.083164] run_ksoftirqd+0x43/0x70
[ 2518.083306] smpboot_thread_fn+0x1c1/0x270
[ 2518.083459] ? sort_range+0x20/0x20
[ 2518.083596] kthread+0xf0/0x120
[ 2518.083727] ? kthread_complete_and_exit+0x20/0x20
[ 2518.083889] ret_from_fork+0x1f/0x30
[ 2518.084033]
[ 2518.222373] BTRFS: device fsid bab6471a-e0e6-4940-bfbd-7cc3ec8c0fe1 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (365820)
[ 2518.305942] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2518.306264] BTRFS info (device dm-11): turning on async discard
[ 2518.307129] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2518.307424] BTRFS info (device dm-11): force lzo compression, level 0
[ 2518.307622] BTRFS info (device dm-11): disk space caching is enabled
[ 2518.307816] BTRFS info (device dm-11): has skinny extents
[ 2518.310201] BTRFS info (device dm-11): checking UUID tree
[ 2518.456765] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (362308)
[ 2518.457564] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (362308)
[ 2518.517832] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2518.518056] BTRFS info (device dm-11): turning on async discard
[ 2518.518234] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2518.518535] BTRFS info (device dm-11): force lzo compression, level 0
[ 2518.518726] BTRFS info (device dm-11): disk space caching is enabled
[ 2518.518915] BTRFS info (device dm-11): has skinny extents
[ 2518.520354] BTRFS info (device dm-11): start tree-log replay
[ 2518.558840] BTRFS info (device dm-11): checking UUID tree
[ 2518.821603] BTRFS: device fsid accc4659-59f1-4647-b531-f916c7c4379c devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (365957)
[ 2518.881712] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2518.881935] BTRFS info (device dm-11): turning on async discard
[ 2518.882113] BTRFS info (device dm-11): disk space caching is enabled
[ 2518.882302] BTRFS info (device dm-11): has skinny extents
[ 2518.884017] BTRFS info (device dm-11): checking UUID tree
[ 2519.067230] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (362308)
[ 2519.068041] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (362308)
[ 2519.129006] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2519.129228] BTRFS info (device dm-11): turning on async discard
[ 2519.129429] BTRFS info (device dm-11): disk space caching is enabled
[ 2519.129611] BTRFS info (device dm-11): has skinny extents
[ 2519.131206] BTRFS info (device dm-11): start tree-log replay
[ 2519.191161] BTRFS info (device dm-11): checking UUID tree
[ 2519.374384] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 2519.374665] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 366094, name: mkfs.btrfs
[ 2519.374919] preempt_count: 100, expected: 0
[ 2519.375071] RCU nest depth: 0, expected: 0
[ 2519.375214] INFO: lockdep is turned off.
[ 2519.375483] Preemption disabled at:
[ 2519.375484] [] __do_softirq+0x5e/0x517
[ 2519.375900] CPU: 1 PID: 366094 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #639
[ 2519.376201] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 2519.376515] Call Trace:
[ 2519.376831]
[ 2519.377004] dump_stack_lvl+0x56/0x6f
[ 2519.377184] __might_resched.cold+0xff/0x13a
[ 2519.377356] blk_release_queue+0x24/0x120
[ 2519.377541] kobject_put+0x7c/0x1d0
[ 2519.377682] ? rcu_do_batch+0x1ac/0x570
[ 2519.377875] blkg_free.part.0+0x41/0x60
[ 2519.378073] rcu_do_batch+0x1e1/0x570
[ 2519.378238] ? rcu_do_batch+0x1ac/0x570
[ 2519.378410] rcu_core+0x2cb/0x460
[ 2519.378544] __do_softirq+0x178/0x517
[ 2519.378685] __irq_exit_rcu+0xe3/0x160
[ 2519.378829] irq_exit_rcu+0xa/0x20
[ 2519.378962] sysvec_apic_timer_interrupt+0xa2/0xd0
[ 2519.379119]
[ 2519.379235]
[ 2519.379356] asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 2519.379520] RIP: 0010:try_charge_memcg+0x163/0x850
[ 2519.379681] Code: 04 00 00 48 c7 43 28 00 00 00 00 48 c7 c6 5c c6 42 b8 48 89 df e8 bd a6 db ff 4d 85 e4 74 0b e8 d3 fb e7 ff fb 0f 1f 44 00 00 <45> 84 ff 0f 85 23 02 00 00 0f 1f 44 00 00 45 89 f7 4c 8d 64 24 28
[ 2519.380145] RSP: 0000:ffffb835c1827d08 EFLAGS: 00000246
[ 2519.380311] RAX: 0000000080000000 RBX: ffff9418bbd2f620 RCX: 0000000000000000
[ 2519.380506] RDX: 000000000000001e RSI: ffffffffb842c65c RDI: ffffffffb842c67d
[ 2519.380705] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[ 2519.380899] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000200
[ 2519.381098] R13: ffff94184dd3a000 R14: 0000000000000020 R15: 0000000000000001
[ 2519.381299] ? try_charge_memcg+0x13c/0x850
[ 2519.381451] ? try_charge_memcg+0x15d/0x850
[ 2519.381601] ? try_charge_memcg+0x15d/0x850
[ 2519.381751] ? rcu_read_lock_sched_held+0x12/0x60
[ 2519.381906] ? rcu_read_lock_sched_held+0x12/0x60
[ 2519.382067] ? lock_release+0x1ed/0x2c0
[ 2519.382211] charge_memcg+0xd8/0x1a0
[ 2519.382352] __mem_cgroup_charge+0x2d/0x60
[ 2519.382497] __handle_mm_fault+0xb68/0x1460
[ 2519.382648] ? mmap_region+0x2da/0x620
[ 2519.382793] handle_mm_fault+0x11e/0x3a0
[ 2519.382936] do_user_addr_fault+0x1e9/0x6b0
[ 2519.383086] exc_page_fault+0x78/0x280
[ 2519.383228] ? asm_exc_page_fault+0x8/0x30
[ 2519.383379] asm_exc_page_fault+0x1e/0x30
[ 2519.383526] RIP: 0033:0x7fd037fd9411
[ 2519.383666] 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
[ 2519.384124] RSP: 002b:00007fff36153d68 EFLAGS: 00010206
[ 2519.384289] RAX: 0000000000000000 RBX: 00007fd037c53010 RCX: 0000000000023010
[ 2519.384485] RDX: 00007fd037c53010 RSI: 0000000000000000 RDI: 00007fd037e30000
[ 2519.384682] RBP: 0000000000200000 R08: 00007fd037c53010 R09: 0000000000000000
[ 2519.384878] R10: fffffffffffffb0c R11: 00007fd037fd9430 R12: 0000000000000000
[ 2519.385076] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[ 2519.385274]
[ 2519.523252] BTRFS: device fsid d1a18925-59a9-4d34-8d48-6494cb71afb0 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (366094)
[ 2519.581766] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2519.581985] BTRFS info (device dm-11): turning on async discard
[ 2519.582165] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2519.582446] BTRFS info (device dm-11): force lzo compression, level 0
[ 2519.582632] BTRFS info (device dm-11): disk space caching is enabled
[ 2519.582819] BTRFS info (device dm-11): has skinny extents
[ 2519.584515] BTRFS info (device dm-11): checking UUID tree
[ 2519.752123] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (362308)
[ 2519.752913] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (362308)
[ 2519.824235] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 2519.824478] BTRFS info (device dm-11): turning on async discard
[ 2519.824662] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[ 2519.824922] BTRFS info (device dm-11): force lzo compression, level 0
[ 2519.825105] BTRFS info (device dm-11): disk space caching is enabled
[ 2519.825299] BTRFS info (device dm-11): has skinny extents
[ 2519.826851] BTRFS info (device dm-11): start tree-log replay
[ 2519.835354] BTRFS info (device dm-11): checking UUID tree