[14687.305403] run fstests btrfs/085 at 2022-04-02 00:55:18
[14687.471831] BTRFS info (device dm-0): flagging fs with big metadata feature
[14687.472046] BTRFS info (device dm-0): using free space tree
[14687.472239] BTRFS info (device dm-0): has skinny extents
[14687.647535] BTRFS: device fsid d3691875-3a08-4215-86f5-50c613de47c5 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (2970977)
[14687.710318] BTRFS info (device dm-9): flagging fs with big metadata feature
[14687.710530] BTRFS info (device dm-9): use zlib compression, level 3
[14687.710717] BTRFS info (device dm-9): using free space tree
[14687.710883] BTRFS info (device dm-9): has skinny extents
[14687.712567] BTRFS info (device dm-9): checking UUID tree
[14687.838717] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (2968953)
[14687.839702] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2968953)
[14687.891751] BTRFS info (device dm-9): flagging fs with big metadata feature
[14687.891974] BTRFS info (device dm-9): use zlib compression, level 3
[14687.892177] BTRFS info (device dm-9): using free space tree
[14687.892348] BTRFS info (device dm-9): has skinny extents
[14687.893966] BTRFS info (device dm-9): checking UUID tree
[14688.009090] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14688.009356] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2971072, name: mkfs.btrfs
[14688.009615] preempt_count: 101, expected: 0
[14688.009760] RCU nest depth: 0, expected: 0
[14688.009906] INFO: lockdep is turned off.
[14688.010047] Preemption disabled at:
[14688.010047] [<0000000000000000>] 0x0
[14688.016049] CPU: 0 PID: 2971072 Comm: mkfs.btrfs Tainted: G        W         5.17.0+ #639
[14688.016300] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14688.016556] Call Trace:
[14688.016690]  
[14688.016804]  dump_stack_lvl+0x56/0x6f
[14688.016947]  __might_resched.cold+0xff/0x13a
[14688.017095]  blk_release_queue+0x24/0x120
[14688.017241]  kobject_put+0x7c/0x1d0
[14688.017379]  ? rcu_do_batch+0x1ac/0x570
[14688.017517]  blkg_free.part.0+0x41/0x60
[14688.017665]  rcu_do_batch+0x1e1/0x570
[14688.017806]  ? rcu_do_batch+0x1ac/0x570
[14688.017946]  rcu_core+0x2cb/0x460
[14688.018077]  __do_softirq+0x178/0x517
[14688.018218]  __irq_exit_rcu+0xe3/0x160
[14688.018359]  irq_exit_rcu+0xa/0x20
[14688.018491]  sysvec_apic_timer_interrupt+0xa2/0xd0
[14688.018650]  
[14688.018763]  
[14688.018873]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[14688.019036] RIP: 0010:_raw_spin_unlock_irq+0x2a/0x50
[14688.019196] Code: 0f 1f 44 00 00 55 48 8b 74 24 08 48 89 fd 48 83 c7 18 e8 99 1b 38 ff 48 89 ef e8 31 45 38 ff e8 ac 70 44 ff fb 0f 1f 44 00 00  01 00 00 00 e8 ec d8 33 ff 65 8b 05 c5 ac 1b 6d 85 c0 74 02 5d
[14688.019648] RSP: 0018:ffffa592871dbba0 EFLAGS: 00000246
[14688.019811] RAX: 0000000080000001 RBX: ffff936083afa988 RCX: 0000000000000013
[14688.020006] RDX: 000000000000000c RSI: ffffffff923580ea RDI: ffffffff92e651a4
[14688.020200] RBP: ffff936083afa990 R08: 0000000000000000 R09: 0000000000000000
[14688.020393] R10: 0000000000000000 R11: 0000000000000000 R12: fffff95dc40f08c0
[14688.020587] R13: 0000000000000cc0 R14: ffffa592871dbc30 R15: 0000000000000000
[14688.020786]  ? __filemap_add_folio+0x1da/0x6a0
[14688.020938]  ? _raw_spin_unlock_irq+0x24/0x50
[14688.021086]  __filemap_add_folio+0x1da/0x6a0
[14688.021234]  ? mod_lruvec_state+0x60/0x60
[14688.021381]  filemap_add_folio+0x27/0x80
[14688.021523]  __filemap_get_folio+0x1ac/0x4d0
[14688.021676]  ? blkdev_llseek+0x60/0x60
[14688.021814]  pagecache_get_page+0x15/0x90
[14688.021960]  block_write_begin+0x24/0x130
[14688.022104]  generic_perform_write+0xa9/0x1d0
[14688.022251]  __generic_file_write_iter+0xdb/0x1a0
[14688.022408]  blkdev_write_iter+0xc1/0x150
[14688.022552]  new_sync_write+0xf6/0x160
[14688.022693]  vfs_write+0x290/0x390
[14688.022827]  __x64_sys_pwrite64+0x6f/0xb0
[14688.022970]  do_syscall_64+0x38/0x90
[14688.023109]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[14688.023267] RIP: 0033:0x7fdb79eb225a
[14688.023402] 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
[14688.023861] RSP: 002b:00007ffdd6006e18 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[14688.024096] RAX: ffffffffffffffda RBX: 00007fdb79aaf010 RCX: 00007fdb79eb225a
[14688.024290] RDX: 0000000000200000 RSI: 00007fdb79aaf010 RDI: 0000000000000003
[14688.024481] RBP: 0000000000200000 R08: 00007fdb79aaf010 R09: 0000000000000000
[14688.024679] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[14688.024870] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[14688.025069]  
[14688.198681] BTRFS: device fsid cfba9fb1-f546-4fcc-b1fa-cb1d33b15d18 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (2971072)
[14688.259632] BTRFS info (device dm-9): flagging fs with big metadata feature
[14688.259863] BTRFS info (device dm-9): use zlib compression, level 3
[14688.260047] BTRFS info (device dm-9): using free space tree
[14688.260234] BTRFS info (device dm-9): has skinny extents
[14688.262249] BTRFS info (device dm-9): checking UUID tree
[14688.284165] BTRFS info (device dm-9): setting incompat feature flag for DEFAULT_SUBVOL (0x2)
[14688.323262] BTRFS info (device dm-9): flagging fs with big metadata feature
[14688.323504] BTRFS info (device dm-9): use zlib compression, level 3
[14688.323722] BTRFS info (device dm-9): using free space tree
[14688.323904] BTRFS info (device dm-9): has skinny extents
[14688.445050] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (2968953)
[14688.445801] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2968953)
[14688.499104] BTRFS info (device dm-9): flagging fs with big metadata feature
[14688.499321] BTRFS info (device dm-9): use zlib compression, level 3
[14688.499499] BTRFS info (device dm-9): using free space tree
[14688.499675] BTRFS info (device dm-9): has skinny extents
[14688.814427] BTRFS: device fsid 57d3f2f8-6955-4123-b066-8ca46db1212d devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (2971190)
[14688.879757] BTRFS info (device dm-9): flagging fs with big metadata feature
[14688.879974] BTRFS info (device dm-9): use zlib compression, level 3
[14688.880177] BTRFS info (device dm-9): using free space tree
[14688.880348] BTRFS info (device dm-9): has skinny extents
[14688.882054] BTRFS info (device dm-9): checking UUID tree
[14689.013853] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (2968953)
[14689.014635] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2968953)
[14689.069784] BTRFS info (device dm-9): flagging fs with big metadata feature
[14689.070008] BTRFS info (device dm-9): use zlib compression, level 3
[14689.070212] BTRFS info (device dm-9): using free space tree
[14689.070385] BTRFS info (device dm-9): has skinny extents
[14689.187079] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14689.187387] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2971285, name: udevadm
[14689.187662] preempt_count: 100, expected: 0
[14689.187811] RCU nest depth: 0, expected: 0
[14689.187955] INFO: lockdep is turned off.
[14689.188112] Preemption disabled at:
[14689.188113] [] __do_softirq+0x5e/0x517
[14689.188419] CPU: 1 PID: 2971285 Comm: udevadm Tainted: G        W         5.17.0+ #639
[14689.188665] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14689.188922] Call Trace:
[14689.189038]  
[14689.189153]  dump_stack_lvl+0x56/0x6f
[14689.189296]  __might_resched.cold+0xff/0x13a
[14689.189446]  blk_release_queue+0x24/0x120
[14689.189600]  kobject_put+0x7c/0x1d0
[14689.189760]  ? rcu_do_batch+0x1ac/0x570
[14689.189902]  blkg_free.part.0+0x41/0x60
[14689.190044]  rcu_do_batch+0x1e1/0x570
[14689.190184]  ? rcu_do_batch+0x1ac/0x570
[14689.190328]  rcu_core+0x2cb/0x460
[14689.190458]  __do_softirq+0x178/0x517
[14689.190605]  __irq_exit_rcu+0xe3/0x160
[14689.190748]  irq_exit_rcu+0xa/0x20
[14689.190894]  sysvec_apic_timer_interrupt+0xa2/0xd0
[14689.191051]  
[14689.191173]  
[14689.191329]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[14689.191583] RIP: 0010:__raw_spin_lock_init+0x36/0x60
[14689.191850] Code: 49 89 d4 55 48 89 f5 be 40 00 00 00 53 48 89 fb e8 8f a3 ff ff 48 8d 7b 18 45 0f b6 c5 4c 89 e2 6a 00 48 89 ee 45 31 c9 31 c9  85 e3 ff ff 48 c7 43 10 ff ff ff ff 48 b8 00 00 00 00 ad 4e ad
[14689.192311] RSP: 0018:ffffa59287653cf8 EFLAGS: 00000246
[14689.192474] RAX: 0000000000000000 RBX: ffff9360ce30e830 RCX: 0000000000000000
[14689.192671] RDX: ffffffff952c9980 RSI: ffffffff938473f0 RDI: ffff9360ce30e848
[14689.192861] RBP: ffffffff938473f0 R08: 0000000000000002 R09: 0000000000000000
[14689.193057] R10: 0000000000000003 R11: 0000000000000100 R12: ffffffff952c9980
[14689.193249] R13: 0000000000000002 R14: ffffa59287653de8 R15: 0000000000000000
[14689.193448]  __alloc_file+0x9b/0x100
[14689.193592]  alloc_empty_file+0x41/0xb0
[14689.193733]  path_openat+0x39/0xab0
[14689.193868]  do_filp_open+0x96/0x120
[14689.194008]  ? lock_acquire+0x176/0x2d0
[14689.194152]  ? lock_release+0x1ed/0x2c0
[14689.194291]  ? _raw_spin_unlock+0x29/0x40
[14689.194432]  ? alloc_fd+0x130/0x1f0
[14689.194572]  do_sys_openat2+0x7b/0x140
[14689.194714]  __x64_sys_openat+0x46/0x70
[14689.194854]  do_syscall_64+0x38/0x90
[14689.194992]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[14689.195153] RIP: 0033:0x7f2906f19268
[14689.195300] Code: f9 41 89 f0 41 83 e2 40 75 30 89 f0 25 00 00 41 00 3d 00 00 41 00 74 22 44 89 c2 4c 89 ce bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 30 c3 0f 1f 80 00 00 00 00 48 8d 44 24 08 c7
[14689.195759] RSP: 002b:00007ffc39e09378 EFLAGS: 00000287 ORIG_RAX: 0000000000000101
[14689.195999] RAX: ffffffffffffffda RBX: 00007ffc39e0943f RCX: 00007f2906f19268
[14689.196190] RDX: 0000000000080000 RSI: 00007f29069f44e0 RDI: 00000000ffffff9c
[14689.196382] RBP: 00007ffc39e093e0 R08: 0000000000080000 R09: 00007f29069f44e0
[14689.196572] R10: 0000000000000000 R11: 0000000000000287 R12: 00007f2906ef6000
[14689.196772] R13: 00007ffc39e09450 R14: 00007f29069f44e0 R15: 00007ffc39e09450
[14689.196967]