[24268.911785] run fstests generic/479 at 2022-04-02 03:34:59
[24269.436381] BTRFS: device fsid 01bcbdf3-c791-4e29-b5ea-b3672192e965 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (1478631)
[24269.553423] BTRFS info (device dm-9): flagging fs with big metadata feature
[24269.553636] BTRFS info (device dm-9): use zlib compression, level 3
[24269.553820] BTRFS info (device dm-9): using free space tree
[24269.553989] BTRFS info (device dm-9): has skinny extents
[24269.556491] BTRFS info (device dm-9): checking UUID tree
[24269.786991] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (1477843)
[24269.787813] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1477843)
[24269.835297] BTRFS info (device dm-9): flagging fs with big metadata feature
[24269.835522] BTRFS info (device dm-9): use zlib compression, level 3
[24269.835705] BTRFS info (device dm-9): using free space tree
[24269.835872] BTRFS info (device dm-9): has skinny extents
[24269.837842] BTRFS info (device dm-9): start tree-log replay
[24269.903159] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[24269.903454] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1478721, name: mkfs.btrfs
[24269.903870] preempt_count: 101, expected: 0
[24269.904159] RCU nest depth: 1, expected: 0
[24269.905036] INFO: lockdep is turned off.
[24269.905393] Preemption disabled at:
[24269.905394] [<0000000000000000>] 0x0
[24269.912703] CPU: 1 PID: 1478721 Comm: mkfs.btrfs Tainted: G        W         5.17.0+ #639
[24269.913187] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[24269.913696] Call Trace:
[24269.913916]  
[24269.914146]  dump_stack_lvl+0x56/0x6f
[24269.914389]  __might_resched.cold+0xff/0x13a
[24269.914657]  blk_release_queue+0x24/0x120
[24269.914913]  kobject_put+0x7c/0x1d0
[24269.915142]  ? rcu_do_batch+0x1ac/0x570
[24269.915385]  blkg_free.part.0+0x41/0x60
[24269.915604]  rcu_do_batch+0x1e1/0x570
[24269.915891]  ? rcu_do_batch+0x1ac/0x570
[24269.916146]  rcu_core+0x2cb/0x460
[24269.916393]  __do_softirq+0x178/0x517
[24269.916645]  __irq_exit_rcu+0xe3/0x160
[24269.916911]  irq_exit_rcu+0xa/0x20
[24269.917149]  sysvec_apic_timer_interrupt+0xa2/0xd0
[24269.917421]  
[24269.917644]  
[24269.917845]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[24269.918142] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[24269.918452] Code: 48 83 c7 18 53 48 89 f3 48 8b 74 24 10 e8 45 1b 38 ff 48 89 ef e8 dd 44 38 ff 80 e7 02 74 0b e8 53 70 44 ff fb 0f 1f 44 00 00  01 00 00 00 e8 93 d8 33 ff 65 8b 05 6c ac 1b 6d 85 c0 74 03 5b
[24269.919217] RSP: 0018:ffffa5928366bc80 EFLAGS: 00000246
[24269.919525] RAX: 0000000080000001 RBX: 0000000000000286 RCX: 0000000000000000
[24269.919866] RDX: 0000000000000002 RSI: ffffffff9249e718 RDI: ffffffff92e651fd
[24269.920212] RBP: ffff936083afa990 R08: 0000000000000000 R09: 0000000000000000
[24269.920541] R10: ffff936084828f18 R11: 0000000000000000 R12: 0000000000000000
[24269.920877] R13: 0000000000001000 R14: 0000000000000000 R15: ffff936084828f18
[24269.921213]  ? mark_buffer_dirty+0x178/0x1d0
[24269.921495]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[24269.921745]  mark_buffer_dirty+0x178/0x1d0
[24269.922017]  __block_commit_write.constprop.0.isra.0+0x51/0xb0
[24269.922331]  block_write_end+0x30/0xb0
[24269.922637]  ? blkdev_llseek+0x60/0x60
[24269.922879]  blkdev_write_end+0x14/0xb0
[24269.923142]  generic_perform_write+0xf7/0x1d0
[24269.923445]  __generic_file_write_iter+0xdb/0x1a0
[24269.923748]  blkdev_write_iter+0xc1/0x150
[24269.924046]  new_sync_write+0xf6/0x160
[24269.924304]  vfs_write+0x290/0x390
[24269.924602]  __x64_sys_pwrite64+0x6f/0xb0
[24269.924865]  do_syscall_64+0x38/0x90
[24269.925117]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[24269.925427] RIP: 0033:0x7f580a6d125a
[24269.925701] 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
[24269.926549] RSP: 002b:00007ffcf05f6358 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[24269.927043] RAX: ffffffffffffffda RBX: 000000000223ec70 RCX: 00007f580a6d125a
[24269.927410] RDX: 0000000000200000 RSI: 000000000223ec70 RDI: 0000000000000003
[24269.927771] RBP: 0000000000200000 R08: 000000000223ec70 R09: 00007f580a6b2a60
[24269.928140] R10: 000000027fe00000 R11: 0000000000000246 R12: 0000000000000000
[24269.928460] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[24269.928851]  
[24270.096817] BTRFS: device fsid 49392dc4-b5e4-4355-97e3-16271a92dac2 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (1478721)
[24270.163401] BTRFS info (device dm-9): flagging fs with big metadata feature
[24270.163625] BTRFS info (device dm-9): use zlib compression, level 3
[24270.163808] BTRFS info (device dm-9): using free space tree
[24270.163992] BTRFS info (device dm-9): has skinny extents
[24270.202463] BTRFS info (device dm-9): checking UUID tree
[24270.388469] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (1477843)
[24270.389274] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1477843)
[24270.440057] BTRFS info (device dm-9): flagging fs with big metadata feature
[24270.440300] BTRFS info (device dm-9): use zlib compression, level 3
[24270.440569] BTRFS info (device dm-9): using free space tree
[24270.440776] BTRFS info (device dm-9): has skinny extents
[24270.443159] BTRFS info (device dm-9): start tree-log replay
[24270.675189] BTRFS: device fsid 28cab3f8-e742-4b71-b8a9-19bc3eea259a devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (1478811)
[24270.762248] BTRFS info (device dm-9): flagging fs with big metadata feature
[24270.762474] BTRFS info (device dm-9): use zlib compression, level 3
[24270.762655] BTRFS info (device dm-9): using free space tree
[24270.762851] BTRFS info (device dm-9): has skinny extents
[24270.764775] BTRFS info (device dm-9): checking UUID tree
[24271.001797] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (1477843)
[24271.002617] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1477843)
[24271.049983] BTRFS info (device dm-9): flagging fs with big metadata feature
[24271.050217] BTRFS info (device dm-9): use zlib compression, level 3
[24271.050410] BTRFS info (device dm-9): using free space tree
[24271.050581] BTRFS info (device dm-9): has skinny extents
[24271.052443] BTRFS info (device dm-9): start tree-log replay
[24271.160136] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[24271.160450] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[24271.160697] preempt_count: 100, expected: 0
[24271.160840] RCU nest depth: 0, expected: 0
[24271.160986] INFO: lockdep is turned off.
[24271.161142] Preemption disabled at:
[24271.161143] [] __do_softirq+0x5e/0x517
[24271.161449] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G        W         5.17.0+ #639
[24271.161692] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[24271.161946] Call Trace:
[24271.162065]  
[24271.162178]  dump_stack_lvl+0x56/0x6f
[24271.162320]  __might_resched.cold+0xff/0x13a
[24271.162482]  blk_release_queue+0x24/0x120
[24271.162627]  kobject_put+0x7c/0x1d0
[24271.162761]  ? rcu_do_batch+0x1ac/0x570
[24271.162905]  blkg_free.part.0+0x41/0x60
[24271.163045]  rcu_do_batch+0x1e1/0x570
[24271.163182]  ? rcu_do_batch+0x1ac/0x570
[24271.163331]  rcu_core+0x2cb/0x460
[24271.163469]  __do_softirq+0x178/0x517
[24271.163607]  ? smpboot_thread_fn+0x2c/0x270
[24271.163758]  run_ksoftirqd+0x43/0x70
[24271.163895]  smpboot_thread_fn+0x1c1/0x270
[24271.164039]  ? sort_range+0x20/0x20
[24271.164172]  kthread+0xf0/0x120
[24271.164301]  ? kthread_complete_and_exit+0x20/0x20
[24271.165383]  ret_from_fork+0x1f/0x30
[24271.165524]