[32572.966727] run fstests btrfs/233 at 2022-04-04 05:53:46
[32573.180858] BTRFS info (device dm-0): flagging fs with big metadata feature
[32573.181300] BTRFS info (device dm-0): using free space tree
[32573.181635] BTRFS info (device dm-0): has skinny extents
[32574.118477] BTRFS: device fsid d8bbde2b-7f8d-428e-89e6-478eb8235e16 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3218106)
[32574.176933] BTRFS info (device dm-11): flagging fs with big metadata feature
[32574.177386] BTRFS info (device dm-11): using free space tree
[32574.177727] BTRFS info (device dm-11): has skinny extents
[32574.179453] BTRFS info (device dm-11): checking UUID tree
[32574.792394] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3217875)
[32574.793526] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3217875)
[32574.849606] BTRFS info (device dm-11): flagging fs with big metadata feature
[32574.850062] BTRFS info (device dm-11): using free space tree
[32574.850429] BTRFS info (device dm-11): has skinny extents
[32577.332055] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[32577.332717] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3218203, name: mkfs.btrfs
[32577.333377] preempt_count: 100, expected: 0
[32577.333713] RCU nest depth: 1, expected: 0
[32577.334000] INFO: lockdep is turned off.
[32577.334275] Preemption disabled at:
[32577.334276] [] __do_softirq+0x5e/0x517
[32577.334867] CPU: 0 PID: 3218203 Comm: mkfs.btrfs Tainted: G        W         5.17.0+ #641
[32577.335376] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[32577.335875] Call Trace:
[32577.336027]  
[32577.336154]  dump_stack_lvl+0x56/0x6f
[32577.336377]  __might_resched.cold+0xff/0x13a
[32577.336635]  blk_release_queue+0x24/0x120
[32577.336877]  kobject_put+0x7c/0x1d0
[32577.337090]  ? rcu_do_batch+0x1ac/0x570
[32577.337320]  blkg_free.part.0+0x41/0x60
[32577.337553]  rcu_do_batch+0x1e1/0x570
[32577.337772]  ? rcu_do_batch+0x1ac/0x570
[32577.338003]  rcu_core+0x2cb/0x460
[32577.338203]  __do_softirq+0x178/0x517
[32577.338427]  __irq_exit_rcu+0xe3/0x160
[32577.338654]  irq_exit_rcu+0xa/0x20
[32577.338858]  sysvec_apic_timer_interrupt+0xa2/0xd0
[32577.339143]  
[32577.339272]  
[32577.339403]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[32577.339709] RIP: 0010:lock_is_held_type+0x111/0x140
[32577.339997] Code: 65 0f c1 05 59 d7 1c 56 83 f8 01 75 27 48 f7 04 24 00 02 00 00 74 06 fb 0f 1f 44 00 00 48 83 c4 08 44 89 e8 5b 5d 41 5c 41 5d <41> 5e 41 5f c3 45 31 ed eb bb 0f 0b 48 c7 c7 df b2 90 aa e8 67 0c
[32577.341077] RSP: 0018:ffffa284464dbb18 EFLAGS: 00000282
[32577.341384] RAX: 00000000ffffffff RBX: ffffa284464dbb68 RCX: 0000000000000000
[32577.341803] RDX: ffffffffa982fdc0 RSI: 00000000ffffffff RDI: ffff94fc43826ba8
[32577.342219] RBP: 0000000000000003 R08: 0000000000000000 R09: 0000000000000000
[32577.342636] R10: 0000000000000000 R11: 0000000000000000 R12: ffff94fc9e4f6002
[32577.343051] R13: 0000000000000cc0 R14: ffffa284464dbc30 R15: 0000000000000000
[32577.343467]  ? xa_load+0x140/0x140
[32577.343678]  xas_start+0x3b/0x260
[32577.343879]  xas_load+0xa/0x50
[32577.344064]  xa_get_order+0x76/0x180
[32577.344280]  __filemap_add_folio+0xe5/0x6a0
[32577.344532]  ? mod_lruvec_state+0x60/0x60
[32577.344773]  filemap_add_folio+0x27/0x80
[32577.345007]  __filemap_get_folio+0x1ac/0x4d0
[32577.345262]  ? blkdev_llseek+0x60/0x60
[32577.345486]  pagecache_get_page+0x15/0x90
[32577.345730]  block_write_begin+0x24/0x130
[32577.345971]  generic_perform_write+0xa9/0x1d0
[32577.346232]  __generic_file_write_iter+0xdb/0x1a0
[32577.346513]  blkdev_write_iter+0xc1/0x150
[32577.346753]  new_sync_write+0xf6/0x160
[32577.346980]  vfs_write+0x290/0x390
[32577.347185]  __x64_sys_pwrite64+0x6f/0xb0
[32577.347425]  do_syscall_64+0x38/0x90
[32577.347642]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[32577.347941] RIP: 0033:0x7f515bcc219a
[32577.348156] 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
[32577.349233] RSP: 002b:00007fff428b7d18 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[32577.349677] RAX: ffffffffffffffda RBX: 00007f515b8bf010 RCX: 00007f515bcc219a
[32577.350091] RDX: 0000000000200000 RSI: 00007f515b8bf010 RDI: 0000000000000003
[32577.350510] RBP: 0000000000200000 R08: 00007f515b8bf010 R09: 0000000000000000
[32577.350925] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[32577.351339] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[32577.351759]  
[32578.350872] BTRFS: device fsid fd50f65e-4c24-42f9-bc2e-cc280d21a858 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3218203)
[32578.412014] BTRFS info (device dm-11): flagging fs with big metadata feature
[32578.412472] BTRFS info (device dm-11): using free space tree
[32578.412813] BTRFS info (device dm-11): has skinny extents
[32578.414780] BTRFS info (device dm-11): checking UUID tree
[32579.010446] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3217875)
[32579.012106] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3217875)
[32579.071151] BTRFS info (device dm-11): flagging fs with big metadata feature
[32579.071607] BTRFS info (device dm-11): using free space tree
[32579.071954] BTRFS info (device dm-11): has skinny extents
[32579.086992] BTRFS info (device dm-11): flagging fs with big metadata feature
[32579.087453] BTRFS info (device dm-11): using free space tree
[32579.087817] BTRFS info (device dm-11): has skinny extents
[32579.092628] BTRFS info (device dm-11: state M): using free space tree
[32579.157056] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[32579.157603] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[32579.158143] preempt_count: 101, expected: 0
[32579.158425] RCU nest depth: 0, expected: 0
[32579.158711] INFO: lockdep is turned off.
[32579.158969] Preemption disabled at:
[32579.158970] [] schedule_preempt_disabled+0x1e/0x20
[32579.159662] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #641
[32579.160173] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[32579.160738] Call Trace:
[32579.160918]  
[32579.161068]  dump_stack_lvl+0x56/0x6f
[32579.161322]  __might_resched.cold+0xff/0x13a
[32579.161610]  blk_release_queue+0x24/0x120
[32579.161900]  kobject_put+0x7c/0x1d0
[32579.162159]  ? rcu_do_batch+0x1ac/0x570
[32579.162420]  blkg_free.part.0+0x41/0x60
[32579.162686]  rcu_do_batch+0x1e1/0x570
[32579.162954]  ? rcu_do_batch+0x1ac/0x570
[32579.163216]  rcu_core+0x2cb/0x460
[32579.163441]  __do_softirq+0x178/0x517
[32579.163700]  __irq_exit_rcu+0xe3/0x160
[32579.163966]  irq_exit_rcu+0xa/0x20
[32579.164184]  sysvec_apic_timer_interrupt+0xa2/0xd0
[32579.164515]  
[32579.164668]  
[32579.164815]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[32579.165161] RIP: 0010:native_safe_halt+0xb/0x10
[32579.165462] Code: 56 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
[32579.166683] RSP: 0018:ffffffffaaa03ea8 EFLAGS: 00000246
[32579.167039] RAX: ffffffffa9e64a80 RBX: ffffffffaaa30a00 RCX: 0000000000000000
[32579.167512] RDX: ffffffffaaa30a00 RSI: ffffffffaa87e4d9 RDI: ffffffffa9e64bf6
[32579.167984] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[32579.168452] R10: 0000000000000000 R11: ffff94fcbbc20c88 R12: 0000000000000000
[32579.168918] R13: 0000000000000000 R14: ffffffffaaa30118 R15: 0000000000000073
[32579.169379]  ? mwait_idle+0x80/0x80
[32579.169630]  ? default_idle_call+0x26/0xa0
[32579.169900]  default_idle+0xa/0x10
[32579.170127]  default_idle_call+0x5e/0xa0
[32579.170392]  do_idle+0x1e9/0x240
[32579.170608]  cpu_startup_entry+0x19/0x20
[32579.170883]  start_kernel+0x98c/0x99e
[32579.171106]  ? copy_bootdata+0x18/0x55
[32579.171335]  secondary_startup_64_no_verify+0xd5/0xdb
[32579.171643]