[19508.359451] run fstests btrfs/201 at 2022-04-04 02:21:36
[19508.831561] BTRFS: device fsid 6294b904-ccc3-4c54-b553-c5f8f26ee8c0 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3373115)
[19508.903956] BTRFS info (device dm-9): flagging fs with big metadata feature
[19508.904279] BTRFS info (device dm-9): use zlib compression, level 3
[19508.904487] BTRFS info (device dm-9): using free space tree
[19508.904680] BTRFS info (device dm-9): has skinny extents
[19508.907207] BTRFS info (device dm-9): checking UUID tree
[19509.157488] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3372244)
[19509.158267] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3372244)
[19509.218533] BTRFS info (device dm-9): flagging fs with big metadata feature
[19509.218765] BTRFS info (device dm-9): use zlib compression, level 3
[19509.218979] BTRFS info (device dm-9): using free space tree
[19509.219152] BTRFS info (device dm-9): has skinny extents
[19509.221149] BTRFS info (device dm-9): start tree-log replay
[19509.318793] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[19509.319074] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3372244, name: systemd-udevd
[19509.319332] preempt_count: 100, expected: 0
[19509.319481] RCU nest depth: 0, expected: 0
[19509.319625] INFO: lockdep is turned off.
[19509.319769] Preemption disabled at:
[19509.319770] [] __do_softirq+0x5e/0x517
[19509.320096] CPU: 0 PID: 3372244 Comm: systemd-udevd Tainted: G        W         5.17.0+ #641
[19509.320348] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[19509.320607] Call Trace:
[19509.320726]  
[19509.320845]  dump_stack_lvl+0x56/0x6f
[19509.320986]  __might_resched.cold+0xff/0x13a
[19509.321135]  blk_release_queue+0x24/0x120
[19509.321283]  kobject_put+0x7c/0x1d0
[19509.321425]  ? rcu_do_batch+0x1ac/0x570
[19509.321567]  blkg_free.part.0+0x41/0x60
[19509.321711]  rcu_do_batch+0x1e1/0x570
[19509.321859]  ? rcu_do_batch+0x1ac/0x570
[19509.322003]  rcu_core+0x2cb/0x460
[19509.322137]  __do_softirq+0x178/0x517
[19509.322281]  __irq_exit_rcu+0xe3/0x160
[19509.322426]  irq_exit_rcu+0xa/0x20
[19509.322562]  sysvec_apic_timer_interrupt+0xa2/0xd0
[19509.322725]  
[19509.322846]  
[19509.322959]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[19509.323131] RIP: 0010:submit_bio_noacct+0x164/0x5e0
[19509.323298] Code: 01 48 8b 84 d0 b8 00 00 00 80 7d 14 00 79 0d f6 80 78 01 00 00 04 0f 84 7f 01 00 00 8b 55 10 83 e2 01 80 bc 10 7c 01 00 00 00 <0f> 84 6b 01 00 00 48 89 ef e8 6e e1 02 00 84 c0 0f 84 5b 01 00 00
[19509.323766] RSP: 0018:ffffb94a027a7a38 EFLAGS: 00000246
[19509.323950] RAX: ffff96345661c800 RBX: ffff96344052c200 RCX: 00000000013ffff8
[19509.324145] RDX: 0000000000000000 RSI: 00000000ffffffff RDI: ffff963478e2a500
[19509.324344] RBP: ffff963478e2a500 R08: 00000000ffffefff R09: 0000000000000000
[19509.324539] R10: 000000000027fff1 R11: 0000000000000000 R12: ffff963445654200
[19509.324737] R13: ffff96344052c200 R14: 0000000001400000 R15: ffffdfe604f5d140
[19509.324942]  ? submit_bio_noacct+0xb3/0x5e0
[19509.325094]  mpage_readahead+0x171/0x200
[19509.325241]  ? blkdev_llseek+0x60/0x60
[19509.325382]  read_pages+0x60/0x3d0
[19509.325521]  page_cache_ra_unbounded+0x1a4/0x1f0
[19509.325683]  force_page_cache_ra+0xc5/0x100
[19509.325834]  filemap_get_pages+0xca/0x740
[19509.325980]  ? rcu_read_lock_sched_held+0x12/0x60
[19509.326139]  ? lock_acquire+0x176/0x2d0
[19509.326286]  filemap_read+0xbe/0x340
[19509.326422]  ? rcu_read_lock_sched_held+0x12/0x60
[19509.326580]  ? lock_release+0x1ed/0x2c0
[19509.326725]  ? _raw_spin_unlock+0x29/0x40
[19509.326874]  ? wp_page_reuse+0x60/0x70
[19509.327015]  ? do_wp_page+0x184/0x3c0
[19509.327157]  ? __handle_mm_fault+0xba9/0x1460
[19509.327312]  blkdev_read_iter+0xac/0x1c0
[19509.327459]  new_sync_read+0xf3/0x160
[19509.327600]  vfs_read+0x15f/0x1b0
[19509.327739]  ksys_read+0x55/0xd0
[19509.327914]  do_syscall_64+0x38/0x90
[19509.328054]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[19509.328223] RIP: 0033:0x7fa5351f92e2
[19509.328365] Code: c0 e9 b2 fe ff ff 50 48 8d 3d ea 2e 0a 00 e8 95 e9 01 00 0f 1f 44 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 0f 05 <48> 3d 00 f0 ff ff 77 56 c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24
[19509.328872] RSP: 002b:00007ffe36da85c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[19509.329120] RAX: ffffffffffffffda RBX: 0000557484352c58 RCX: 00007fa5351f92e2
[19509.329321] RDX: 0000000000000040 RSI: 0000557484352c68 RDI: 0000000000000006
[19509.329516] RBP: 00005574843667a0 R08: 0000557484352c40 R09: 00007fa5352cba60
[19509.329714] R10: 00007fa5352cc090 R11: 0000000000000246 R12: 000000027fff0000
[19509.329955] R13: 0000000000000040 R14: 0000557484352c40 R15: 00005574843667f0
[19509.330159]  
[19509.515999] BTRFS: device fsid f09070b1-f96a-48e1-bc28-b8ca839188b8 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3373230)
[19509.583350] BTRFS info (device dm-9): flagging fs with big metadata feature
[19509.583663] BTRFS info (device dm-9): use zlib compression, level 3
[19509.583960] BTRFS info (device dm-9): using free space tree
[19509.584135] BTRFS info (device dm-9): has skinny extents
[19509.586130] BTRFS info (device dm-9): checking UUID tree
[19515.426101] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3377284)
[19515.427371] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3377284)
[19515.522109] BTRFS info (device dm-9): flagging fs with big metadata feature
[19515.522328] BTRFS info (device dm-9): use zlib compression, level 3
[19515.522512] BTRFS info (device dm-9): using free space tree
[19515.522685] BTRFS info (device dm-9): has skinny extents
[19515.525469] BTRFS info (device dm-9): start tree-log replay
[19515.965756] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[19515.966038] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3377326, name: mkfs.btrfs
[19515.966296] preempt_count: 101, expected: 0
[19515.966443] RCU nest depth: 0, expected: 0
[19515.966587] INFO: lockdep is turned off.
[19515.966744] Preemption disabled at:
[19515.966745] [<0000000000000000>] 0x0
[19515.967835] CPU: 1 PID: 3377326 Comm: mkfs.btrfs Tainted: G        W         5.17.0+ #641
[19515.968108] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[19515.968389] Call Trace:
[19515.968514]  
[19515.968632]  dump_stack_lvl+0x56/0x6f
[19515.968834]  __might_resched.cold+0xff/0x13a
[19515.969185]  blk_release_queue+0x24/0x120
[19515.969383]  kobject_put+0x7c/0x1d0
[19515.969527]  ? rcu_do_batch+0x1ac/0x570
[19515.969684]  blkg_free.part.0+0x41/0x60
[19515.969852]  rcu_do_batch+0x1e1/0x570
[19515.970004]  ? rcu_do_batch+0x1ac/0x570
[19515.970161]  rcu_core+0x2cb/0x460
[19515.970313]  __do_softirq+0x178/0x517
[19515.970487]  __irq_exit_rcu+0xe3/0x160
[19515.970697]  irq_exit_rcu+0xa/0x20
[19515.970893]  sysvec_apic_timer_interrupt+0xa2/0xd0
[19515.971079]  
[19515.971239]  
[19515.971391]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[19515.971597] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[19515.971837] 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 5d 85 c0 74 03 5b
[19515.972332] RSP: 0018:ffffb94a03b5fd38 EFLAGS: 00000246
[19515.972500] RAX: 0000000080000001 RBX: 0000000000000246 RCX: 00000000ffffffff
[19515.972739] RDX: ffffdfe6043dd048 RSI: ffffffffa2369921 RDI: ffffffffa2e651fd
[19515.972946] RBP: ffff963486a4f850 R08: 0000000000000000 R09: 0000000000000000
[19515.973192] R10: 0000000000000001 R11: 0000000000000000 R12: ffff963476bddfe0
[19515.973388] R13: ffffdfe605e85208 R14: 0000000000000020 R15: 0000000000000000
[19515.973584]  ? release_pages+0x421/0x6b0
[19515.973728]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[19515.979527]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[19515.979692]  release_pages+0x421/0x6b0
[19515.979842]  tlb_finish_mmu+0x7a/0x1a0
[19515.979986]  unmap_region+0xbc/0xe0
[19515.980127]  __do_munmap+0x1fe/0x4c0
[19515.980266]  __vm_munmap+0x68/0xf0
[19515.980401]  __x64_sys_munmap+0x17/0x20
[19515.980545]  do_syscall_64+0x38/0x90
[19515.980682]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[19515.980849] RIP: 0033:0x7fe67f23704b
[19515.981012] Code: 8b 15 29 7e 0c 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb 89 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa b8 0b 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d f5 7d 0c 00 f7 d8 64 89 01 48
[19515.981516] RSP: 002b:00007ffc70fc1e28 EFLAGS: 00000206 ORIG_RAX: 000000000000000b
[19515.981774] RAX: ffffffffffffffda RBX: fffffffffffffdf0 RCX: 00007fe67f23704b
[19515.981983] RDX: 0000000000000000 RSI: 0000000000201000 RDI: 00007fe67ef1b000
[19515.982194] RBP: 0000000000000002 R08: 00007fe67ef1b000 R09: 0000000000000000
[19515.982399] R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000000
[19515.982598] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[19515.982800]  
[19516.120230] BTRFS: device fsid f57bd709-3267-4085-92c6-c9ffbfe15e63 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3377326)
[19516.183288] BTRFS info (device dm-9): flagging fs with big metadata feature
[19516.183528] BTRFS info (device dm-9): use zlib compression, level 3
[19516.183730] BTRFS info (device dm-9): using free space tree
[19516.183912] BTRFS info (device dm-9): has skinny extents
[19516.186352] BTRFS info (device dm-9): checking UUID tree
[19522.725770] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3381380)
[19522.726790] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3381380)
[19522.818696] BTRFS info (device dm-9): flagging fs with big metadata feature
[19522.818923] BTRFS info (device dm-9): use zlib compression, level 3
[19522.819105] BTRFS info (device dm-9): using free space tree
[19522.819274] BTRFS info (device dm-9): has skinny extents
[19522.822023] BTRFS info (device dm-9): start tree-log replay
[19523.282674] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[19523.283120] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[19523.283484] preempt_count: 101, expected: 0
[19523.283733] RCU nest depth: 0, expected: 0
[19523.283944] INFO: lockdep is turned off.
[19523.284154] Preemption disabled at:
[19523.284155] [] schedule_preempt_disabled+0x1e/0x20
[19523.284694] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #641
[19523.285052] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[19523.285430] Call Trace:
[19523.285606]  
[19523.285786]  dump_stack_lvl+0x56/0x6f
[19523.286020]  __might_resched.cold+0xff/0x13a
[19523.286234]  blk_release_queue+0x24/0x120
[19523.286498]  kobject_put+0x7c/0x1d0
[19523.287444]  ? rcu_do_batch+0x1ac/0x570
[19523.287708]  blkg_free.part.0+0x41/0x60
[19523.287999]  rcu_do_batch+0x1e1/0x570
[19523.288257]  ? rcu_do_batch+0x1ac/0x570
[19523.288482]  rcu_core+0x2cb/0x460
[19523.288685]  __do_softirq+0x178/0x517
[19523.288947]  __irq_exit_rcu+0xe3/0x160
[19523.289166]  irq_exit_rcu+0xa/0x20
[19523.289371]  sysvec_apic_timer_interrupt+0xa2/0xd0
[19523.289609]  
[19523.289803]  
[19523.289973]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[19523.290208] RIP: 0010:native_safe_halt+0xb/0x10
[19523.290482] Code: 5d 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
[19523.291364] RSP: 0018:ffffffffa3a03ea8 EFLAGS: 00000246
[19523.291606] RAX: ffffffffa2e64a80 RBX: ffffffffa3a30a00 RCX: 0000000000000000
[19523.291894] RDX: ffffffffa3a30a00 RSI: ffffffffa387e4d9 RDI: ffffffffa2e64bf6
[19523.292187] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[19523.292489] R10: 0000000000000000 R11: ffff9634bbc20c88 R12: 0000000000000000
[19523.292783] R13: 0000000000000000 R14: ffffffffa3a30118 R15: 000000000000007b
[19523.293073]  ? mwait_idle+0x80/0x80
[19523.293285]  ? default_idle_call+0x26/0xa0
[19523.293495]  default_idle+0xa/0x10
[19523.293687]  default_idle_call+0x5e/0xa0
[19523.293908]  do_idle+0x1e9/0x240
[19523.294107]  cpu_startup_entry+0x19/0x20
[19523.294314]  start_kernel+0x98c/0x99e
[19523.294523]  ? copy_bootdata+0x18/0x55
[19523.294738]  secondary_startup_64_no_verify+0xd5/0xdb
[19523.294980]  
[19523.496166] BTRFS: device fsid 5e015366-d44c-4e26-87a4-d5952e20fe94 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3381421)
[19523.579736] BTRFS info (device dm-9): flagging fs with big metadata feature
[19523.580041] BTRFS info (device dm-9): use zlib compression, level 3
[19523.580311] BTRFS info (device dm-9): using free space tree
[19523.580558] BTRFS info (device dm-9): has skinny extents
[19523.583061] BTRFS info (device dm-9): checking UUID tree
[19530.031478] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3385477)
[19530.032404] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3385477)
[19530.126986] BTRFS info (device dm-9): flagging fs with big metadata feature
[19530.127202] BTRFS info (device dm-9): use zlib compression, level 3
[19530.127385] BTRFS info (device dm-9): using free space tree
[19530.127559] BTRFS info (device dm-9): has skinny extents
[19530.130252] BTRFS info (device dm-9): start tree-log replay
[19530.508611] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[19530.508890] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[19530.509144] preempt_count: 100, expected: 0
[19530.509288] RCU nest depth: 0, expected: 0
[19530.509433] INFO: lockdep is turned off.
[19530.509596] Preemption disabled at:
[19530.509597] [] __do_softirq+0x5e/0x517
[19530.509909] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G        W         5.17.0+ #641
[19530.510152] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[19530.510410] Call Trace:
[19530.510527]  
[19530.510649]  dump_stack_lvl+0x56/0x6f
[19530.510790]  __might_resched.cold+0xff/0x13a
[19530.510943]  blk_release_queue+0x24/0x120
[19530.511092]  kobject_put+0x7c/0x1d0
[19530.511229]  ? rcu_do_batch+0x1ac/0x570
[19530.511372]  blkg_free.part.0+0x41/0x60
[19530.511518]  rcu_do_batch+0x1e1/0x570
[19530.511665]  ? rcu_do_batch+0x1ac/0x570
[19530.511810]  rcu_core+0x2cb/0x460
[19530.511949]  __do_softirq+0x178/0x517
[19530.512094]  ? smpboot_thread_fn+0x2c/0x270
[19530.512249]  run_ksoftirqd+0x43/0x70
[19530.512387]  smpboot_thread_fn+0x1c1/0x270
[19530.512538]  ? sort_range+0x20/0x20
[19530.512726]  kthread+0xf0/0x120
[19530.512857]  ? kthread_complete_and_exit+0x20/0x20
[19530.513020]  ret_from_fork+0x1f/0x30
[19530.513165]