[16711.442769] run fstests btrfs/201 at 2022-04-02 01:29:57
[16711.804387] BTRFS: device fsid 339ce5d5-2fba-4d00-8871-018c5c59b8eb devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3405534)
[16711.863631] BTRFS info (device dm-11): flagging fs with big metadata feature
[16711.863880] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[16711.864157] BTRFS info (device dm-11): use zstd compression, level 3
[16711.864358] BTRFS info (device dm-11): turning on async discard
[16711.864557] BTRFS info (device dm-11): disk space caching is enabled
[16711.864776] BTRFS info (device dm-11): has skinny extents
[16711.867053] BTRFS info (device dm-11): checking UUID tree
[16712.015427] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3404659)
[16712.016181] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3404659)
[16712.066384] BTRFS info (device dm-11): flagging fs with big metadata feature
[16712.066617] BTRFS info (device dm-11): use zstd compression, level 3
[16712.066821] BTRFS info (device dm-11): turning on async discard
[16712.066999] BTRFS info (device dm-11): disk space caching is enabled
[16712.067181] BTRFS info (device dm-11): has skinny extents
[16712.068699] BTRFS info (device dm-11): start tree-log replay
[16712.127696] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16712.127990] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[16712.128263] preempt_count: 100, expected: 0
[16712.128432] RCU nest depth: 0, expected: 0
[16712.128598] INFO: lockdep is turned off.
[16712.128772] Preemption disabled at:
[16712.128773] [] __do_softirq+0x5e/0x517
[16712.129136] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G        W         5.17.0+ #639
[16712.135113] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16712.135368] Call Trace:
[16712.135490]  
[16712.135612]  dump_stack_lvl+0x56/0x6f
[16712.135754]  __might_resched.cold+0xff/0x13a
[16712.135907]  blk_release_queue+0x24/0x120
[16712.136060]  kobject_put+0x7c/0x1d0
[16712.136205]  ? rcu_do_batch+0x1ac/0x570
[16712.136357]  blkg_free.part.0+0x41/0x60
[16712.136518]  rcu_do_batch+0x1e1/0x570
[16712.136666]  ? rcu_do_batch+0x1ac/0x570
[16712.136817]  rcu_core+0x2cb/0x460
[16712.136966]  __do_softirq+0x178/0x517
[16712.137107]  ? smpboot_thread_fn+0x2c/0x270
[16712.137254]  run_ksoftirqd+0x43/0x70
[16712.137394]  smpboot_thread_fn+0x1c1/0x270
[16712.137547]  ? sort_range+0x20/0x20
[16712.137684]  kthread+0xf0/0x120
[16712.137816]  ? kthread_complete_and_exit+0x20/0x20
[16712.137982]  ret_from_fork+0x1f/0x30
[16712.138127]  
[16712.211948] BTRFS: device fsid 92459376-9de8-4e01-9284-569da33161c7 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3405648)
[16712.280649] BTRFS info (device dm-11): flagging fs with big metadata feature
[16712.280884] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[16712.281142] BTRFS info (device dm-11): use zstd compression, level 3
[16712.281333] BTRFS info (device dm-11): turning on async discard
[16712.281525] BTRFS info (device dm-11): disk space caching is enabled
[16712.281722] BTRFS info (device dm-11): has skinny extents
[16712.283825] BTRFS info (device dm-11): checking UUID tree
[16717.948331] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3409703)
[16717.949217] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3409703)
[16718.044471] BTRFS info (device dm-11): flagging fs with big metadata feature
[16718.044712] BTRFS info (device dm-11): use zstd compression, level 3
[16718.044898] BTRFS info (device dm-11): turning on async discard
[16718.045071] BTRFS info (device dm-11): disk space caching is enabled
[16718.045252] BTRFS info (device dm-11): has skinny extents
[16718.047612] BTRFS info (device dm-11): start tree-log replay
[16718.416599] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16718.416878] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[16718.417127] preempt_count: 101, expected: 0
[16718.417277] RCU nest depth: 0, expected: 0
[16718.417424] INFO: lockdep is turned off.
[16718.417601] Preemption disabled at:
[16718.417602] [] schedule_preempt_disabled+0x1e/0x20
[16718.417953] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #639
[16718.418201] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16718.418461] Call Trace:
[16718.418580]  
[16718.418693]  dump_stack_lvl+0x56/0x6f
[16718.418835]  __might_resched.cold+0xff/0x13a
[16718.418987]  blk_release_queue+0x24/0x120
[16718.419130]  kobject_put+0x7c/0x1d0
[16718.419269]  ? rcu_do_batch+0x1ac/0x570
[16718.419409]  blkg_free.part.0+0x41/0x60
[16718.419552]  rcu_do_batch+0x1e1/0x570
[16718.419692]  ? rcu_do_batch+0x1ac/0x570
[16718.419835]  rcu_core+0x2cb/0x460
[16718.419969]  __do_softirq+0x178/0x517
[16718.420107]  __irq_exit_rcu+0xe3/0x160
[16718.420249]  irq_exit_rcu+0xa/0x20
[16718.420381]  sysvec_apic_timer_interrupt+0xa2/0xd0
[16718.420541]  
[16718.420656]  
[16718.420767]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[16718.420928] RIP: 0010:native_safe_halt+0xb/0x10
[16718.421082] Code: 47 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
[16718.421544] RSP: 0018:ffffffffb9a03ea8 EFLAGS: 00000246
[16718.421708] RAX: ffffffffb8e64a80 RBX: ffffffffb9a30a00 RCX: 0000000000000000
[16718.421902] RDX: ffffffffb9a30a00 RSI: ffffffffb987e4d9 RDI: ffffffffb8e64bf6
[16718.422094] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[16718.422288] R10: 0000000000000000 R11: ffff9418bbc20c88 R12: 0000000000000000
[16718.422492] R13: 0000000000000000 R14: ffffffffb9a30118 R15: 0000000000000065
[16718.422688]  ? mwait_idle+0x80/0x80
[16718.422826]  ? default_idle_call+0x26/0xa0
[16718.422973]  default_idle+0xa/0x10
[16718.423105]  default_idle_call+0x5e/0xa0
[16718.423249]  do_idle+0x1e9/0x240
[16718.423383]  cpu_startup_entry+0x19/0x20
[16718.423527]  start_kernel+0x98c/0x99e
[16718.423665]  ? copy_bootdata+0x18/0x55
[16718.423808]  secondary_startup_64_no_verify+0xd5/0xdb
[16718.423970]  
[16718.478522] BTRFS: device fsid 0d5fefd7-cd1c-4ff0-9442-872c6f0ad307 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3409745)
[16718.538881] BTRFS info (device dm-11): flagging fs with big metadata feature
[16718.539103] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[16718.539363] BTRFS info (device dm-11): use zstd compression, level 3
[16718.539554] BTRFS info (device dm-11): turning on async discard
[16718.539755] BTRFS info (device dm-11): disk space caching is enabled
[16718.539942] BTRFS info (device dm-11): has skinny extents
[16718.541900] BTRFS info (device dm-11): checking UUID tree
[16724.086428] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3413799)
[16724.087357] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3413799)
[16724.179328] BTRFS info (device dm-11): flagging fs with big metadata feature
[16724.179571] BTRFS info (device dm-11): use zstd compression, level 3
[16724.179759] BTRFS info (device dm-11): turning on async discard
[16724.179933] BTRFS info (device dm-11): disk space caching is enabled
[16724.180113] BTRFS info (device dm-11): has skinny extents
[16724.182320] BTRFS info (device dm-11): start tree-log replay
[16724.563584] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16724.563868] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[16724.564115] preempt_count: 100, expected: 0
[16724.564265] RCU nest depth: 0, expected: 0
[16724.564417] INFO: lockdep is turned off.
[16724.564577] Preemption disabled at:
[16724.564578] [] __do_softirq+0x5e/0x517
[16724.564890] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G        W         5.17.0+ #639
[16724.565137] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16724.565426] Call Trace:
[16724.565624]  
[16724.565739]  dump_stack_lvl+0x56/0x6f
[16724.565952]  __might_resched.cold+0xff/0x13a
[16724.566173]  blk_release_queue+0x24/0x120
[16724.566321]  kobject_put+0x7c/0x1d0
[16724.566531]  ? rcu_do_batch+0x1ac/0x570
[16724.566745]  blkg_free.part.0+0x41/0x60
[16724.566916]  rcu_do_batch+0x1e1/0x570
[16724.567092]  ? rcu_do_batch+0x1ac/0x570
[16724.567237]  rcu_core+0x2cb/0x460
[16724.567372]  __do_softirq+0x178/0x517
[16724.567517]  ? smpboot_thread_fn+0x2c/0x270
[16724.567668]  run_ksoftirqd+0x43/0x70
[16724.567810]  smpboot_thread_fn+0x1c1/0x270
[16724.567957]  ? sort_range+0x20/0x20
[16724.568096]  kthread+0xf0/0x120
[16724.568230]  ? kthread_complete_and_exit+0x20/0x20
[16724.568391]  ret_from_fork+0x1f/0x30
[16724.568531]  
[16724.651792] BTRFS: device fsid 00e9278d-8ffd-4d0f-a781-4f8b2c7cbe22 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (3413840)
[16724.716754] BTRFS info (device dm-11): flagging fs with big metadata feature
[16724.716976] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[16724.717230] BTRFS info (device dm-11): use zstd compression, level 3
[16724.717422] BTRFS info (device dm-11): turning on async discard
[16724.717618] BTRFS info (device dm-11): disk space caching is enabled
[16724.717802] BTRFS info (device dm-11): has skinny extents
[16724.719979] BTRFS info (device dm-11): checking UUID tree
[16730.572634] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (3417895)
[16730.573767] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (3417895)
[16730.665501] BTRFS info (device dm-11): flagging fs with big metadata feature
[16730.665723] BTRFS info (device dm-11): use zstd compression, level 3
[16730.665908] BTRFS info (device dm-11): turning on async discard
[16730.666081] BTRFS info (device dm-11): disk space caching is enabled
[16730.666265] BTRFS info (device dm-11): has skinny extents
[16730.668753] BTRFS info (device dm-11): start tree-log replay
[16731.035451] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16731.035732] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3417937, name: udevadm
[16731.035989] preempt_count: 100, expected: 0
[16731.036136] RCU nest depth: 0, expected: 0
[16731.036282] INFO: lockdep is turned off.
[16731.036470] Preemption disabled at:
[16731.036471] [] __do_softirq+0x5e/0x517
[16731.036799] CPU: 1 PID: 3417937 Comm: udevadm Tainted: G        W         5.17.0+ #639
[16731.037043] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16731.037302] Call Trace:
[16731.043715]  
[16731.043834]  dump_stack_lvl+0x56/0x6f
[16731.043983]  __might_resched.cold+0xff/0x13a
[16731.044139]  blk_release_queue+0x24/0x120
[16731.044287]  kobject_put+0x7c/0x1d0
[16731.044432]  ? rcu_do_batch+0x1ac/0x570
[16731.044582]  blkg_free.part.0+0x41/0x60
[16731.044725]  rcu_do_batch+0x1e1/0x570
[16731.044864]  ? rcu_do_batch+0x1ac/0x570
[16731.045012]  rcu_core+0x2cb/0x460
[16731.045149]  __do_softirq+0x178/0x517
[16731.045292]  __irq_exit_rcu+0xe3/0x160
[16731.045442]  irq_exit_rcu+0xa/0x20
[16731.045579]  sysvec_apic_timer_interrupt+0x54/0xd0
[16731.045738]  ? asm_sysvec_apic_timer_interrupt+0xa/0x20
[16731.045902]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[16731.046072] RIP: 0033:0x7f9c7b907622
[16731.046222] Code: 5c c3 66 0f 1f 44 00 00 f3 0f 1e fa 89 f1 89 f8 48 83 e1 3f 48 83 e0 3f 83 f9 30 77 4b 83 f8 30 77 46 66 0f 12 0f 66 0f 12 16 <66> 0f 16 4f 08 66 0f 16 56 08 66 0f ef c0 66 0f 74 c1 66 0f 74 ca
[16731.046687] RSP: 002b:00007ffcbc301a98 EFLAGS: 00000283
[16731.046855] RAX: 0000000000000013 RBX: 0000000000000000 RCX: 0000000000000013
[16731.047054] RDX: 00007f9c7aad1dd0 RSI: 00007f9c7aacaa13 RDI: 00007f9c7ab77153
[16731.047254] RBP: 0000000000000000 R08: 000000000000003d R09: 0000000000000006
[16731.047455] R10: fffffffffffff166 R11: 0000000000000246 R12: 00007f9c7b0ee070
[16731.047654] R13: 00007f9c7ab77153 R14: 00007f9c7b917000 R15: 00007f9c7b0eea60
[16731.047856]