[24394.566335] run fstests generic/479 at 2022-04-03 03:38:02
[24394.987119] BTRFS: device fsid 2db681ef-b406-4ad3-9445-d3e4e014c9ac devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1467521)
[24395.075535] BTRFS info (device dm-11): flagging fs with big metadata feature
[24395.075764] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[24395.076026] BTRFS info (device dm-11): use zstd compression, level 3
[24395.076210] BTRFS info (device dm-11): turning on async discard
[24395.076412] BTRFS info (device dm-11): disk space caching is enabled
[24395.076597] BTRFS info (device dm-11): has skinny extents
[24395.112554] BTRFS info (device dm-11): checking UUID tree
[24395.213079] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1466309)
[24395.213859] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1466309)
[24395.264476] BTRFS info (device dm-11): flagging fs with big metadata feature
[24395.264699] BTRFS info (device dm-11): use zstd compression, level 3
[24395.264888] BTRFS info (device dm-11): turning on async discard
[24395.265068] BTRFS info (device dm-11): disk space caching is enabled
[24395.265252] BTRFS info (device dm-11): has skinny extents
[24395.267584] BTRFS info (device dm-11): start tree-log replay
[24395.327339] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[24395.327662] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1467611, name: mkfs.btrfs
[24395.327916] preempt_count: 100, expected: 0
[24395.328067] RCU nest depth: 1, expected: 0
[24395.328212] INFO: lockdep is turned off.
[24395.328377] Preemption disabled at:
[24395.328378] [] __do_softirq+0x5e/0x517
[24395.328700] CPU: 1 PID: 1467611 Comm: mkfs.btrfs Tainted: G W 5.17.0+ #640
[24395.329078] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[24395.329424] Call Trace:
[24395.329558]
[24395.329717] dump_stack_lvl+0x56/0x6f
[24395.329986] __might_resched.cold+0xff/0x13a
[24395.330246] blk_release_queue+0x24/0x120
[24395.330454] kobject_put+0x7c/0x1d0
[24395.330686] ? rcu_do_batch+0x1ac/0x570
[24395.330899] blkg_free.part.0+0x41/0x60
[24395.331092] rcu_do_batch+0x1e1/0x570
[24395.331331] ? rcu_do_batch+0x1ac/0x570
[24395.331574] rcu_core+0x2cb/0x460
[24395.331809] __do_softirq+0x178/0x517
[24395.332053] __irq_exit_rcu+0xe3/0x160
[24395.332201] irq_exit_rcu+0xa/0x20
[24395.332440] sysvec_apic_timer_interrupt+0xa2/0xd0
[24395.332622]
[24395.332799]
[24395.332914] asm_sysvec_apic_timer_interrupt+0x12/0x20
[24395.333081] RIP: 0010:lock_is_held_type+0xe/0x140
[24395.333242] Code: 89 44 24 04 e8 1a 52 f8 ff 0f 0b 8b 44 24 04 eb bd 66 66 2e 0f 1f 84 00 00 00 00 00 41 57 41 56 41 55 41 bd ff ff ff ff 41 54 <55> 53 48 83 ec 08 8b 0d b2 ef 02 01 85 c9 0f 84 e2 00 00 00 65 8b
[24395.333722] RSP: 0018:ffffb18fc4003b00 EFLAGS: 00000202
[24395.333898] RAX: ffff9e59d5e58890 RBX: 0000000000000022 RCX: 0000000000000000
[24395.334098] RDX: 0000000000000002 RSI: 00000000ffffffff RDI: ffff9e59d5e588a8
[24395.334302] RBP: ffffb18fc4003b68 R08: 0000000000000000 R09: 0000000000000000
[24395.334506] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
[24395.334713] R13: 00000000ffffffff R14: ffffb18fc4003c30 R15: 0000000000000000
[24395.334933] xas_descend+0x35/0x120
[24395.335076] xas_load+0x39/0x50
[24395.335207] xa_get_order+0x76/0x180
[24395.335348] __filemap_add_folio+0xe5/0x6a0
[24395.335510] ? mod_lruvec_state+0x60/0x60
[24395.335665] filemap_add_folio+0x27/0x80
[24395.335810] __filemap_get_folio+0x1ac/0x4d0
[24395.335966] ? blkdev_llseek+0x60/0x60
[24395.336111] pagecache_get_page+0x15/0x90
[24395.336271] block_write_begin+0x24/0x130
[24395.336424] generic_perform_write+0xa9/0x1d0
[24395.336583] __generic_file_write_iter+0xdb/0x1a0
[24395.336745] blkdev_write_iter+0xc1/0x150
[24395.336894] new_sync_write+0xf6/0x160
[24395.337037] vfs_write+0x290/0x390
[24395.337177] __x64_sys_pwrite64+0x6f/0xb0
[24395.337328] do_syscall_64+0x38/0x90
[24395.337472] entry_SYSCALL_64_after_hwframe+0x44/0xae
[24395.337639] RIP: 0033:0x7f1ec6da853a
[24395.337781] 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
[24395.338248] RSP: 002b:00007ffd5e7f38d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000012
[24395.338495] RAX: ffffffffffffffda RBX: 00007f1ec69a9010 RCX: 00007f1ec6da853a
[24395.338695] RDX: 0000000000200000 RSI: 00007f1ec69a9010 RDI: 0000000000000003
[24395.338893] RBP: 0000000000200000 R08: 00007f1ec69a9010 R09: 0000000000000000
[24395.339090] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[24395.339287] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[24395.339501]
[24395.419634] BTRFS: device fsid 0f70171e-083b-4cda-b8a0-de9373a84d43 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1467611)
[24395.506704] BTRFS info (device dm-11): flagging fs with big metadata feature
[24395.506932] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[24395.507192] BTRFS info (device dm-11): use zstd compression, level 3
[24395.507394] BTRFS info (device dm-11): turning on async discard
[24395.507573] BTRFS info (device dm-11): disk space caching is enabled
[24395.507759] BTRFS info (device dm-11): has skinny extents
[24395.509727] BTRFS info (device dm-11): checking UUID tree
[24395.600081] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1466309)
[24395.600893] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1466309)
[24395.652757] BTRFS info (device dm-11): flagging fs with big metadata feature
[24395.653076] BTRFS info (device dm-11): use zstd compression, level 3
[24395.653381] BTRFS info (device dm-11): turning on async discard
[24395.653665] BTRFS info (device dm-11): disk space caching is enabled
[24395.653936] BTRFS info (device dm-11): has skinny extents
[24395.655659] BTRFS info (device dm-11): start tree-log replay
[24395.791357] BTRFS: device fsid bfd83508-0a0d-4de5-9b6d-b2dbe2b2cf46 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1467701)
[24395.874704] BTRFS info (device dm-11): flagging fs with big metadata feature
[24395.874931] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[24395.875192] BTRFS info (device dm-11): use zstd compression, level 3
[24395.875395] BTRFS info (device dm-11): turning on async discard
[24395.875574] BTRFS info (device dm-11): disk space caching is enabled
[24395.875837] BTRFS info (device dm-11): has skinny extents
[24395.877957] BTRFS info (device dm-11): checking UUID tree
[24395.965052] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (1466309)
[24395.965834] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (1466309)
[24396.015866] BTRFS info (device dm-11): flagging fs with big metadata feature
[24396.016089] BTRFS info (device dm-11): use zstd compression, level 3
[24396.016276] BTRFS info (device dm-11): turning on async discard
[24396.016478] BTRFS info (device dm-11): disk space caching is enabled
[24396.016661] BTRFS info (device dm-11): has skinny extents
[24396.018279] BTRFS info (device dm-11): start tree-log replay