[14337.558702] run fstests btrfs/159 at 2022-04-03 01:01:24
[14337.770941] BTRFS info (device dm-0): flagging fs with big metadata feature
[14337.771407] BTRFS info (device dm-0): using free space tree
[14337.771736] BTRFS info (device dm-0): has skinny extents
[14338.014209] BTRFS: device fsid f9fde9cd-ed66-4ace-8118-b52a0cce8074 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3057933)
[14338.080115] BTRFS info (device dm-11): flagging fs with big metadata feature
[14338.080716] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14338.081400] BTRFS info (device dm-11): use lzo compression, level 0
[14338.081923] BTRFS info (device dm-11): disk space caching is enabled
[14338.082466] BTRFS info (device dm-11): has skinny extents
[14338.085066] BTRFS info (device dm-11): checking UUID tree
[14342.181558] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3059645)
[14342.182933] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3059645)
[14342.284563] BTRFS info (device dm-11): flagging fs with big metadata feature
[14342.285003] BTRFS info (device dm-11): use lzo compression, level 0
[14342.285395] BTRFS info (device dm-11): disk space caching is enabled
[14342.285780] BTRFS info (device dm-11): has skinny extents
[14342.288031] BTRFS info (device dm-11): start tree-log replay
[14343.333179] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14343.333832] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3059686, name: mkfs.btrfs
[14343.334422] preempt_count: 100, expected: 0
[14343.334681] RCU nest depth: 0, expected: 0
[14343.335007] INFO: lockdep is turned off.
[14343.335292] Preemption disabled at:
[14343.335293] [] __do_softirq+0x5e/0x517
[14343.335891] CPU: 0 PID: 3059686 Comm: mkfs.btrfs Tainted: G        W         5.17.0+ #640
[14343.336403] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14343.336902] Call Trace:
[14343.337052]  
[14343.337179]  dump_stack_lvl+0x56/0x6f
[14343.337400]  __might_resched.cold+0xff/0x13a
[14343.337656]  blk_release_queue+0x24/0x120
[14343.337900]  kobject_put+0x7c/0x1d0
[14343.338111]  ? rcu_do_batch+0x1ac/0x570
[14343.338341]  blkg_free.part.0+0x41/0x60
[14343.338572]  rcu_do_batch+0x1e1/0x570
[14343.338803]  ? rcu_do_batch+0x1ac/0x570
[14343.339033]  rcu_core+0x2cb/0x460
[14343.339235]  __do_softirq+0x178/0x517
[14343.339457]  __irq_exit_rcu+0xe3/0x160
[14343.339685]  irq_exit_rcu+0xa/0x20
[14343.339890]  sysvec_apic_timer_interrupt+0xa2/0xd0
[14343.340174]  
[14343.340303]  
[14343.340432]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[14343.340740] RIP: 0010:__handle_mm_fault+0xa7d/0x1460
[14343.341033] Code: fb ff ff 48 8d 7c 24 18 e8 d0 bb ff ff 41 89 c1 e9 78 f9 ff ff 41 b9 02 00 00 00 f6 45 50 08 0f 85 68 f9 ff ff 4c 8b 64 24 48 <49> 8b 1c 24 48 89 d8 48 83 e0 9f 0f 84 7e 08 00 00 48 85 c0 0f 84
[14343.342114] RSP: 0000:ffffa8c1817e7de8 EFLAGS: 00000246
[14343.342421] RAX: 0000000000000000 RBX: 0000000000000410 RCX: 000000015768f000
[14343.342839] RDX: 0000000000000000 RSI: 0400000000000000 RDI: 0000000000000000
[14343.343254] RBP: ffff9da10713e190 R08: 0000000000000000 R09: 0000000000000002
[14343.343670] R10: 0000000000000000 R11: 0000000000000000 R12: ffff9da15768f068
[14343.344087] R13: 000000015768f067 R14: 0000000000000068 R15: ffff9da10ea07000
[14343.344505]  ? __handle_mm_fault+0x891/0x1460
[14343.344768]  handle_mm_fault+0x11e/0x3a0
[14343.345002]  do_user_addr_fault+0x1e9/0x6b0
[14343.345252]  exc_page_fault+0x78/0x280
[14343.345479]  ? asm_exc_page_fault+0x8/0x30
[14343.345725]  asm_exc_page_fault+0x1e/0x30
[14343.345967] RIP: 0033:0x7f392594de71
[14343.346183] 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
[14343.347263] RSP: 002b:00007ffdcef01d28 EFLAGS: 00010206
[14343.347569] RAX: 0000000000000000 RBX: 00000000019f8c70 RCX: 0000000000176c70
[14343.347986] RDX: 00000000019f8c70 RSI: 0000000000000000 RDI: 0000000001a82000
[14343.348400] RBP: 0000000000200000 R08: 00000000019f8c70 R09: 00007f39259ada60
[14343.348819] R10: fffffffffffff000 R11: 0000000001a16000 R12: 0000000000000000
[14343.349236] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[14343.349652]  
[14343.544955] BTRFS: device fsid ebf55a8b-5da0-4687-9e8c-06df53330a0b devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3059686)
[14343.602986] BTRFS info (device dm-11): flagging fs with big metadata feature
[14343.603437] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14343.603938] BTRFS info (device dm-11): use lzo compression, level 0
[14343.604331] BTRFS info (device dm-11): disk space caching is enabled
[14343.604715] BTRFS info (device dm-11): has skinny extents
[14343.606834] BTRFS info (device dm-11): checking UUID tree
[14347.667624] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3061400)
[14347.668866] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3061400)
[14347.768485] BTRFS info (device dm-11): flagging fs with big metadata feature
[14347.769070] BTRFS info (device dm-11): use lzo compression, level 0
[14347.769533] BTRFS info (device dm-11): disk space caching is enabled
[14347.769971] BTRFS info (device dm-11): has skinny extents
[14347.773431] BTRFS info (device dm-11): start tree-log replay
[14348.776177] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14348.777685] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[14348.779131] preempt_count: 101, expected: 0
[14348.779918] RCU nest depth: 0, expected: 0
[14348.780692] INFO: lockdep is turned off.
[14348.781443] Preemption disabled at:
[14348.781445] [] ___slab_alloc+0x961/0xf50
[14348.783277] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G        W         5.17.0+ #640
[14348.784424] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14348.785580] Call Trace:
[14348.785935]  
[14348.786228]  dump_stack_lvl+0x56/0x6f
[14348.786746]  __might_resched.cold+0xff/0x13a
[14348.787343]  blk_release_queue+0x24/0x120
[14348.787908]  kobject_put+0x7c/0x1d0
[14348.788396]  ? rcu_do_batch+0x1ac/0x570
[14348.788892]  blkg_free.part.0+0x41/0x60
[14348.789323]  rcu_do_batch+0x1e1/0x570
[14348.789740]  ? rcu_do_batch+0x1ac/0x570
[14348.790172]  rcu_core+0x2cb/0x460
[14348.790548]  __do_softirq+0x178/0x517
[14348.790965]  __irq_exit_rcu+0xe3/0x160
[14348.791389]  irq_exit_rcu+0xa/0x20
[14348.791775]  sysvec_apic_timer_interrupt+0xa2/0xd0
[14348.792308]  
[14348.792551]  
[14348.792801]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[14348.793369] RIP: 0010:native_safe_halt+0xb/0x10
[14348.793839] Code: 54 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
[14348.795538] RSP: 0018:ffffa8c180093ef8 EFLAGS: 00000246
[14348.796027] RAX: ffffffffabe64a80 RBX: ffff9da100340000 RCX: 0000000000000000
[14348.796685] RDX: ffff9da100340000 RSI: ffffffffac87e4d9 RDI: ffffffffabe64bf6
[14348.797336] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[14348.797993] R10: 0000000000000000 R11: ffff9da17bd20c88 R12: 0000000000000000
[14348.798648] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[14348.799213]  ? mwait_idle+0x80/0x80
[14348.799496]  ? default_idle_call+0x26/0xa0
[14348.799831]  default_idle+0xa/0x10
[14348.800109]  default_idle_call+0x5e/0xa0
[14348.800429]  do_idle+0x1e9/0x240
[14348.800700]  cpu_startup_entry+0x19/0x20
[14348.801017]  secondary_startup_64_no_verify+0xd5/0xdb
[14348.801425]