[16121.739941] run fstests btrfs/201 at 2022-04-02 01:19:12
[16122.092171] BTRFS: device fsid 0b715919-e61a-4eab-9bb1-3e636f78b207 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3389920)
[16122.159695] BTRFS info (device dm-9): flagging fs with big metadata feature
[16122.160013] BTRFS info (device dm-9): use zlib compression, level 3
[16122.160231] BTRFS info (device dm-9): using free space tree
[16122.160404] BTRFS info (device dm-9): has skinny extents
[16122.163212] BTRFS info (device dm-9): checking UUID tree
[16122.363873] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3389051)
[16122.364941] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3389051)
[16122.416659] BTRFS info (device dm-9): flagging fs with big metadata feature
[16122.416883] BTRFS info (device dm-9): use zlib compression, level 3
[16122.417080] BTRFS info (device dm-9): using free space tree
[16122.417246] BTRFS info (device dm-9): has skinny extents
[16122.419134] BTRFS info (device dm-9): start tree-log replay
[16122.476576] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16122.482738] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 20, name: ksoftirqd/1
[16122.483061] preempt_count: 100, expected: 0
[16122.483256] RCU nest depth: 0, expected: 0
[16122.483421] INFO: lockdep is turned off.
[16122.483597] Preemption disabled at:
[16122.483598] [] __do_softirq+0x5e/0x517
[16122.483920] CPU: 1 PID: 20 Comm: ksoftirqd/1 Tainted: G W 5.17.0+ #639
[16122.484165] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16122.484411] Call Trace:
[16122.484532]
[16122.484645] dump_stack_lvl+0x56/0x6f
[16122.484787] __might_resched.cold+0xff/0x13a
[16122.484939] blk_release_queue+0x24/0x120
[16122.485084] kobject_put+0x7c/0x1d0
[16122.485218] ? rcu_do_batch+0x1ac/0x570
[16122.485360] blkg_free.part.0+0x41/0x60
[16122.485534] rcu_do_batch+0x1e1/0x570
[16122.485745] ? rcu_do_batch+0x1ac/0x570
[16122.486017] rcu_core+0x2cb/0x460
[16122.486220] __do_softirq+0x178/0x517
[16122.486441] ? smpboot_thread_fn+0x2c/0x270
[16122.486606] run_ksoftirqd+0x43/0x70
[16122.486805] smpboot_thread_fn+0x1c1/0x270
[16122.486965] ? sort_range+0x20/0x20
[16122.487102] kthread+0xf0/0x120
[16122.487288] ? kthread_complete_and_exit+0x20/0x20
[16122.487470] ret_from_fork+0x1f/0x30
[16122.487641]
[16123.696525] BTRFS: device fsid d3b5dd84-2edf-4f41-9dcb-f901468e3c5c devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3390034)
[16123.762331] BTRFS info (device dm-9): flagging fs with big metadata feature
[16123.762563] BTRFS info (device dm-9): use zlib compression, level 3
[16123.762754] BTRFS info (device dm-9): using free space tree
[16123.762921] BTRFS info (device dm-9): has skinny extents
[16123.765273] BTRFS info (device dm-9): checking UUID tree
[16129.440629] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3394088)
[16129.441537] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3394088)
[16129.531566] BTRFS info (device dm-9): flagging fs with big metadata feature
[16129.531791] BTRFS info (device dm-9): use zlib compression, level 3
[16129.531971] BTRFS info (device dm-9): using free space tree
[16129.532143] BTRFS info (device dm-9): has skinny extents
[16129.534736] BTRFS info (device dm-9): start tree-log replay
[16129.908485] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16129.908921] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3394130, name: mkfs.btrfs
[16129.909189] preempt_count: 100, expected: 0
[16129.909344] RCU nest depth: 1, expected: 0
[16129.909510] INFO: lockdep is turned off.
[16129.909660] Preemption disabled at:
[16129.909661] [] __do_softirq+0x5e/0x517
[16129.910061] CPU: 0 PID: 3394130 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #639
[16129.910315] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16129.910571] Call Trace:
[16129.910698]
[16129.910811] dump_stack_lvl+0x56/0x6f
[16129.910949] __might_resched.cold+0xff/0x13a
[16129.911100] blk_release_queue+0x24/0x120
[16129.911247] kobject_put+0x7c/0x1d0
[16129.911381] ? rcu_do_batch+0x1ac/0x570
[16129.911521] blkg_free.part.0+0x41/0x60
[16129.911672] rcu_do_batch+0x1e1/0x570
[16129.911811] ? rcu_do_batch+0x1ac/0x570
[16129.911950] rcu_core+0x2cb/0x460
[16129.912084] __do_softirq+0x178/0x517
[16129.912226] __irq_exit_rcu+0xe3/0x160
[16129.912365] irq_exit_rcu+0xa/0x20
[16129.912496] sysvec_apic_timer_interrupt+0xa2/0xd0
[16129.912662]
[16129.912776]
[16129.912887] asm_sysvec_apic_timer_interrupt+0x12/0x20
[16129.913053] RIP: 0010:folio_memcg_lock+0x148/0x230
[16129.913212] Code: a3 00 49 8b 46 38 a8 01 0f 84 2b ff ff ff 48 c7 c6 68 86 83 93 4c 89 f7 e8 f5 e7 f6 ff 0f 0b e8 1e 07 e8 ff fb 0f 1f 44 00 00 85 e8 91 04 e8 ff e9 43 ff ff ff 65 48 8b 04 25 c0 fe 01 00 48
[16129.913662] RSP: 0018:ffffa59282903c68 EFLAGS: 00000246
[16129.913829] RAX: 0000000080000000 RBX: ffff936082c68000 RCX: 0000000000000000
[16129.914025] RDX: 0000000000000000 RSI: ffffffff9242ba87 RDI: ffffffff9242bb32
[16129.914221] RBP: ffffa59282903c88 R08: 0000000000000000 R09: 0000000000000000
[16129.914420] R10: ffff9360ae8c5a80 R11: 0000000000000000 R12: 0000000000000200
[16129.914627] R13: ffff936082c68688 R14: fffff95dc45563c0 R15: ffff9360ae8c5a80
[16129.914836] ? folio_memcg_lock+0x97/0x230
[16129.914982] ? folio_memcg_lock+0x142/0x230
[16129.915126] ? folio_memcg_lock+0x142/0x230
[16129.915276] mark_buffer_dirty+0x9b/0x1d0
[16129.915424] __block_commit_write.constprop.0.isra.0+0x51/0xb0
[16129.915598] block_write_end+0x30/0xb0
[16129.915742] ? blkdev_llseek+0x60/0x60
[16129.915884] blkdev_write_end+0x14/0xb0
[16129.916028] generic_perform_write+0xf7/0x1d0
[16129.916179] __generic_file_write_iter+0xdb/0x1a0
[16129.916334] blkdev_write_iter+0xc1/0x150
[16129.916480] new_sync_write+0xf6/0x160
[16129.916632] vfs_write+0x290/0x390
[16129.916766] __x64_sys_pwrite64+0x6f/0xb0
[16129.916910] do_syscall_64+0x38/0x90
[16129.917052] entry_SYSCALL_64_after_hwframe+0x44/0xae
[16129.917210] RIP: 0033:0x7f2b7495525a
[16129.917346] 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
[16129.917805] RSP: 002b:00007ffd6bebce28 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[16129.918040] RAX: ffffffffffffffda RBX: 0000000000de0c70 RCX: 00007f2b7495525a
[16129.918230] RDX: 0000000000200000 RSI: 0000000000de0c70 RDI: 0000000000000003
[16129.918425] RBP: 0000000000200000 R08: 0000000000de0c70 R09: 00007f2b74936a60
[16129.918623] R10: 000000027fe00000 R11: 0000000000000246 R12: 0000000000000000
[16129.918818] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[16129.919012]
[16130.004503] BTRFS: device fsid 827ea7c5-e775-4bfb-9576-ab358c98bf37 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3394130)
[16130.061516] BTRFS info (device dm-9): flagging fs with big metadata feature
[16130.061738] BTRFS info (device dm-9): use zlib compression, level 3
[16130.061919] BTRFS info (device dm-9): using free space tree
[16130.062091] BTRFS info (device dm-9): has skinny extents
[16130.064359] BTRFS info (device dm-9): checking UUID tree
[16135.650467] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3398184)
[16135.651338] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3398184)
[16135.740834] BTRFS info (device dm-9): flagging fs with big metadata feature
[16135.741051] BTRFS info (device dm-9): use zlib compression, level 3
[16135.741240] BTRFS info (device dm-9): using free space tree
[16135.741410] BTRFS info (device dm-9): has skinny extents
[16135.743815] BTRFS info (device dm-9): start tree-log replay
[16136.122431] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16136.122749] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3398227, name: dmsetup
[16136.122991] preempt_count: 100, expected: 0
[16136.123133] RCU nest depth: 0, expected: 0
[16136.123278] INFO: lockdep is turned off.
[16136.123426] Preemption disabled at:
[16136.123427] [] __do_softirq+0x5e/0x517
[16136.123729] CPU: 0 PID: 3398227 Comm: dmsetup Tainted: G W 5.17.0+ #639
[16136.123969] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16136.124214] Call Trace:
[16136.124330]
[16136.124441] dump_stack_lvl+0x56/0x6f
[16136.124603] __might_resched.cold+0xff/0x13a
[16136.124750] blk_release_queue+0x24/0x120
[16136.124891] kobject_put+0x7c/0x1d0
[16136.125026] ? rcu_do_batch+0x1ac/0x570
[16136.125168] blkg_free.part.0+0x41/0x60
[16136.125305] rcu_do_batch+0x1e1/0x570
[16136.125441] ? rcu_do_batch+0x1ac/0x570
[16136.125586] rcu_core+0x2cb/0x460
[16136.125717] __do_softirq+0x178/0x517
[16136.125855] __irq_exit_rcu+0xe3/0x160
[16136.126097] irq_exit_rcu+0xa/0x20
[16136.126332] sysvec_apic_timer_interrupt+0x54/0xd0
[16136.126522] ? asm_sysvec_apic_timer_interrupt+0xa/0x20
[16136.126687] asm_sysvec_apic_timer_interrupt+0x12/0x20
[16136.126884] RIP: 0033:0x7fa6ec6a67ff
[16136.127070] Code: 00 01 00 45 85 d2 74 0b f6 c3 20 74 06 81 ce 00 04 00 00 f6 c7 01 74 06 81 ce 00 00 02 00 45 85 db 75 55 b8 00 00 00 80 0f a2 <3d> 00 00 00 80 76 30 b8 01 00 00 80 0f a2 f6 c1 40 74 06 81 ce 00
[16136.127556] RSP: 002b:00007ffff8440068 EFLAGS: 00000246
[16136.127756] RAX: 0000000080000008 RBX: 00000000756e6547 RCX: 000000006c65746e
[16136.127949] RDX: 0000000049656e69 RSI: 00000000000f47ff RDI: 0000000000001000
[16136.128138] RBP: 00007fa6ec6bd1d0 R08: 0000000004000000 R09: 000000000000000d
[16136.128330] R10: 0000000000000001 R11: 0000000000000000 R12: 00007ffff8440108
[16136.128518] R13: 00007ffff8440140 R14: 00007fa6ec6bcdd0 R15: 0000000000000000
[16136.128717]
[16136.208936] BTRFS: device fsid c5fc134a-6f07-4583-a46a-19dd1e3fbd94 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (3398226)
[16136.272651] BTRFS info (device dm-9): flagging fs with big metadata feature
[16136.272876] BTRFS info (device dm-9): use zlib compression, level 3
[16136.273063] BTRFS info (device dm-9): using free space tree
[16136.273231] BTRFS info (device dm-9): has skinny extents
[16136.281252] BTRFS info (device dm-9): checking UUID tree
[16142.075115] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (3402280)
[16142.076143] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3402280)
[16142.169631] BTRFS info (device dm-9): flagging fs with big metadata feature
[16142.169853] BTRFS info (device dm-9): use zlib compression, level 3
[16142.170092] BTRFS info (device dm-9): using free space tree
[16142.170262] BTRFS info (device dm-9): has skinny extents
[16142.172976] BTRFS info (device dm-9): start tree-log replay
[16142.999402] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16142.999679] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3402322, name: udevadm
[16142.999931] preempt_count: 100, expected: 0
[16143.000077] RCU nest depth: 1, expected: 0
[16143.000220] INFO: lockdep is turned off.
[16143.000379] Preemption disabled at:
[16143.000380] [] __do_softirq+0x5e/0x517
[16143.000732] CPU: 0 PID: 3402322 Comm: udevadm Tainted: G W 5.17.0+ #639
[16143.000979] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16143.001230] Call Trace:
[16143.001355]
[16143.001471] dump_stack_lvl+0x56/0x6f
[16143.001658] __might_resched.cold+0xff/0x13a
[16143.001813] blk_release_queue+0x24/0x120
[16143.001966] kobject_put+0x7c/0x1d0
[16143.002104] ? rcu_do_batch+0x1ac/0x570
[16143.002248] blkg_free.part.0+0x41/0x60
[16143.002393] rcu_do_batch+0x1e1/0x570
[16143.002535] ? rcu_do_batch+0x1ac/0x570
[16143.002679] rcu_core+0x2cb/0x460
[16143.002818] __do_softirq+0x178/0x517
[16143.002963] __irq_exit_rcu+0xe3/0x160
[16143.003106] irq_exit_rcu+0xa/0x20
[16143.003245] sysvec_apic_timer_interrupt+0xa2/0xd0
[16143.003408]
[16143.003546]
[16143.003679] asm_sysvec_apic_timer_interrupt+0x12/0x20
[16143.003847] RIP: 0010:xas_start+0x3/0x260
[16143.003991] Code: 49 d3 ec 41 83 e4 3f 45 88 67 12 5b 5d 41 5c 41 5d 41 5e 41 5f c3 45 08 77 12 e9 00 ff ff ff 0f 1f 84 00 00 00 00 00 41 54 55 <53> 48 8b 6f 18 48 89 fb 48 89 e8 83 e0 03 0f 84 c4 00 00 00 48 83
[16143.004490] RSP: 0000:ffffa59282d0bcf8 EFLAGS: 00000246
[16143.004658] RAX: 0000000000000003 RBX: ffffa59282d0bd78 RCX: 0000000000000000
[16143.004897] RDX: 0000000000000000 RSI: 000000000000000f RDI: ffffa59282d0bd78
[16143.005094] RBP: ffffa59282d0bd78 R08: 0000000000000000 R09: 0000000000000000
[16143.005313] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000003
[16143.005574] R13: 0000000000000000 R14: 0000000000000000 R15: ffff935ff02ffd48
[16143.005774] ? rcu_read_lock_sched_held+0x12/0x60
[16143.005931] xas_load+0xa/0x50
[16143.006064] xas_find+0x25f/0x2b0
[16143.006199] filemap_map_pages+0xbb/0x960
[16143.006344] ? __mod_memcg_lruvec_state+0x99/0x110
[16143.006548] __handle_mm_fault+0xd2b/0x1460
[16143.006702] handle_mm_fault+0x11e/0x3a0
[16143.006847] do_user_addr_fault+0x1e9/0x6b0
[16143.007080] exc_page_fault+0x78/0x280
[16143.007220] ? asm_exc_page_fault+0x8/0x30
[16143.007373] asm_exc_page_fault+0x1e/0x30
[16143.007565] RIP: 0033:0x7f929fbb222d
[16143.007787] Code: 00 0f 1f 40 00 3d 53 e5 74 64 75 11 8b b5 2c ff ff ff 4c 89 f2 4c 89 ff e8 60 f3 ff ff 4d 39 b7 a8 02 00 00 0f 84 58 01 00 00 <41> 8b 46 c8 49 83 ee 38 83 f8 04 75 ce 49 8b 56 10 49 8b 07 4d 8b
[16143.008290] RSP: 002b:00007fff9f08e080 EFLAGS: 00010293
[16143.008457] RAX: 000000000000004d RBX: 000000006ffffdff RCX: 000000000000000b
[16143.008702] RDX: 00007f929ebbd040 RSI: 0000000000000029 RDI: 00007f929ebbd000
[16143.008891] RBP: 00007fff9f08e1d0 R08: 000000006fffffff R09: 00000000effffef5
[16143.009126] R10: 0000000070000022 R11: 0000000000000032 R12: 000000006ffffeff
[16143.009341] R13: 00007fff9f08e260 R14: 00007f929ebbd2a8 R15: 00007f929ed74b10
[16143.009567]