[15588.309892] run fstests btrfs/159 at 2022-04-03 01:11:16
[15588.508534] BTRFS info (device dm-0): flagging fs with big metadata feature
[15588.508776] BTRFS info (device dm-0): using free space tree
[15588.508951] BTRFS info (device dm-0): has skinny extents
[15588.706842] BTRFS: device fsid 4704cd11-3fb0-4a15-a641-f590b37ab9ba devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2970743)
[15588.771963] BTRFS info (device dm-11): flagging fs with big metadata feature
[15588.772190] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[15588.772451] BTRFS info (device dm-11): use zstd compression, level 3
[15588.772659] BTRFS info (device dm-11): turning on async discard
[15588.772834] BTRFS info (device dm-11): disk space caching is enabled
[15588.773014] BTRFS info (device dm-11): has skinny extents
[15588.775347] BTRFS info (device dm-11): checking UUID tree
[15592.866940] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2972456)
[15592.867883] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2972456)
[15592.972173] BTRFS info (device dm-11): flagging fs with big metadata feature
[15592.972414] BTRFS info (device dm-11): use zstd compression, level 3
[15592.972623] BTRFS info (device dm-11): turning on async discard
[15592.972819] BTRFS info (device dm-11): disk space caching is enabled
[15592.973002] BTRFS info (device dm-11): has skinny extents
[15592.975284] BTRFS info (device dm-11): start tree-log replay
[15593.954527] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15593.954796] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2972456, name: systemd-udevd
[15593.955137] preempt_count: 100, expected: 0
[15593.955307] RCU nest depth: 0, expected: 0
[15593.955482] INFO: lockdep is turned off.
[15593.955666] Preemption disabled at:
[15593.955667] [] __do_softirq+0x5e/0x517
[15593.956028] CPU: 1 PID: 2972456 Comm: systemd-udevd Tainted: G W 5.17.0+ #640
[15593.956306] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15593.956584] Call Trace:
[15593.956735]
[15593.956853] dump_stack_lvl+0x56/0x6f
[15593.957006] __might_resched.cold+0xff/0x13a
[15593.957161] blk_release_queue+0x24/0x120
[15593.957329] kobject_put+0x7c/0x1d0
[15593.957470] ? rcu_do_batch+0x1ac/0x570
[15593.957644] blkg_free.part.0+0x41/0x60
[15593.957790] rcu_do_batch+0x1e1/0x570
[15593.957930] ? rcu_do_batch+0x1ac/0x570
[15593.958073] rcu_core+0x2cb/0x460
[15593.958211] __do_softirq+0x178/0x517
[15593.958356] __irq_exit_rcu+0xe3/0x160
[15593.958498] irq_exit_rcu+0xa/0x20
[15593.958641] sysvec_apic_timer_interrupt+0xa2/0xd0
[15593.958806]
[15593.958919]
[15593.959035] asm_sysvec_apic_timer_interrupt+0x12/0x20
[15593.959204] RIP: 0010:__skb_clone+0x0/0x130
[15593.959353] Code: 00 48 89 6c 02 08 e8 df fd ff ff 49 c7 44 24 60 00 00 00 00 4c 89 e0 5d 41 5c c3 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 <0f> 1f 44 00 00 41 54 49 89 fc 53 48 89 f3 48 c7 47 08 00 00 00 00
[15593.959823] RSP: 0018:ffffb18fc7683c00 EFLAGS: 00000282
[15593.959991] RAX: ffff9e59d3de6e00 RBX: 0000000000000001 RCX: 00000000000000e0
[15593.960190] RDX: 0000000000000cc0 RSI: ffff9e59d3de6000 RDI: ffff9e59d3de6e00
[15593.960390] RBP: ffff9e59d3c83800 R08: 0000000000000100 R09: 0000000000000000
[15593.960591] R10: 0000000000000001 R11: ffff9e5a3bd20c88 R12: 0000000000000000
[15593.960789] R13: ffffffffba387d00 R14: ffff9e59c65cd800 R15: 0000000000000000
[15593.960988] netlink_broadcast+0x273/0x4b0
[15593.961137] netlink_sendmsg+0x398/0x480
[15593.961282] sock_sendmsg+0x5b/0x60
[15593.961425] ____sys_sendmsg+0x22e/0x270
[15593.961575] ? import_iovec+0x17/0x20
[15593.961718] ? sendmsg_copy_msghdr+0x5c/0x80
[15593.961872] ? lock_release+0x1ed/0x2c0
[15593.962080] ___sys_sendmsg+0x75/0xb0
[15593.962238] ? _raw_spin_unlock+0x29/0x40
[15593.962382] ? wp_page_reuse+0x60/0x70
[15593.962533] ? do_wp_page+0x184/0x3c0
[15593.962674] ? __handle_mm_fault+0xba9/0x1460
[15593.962827] __sys_sendmsg+0x49/0x80
[15593.962971] do_syscall_64+0x38/0x90
[15593.963113] entry_SYSCALL_64_after_hwframe+0x44/0xae
[15593.963276] RIP: 0033:0x7f31a7138107
[15593.963416] Code: 0c 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 2e 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 89 54 24 1c 48 89 74 24 10
[15593.963894] RSP: 002b:00007ffd27b291a8 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
[15593.964134] RAX: ffffffffffffffda RBX: 0000000000008010 RCX: 00007f31a7138107
[15593.964334] RDX: 0000000000000000 RSI: 00007ffd27b291d0 RDI: 000000000000000f
[15593.964538] RBP: 0000557dd1672040 R08: 0000000000000000 R09: 0000000000000000
[15593.964738] R10: 0000557dd16720a8 R11: 0000000000000246 R12: 0000557dd1681d60
[15593.964937] R13: 0000000000000000 R14: 00007ffd27b29260 R15: 0000557dd1695700
[15593.965139]
[15594.032112] BTRFS: device fsid 2625f24b-198c-43e7-aee6-ceec286d5b1b devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2972497)
[15594.090791] BTRFS info (device dm-11): flagging fs with big metadata feature
[15594.091091] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[15594.091357] BTRFS info (device dm-11): use zstd compression, level 3
[15594.091601] BTRFS info (device dm-11): turning on async discard
[15594.091847] BTRFS info (device dm-11): disk space caching is enabled
[15594.092033] BTRFS info (device dm-11): has skinny extents
[15594.094097] BTRFS info (device dm-11): checking UUID tree
[15598.828678] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2974210)
[15598.829577] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2974210)
[15598.933234] BTRFS info (device dm-11): flagging fs with big metadata feature
[15598.933505] BTRFS info (device dm-11): use zstd compression, level 3
[15598.933696] BTRFS info (device dm-11): turning on async discard
[15598.933874] BTRFS info (device dm-11): disk space caching is enabled
[15598.934059] BTRFS info (device dm-11): has skinny extents
[15598.936431] BTRFS info (device dm-11): start tree-log replay
[15600.062448] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15600.062770] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[15600.063030] preempt_count: 101, expected: 0
[15600.063177] RCU nest depth: 0, expected: 0
[15600.063364] INFO: lockdep is turned off.
[15600.063526] Preemption disabled at:
[15600.063527] [] ___slab_alloc+0x961/0xf50
[15600.069630] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #640
[15600.069871] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15600.070173] Call Trace:
[15600.070293]
[15600.070408] dump_stack_lvl+0x56/0x6f
[15600.070558] __might_resched.cold+0xff/0x13a
[15600.070712] blk_release_queue+0x24/0x120
[15600.070860] kobject_put+0x7c/0x1d0
[15600.071002] ? rcu_do_batch+0x1ac/0x570
[15600.071146] blkg_free.part.0+0x41/0x60
[15600.071289] rcu_do_batch+0x1e1/0x570
[15600.071433] ? rcu_do_batch+0x1ac/0x570
[15600.071586] rcu_core+0x2cb/0x460
[15600.071723] __do_softirq+0x178/0x517
[15600.071870] __irq_exit_rcu+0xe3/0x160
[15600.072014] irq_exit_rcu+0xa/0x20
[15600.072147] sysvec_apic_timer_interrupt+0xa2/0xd0
[15600.072305]
[15600.072420]
[15600.072536] asm_sysvec_apic_timer_interrupt+0x12/0x20
[15600.072700] RIP: 0010:native_safe_halt+0xb/0x10
[15600.072855] Code: 48 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
[15600.073332] RSP: 0018:ffffb18fc0093ef8 EFLAGS: 00000246
[15600.073502] RAX: ffffffffb7e64a80 RBX: ffff9e59c037b200 RCX: 0000000000000000
[15600.073700] RDX: ffff9e59c037b200 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[15600.073900] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[15600.074096] R10: 0000000000000000 R11: ffff9e5a3bd20c88 R12: 0000000000000000
[15600.074294] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[15600.074497] ? mwait_idle+0x80/0x80
[15600.074637] ? default_idle_call+0x26/0xa0
[15600.074785] default_idle+0xa/0x10
[15600.074919] default_idle_call+0x5e/0xa0
[15600.075066] do_idle+0x1e9/0x240
[15600.075203] cpu_startup_entry+0x19/0x20
[15600.075345] secondary_startup_64_no_verify+0xd5/0xdb
[15600.075516]