[16280.073297] run fstests btrfs/190 at 2022-04-02 01:22:46
[16280.458747] BTRFS: device fsid aceeb78c-c05b-418a-82a9-578e7a3f5804 devid 1 transid 6 /dev/mapper/logwrites-test scanned by mkfs.btrfs (3391656)
[16280.468367] BTRFS info (device dm-11): flagging fs with big metadata feature
[16280.468614] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[16280.468882] BTRFS info (device dm-11): use zstd compression, level 3
[16280.469065] BTRFS info (device dm-11): turning on async discard
[16280.469240] BTRFS info (device dm-11): disk space caching is enabled
[16280.469437] BTRFS info (device dm-11): has skinny extents
[16280.475979] BTRFS info (device dm-11): checking UUID tree
[16280.485437] BTRFS warning (device dm-11): qgroup rescan is already in progress
[16280.492804] BTRFS info (device dm-11): qgroup scan completed (inconsistency flag cleared)
[16281.652297] BTRFS info (device dm-11): balance: start -f -m -s
[16281.652681] BTRFS info (device dm-11): relocating block group 30408704 flags metadata|dup
[16281.681390] BTRFS info (device dm-11): found 19 extents, stage: move data extents
[16281.698899] BTRFS info (device dm-11): relocating block group 22020096 flags system|dup
[16281.716441] BTRFS info (device dm-11): found 1 extents, stage: move data extents
[16281.731294] BTRFS info (device dm-11): balance: ended with status: 0
[16281.780422] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[16281.780708] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[16281.780953] preempt_count: 101, expected: 0
[16281.781101] RCU nest depth: 0, expected: 0
[16281.781247] INFO: lockdep is turned off.
[16281.781388] Preemption disabled at:
[16281.781389] [] schedule_preempt_disabled+0x1e/0x20
[16281.781765] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #639
[16281.782010] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[16281.782270] Call Trace:
[16281.782391]  
[16281.782509]  dump_stack_lvl+0x56/0x6f
[16281.782661]  __might_resched.cold+0xff/0x13a
[16281.782811]  blk_release_queue+0x24/0x120
[16281.782955]  kobject_put+0x7c/0x1d0
[16281.783095]  ? rcu_do_batch+0x1ac/0x570
[16281.783239]  blkg_free.part.0+0x41/0x60
[16281.783381]  rcu_do_batch+0x1e1/0x570
[16281.783521]  ? rcu_do_batch+0x1ac/0x570
[16281.783669]  rcu_core+0x2cb/0x460
[16281.783803]  __do_softirq+0x178/0x517
[16281.783943]  __irq_exit_rcu+0xe3/0x160
[16281.784087]  irq_exit_rcu+0xa/0x20
[16281.784221]  sysvec_apic_timer_interrupt+0xa2/0xd0
[16281.784376]  
[16281.784492]  
[16281.784606]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[16281.784771] RIP: 0010:native_safe_halt+0xb/0x10
[16281.784927] 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
[16281.785387] RSP: 0018:ffffffffb9a03ea8 EFLAGS: 00000246
[16281.785549] RAX: ffffffffb8e64a80 RBX: ffffffffb9a30a00 RCX: 0000000000000000
[16281.785746] RDX: ffffffffb9a30a00 RSI: ffffffffb987e4d9 RDI: ffffffffb8e64bf6
[16281.785938] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[16281.786132] R10: 0000000000000000 R11: ffff9418bbc20c88 R12: 0000000000000000
[16281.786327] R13: 0000000000000000 R14: ffffffffb9a30118 R15: 0000000000000065
[16281.786524]  ? mwait_idle+0x80/0x80
[16281.786686]  ? default_idle_call+0x26/0xa0
[16281.786845]  default_idle+0xa/0x10
[16281.786979]  default_idle_call+0x5e/0xa0
[16281.787125]  do_idle+0x1e9/0x240
[16281.787258]  cpu_startup_entry+0x19/0x20
[16281.787401]  start_kernel+0x98c/0x99e
[16281.787541]  ? copy_bootdata+0x18/0x55
[16281.787690]  secondary_startup_64_no_verify+0xd5/0xdb
[16281.787854]  
[16281.831977] BTRFS info (device dm-9): flagging fs with big metadata feature
[16281.832208] BTRFS info (device dm-9): use zstd compression, level 3
[16281.832396] BTRFS info (device dm-9): turning on async discard
[16281.832590] BTRFS info (device dm-9): disk space caching is enabled
[16281.832781] BTRFS info (device dm-9): has skinny extents
[16281.896098] BTRFS info (device dm-9): flagging fs with big metadata feature
[16281.896322] BTRFS info (device dm-9): use zstd compression, level 3
[16281.896521] BTRFS info (device dm-9): turning on async discard
[16281.896723] BTRFS info (device dm-9): disk space caching is enabled
[16281.896957] BTRFS info (device dm-9): has skinny extents
[16281.904462] BTRFS info (device dm-9): qgroup scan completed (inconsistency flag cleared)
[16281.971966] BTRFS info (device dm-9): flagging fs with big metadata feature
[16281.972188] BTRFS info (device dm-9): use zstd compression, level 3
[16281.972373] BTRFS info (device dm-9): turning on async discard
[16281.972568] BTRFS info (device dm-9): disk space caching is enabled
[16281.972759] BTRFS info (device dm-9): has skinny extents
[16282.049073] BTRFS info (device dm-9): flagging fs with big metadata feature
[16282.049297] BTRFS info (device dm-9): use zstd compression, level 3
[16282.049506] BTRFS info (device dm-9): turning on async discard
[16282.049754] BTRFS info (device dm-9): disk space caching is enabled
[16282.049950] BTRFS info (device dm-9): has skinny extents
[16282.213848] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16282.214122] BTRFS info (device dm-9): balance: paused
[16283.302489] _btrfs_printk: 56 callbacks suppressed
[16283.302492] BTRFS info (device dm-9): flagging fs with big metadata feature
[16283.302880] BTRFS info (device dm-9): use zstd compression, level 3
[16283.303066] BTRFS info (device dm-9): turning on async discard
[16283.303241] BTRFS info (device dm-9): disk space caching is enabled
[16283.303436] BTRFS info (device dm-9): has skinny extents
[16283.306615] BTRFS info (device dm-9): checking UUID tree
[16283.313019] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16283.313311] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[16283.327024] BTRFS info (device dm-9): balance: paused
[16283.412868] BTRFS info (device dm-9): flagging fs with big metadata feature
[16283.413092] BTRFS info (device dm-9): use zstd compression, level 3
[16283.413274] BTRFS info (device dm-9): turning on async discard
[16283.413466] BTRFS info (device dm-9): disk space caching is enabled
[16283.413654] BTRFS info (device dm-9): has skinny extents
[16283.416075] BTRFS info (device dm-9): checking UUID tree
[16283.422354] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16283.424862] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[16283.439808] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[16283.450719] BTRFS info (device dm-9): balance: paused
[16283.537466] BTRFS info (device dm-9): flagging fs with big metadata feature
[16283.537700] BTRFS info (device dm-9): use zstd compression, level 3
[16283.537890] BTRFS info (device dm-9): turning on async discard
[16283.538067] BTRFS info (device dm-9): disk space caching is enabled
[16283.538248] BTRFS info (device dm-9): has skinny extents
[16283.540312] BTRFS info (device dm-9): checking UUID tree
[16283.551125] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16283.551427] BTRFS info (device dm-9): balance: paused
[16283.645127] BTRFS info (device dm-9): flagging fs with big metadata feature
[16283.645356] BTRFS info (device dm-9): use zstd compression, level 3
[16283.645559] BTRFS info (device dm-9): turning on async discard
[16283.645739] BTRFS info (device dm-9): disk space caching is enabled
[16283.645924] BTRFS info (device dm-9): has skinny extents
[16283.648154] BTRFS info (device dm-9): checking UUID tree
[16283.654310] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16283.654608] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[16283.670215] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[16283.681040] BTRFS info (device dm-9): balance: paused
[16283.772733] BTRFS info (device dm-9): flagging fs with big metadata feature
[16283.772952] BTRFS info (device dm-9): use zstd compression, level 3
[16283.773135] BTRFS info (device dm-9): turning on async discard
[16283.773307] BTRFS info (device dm-9): disk space caching is enabled
[16283.773508] BTRFS info (device dm-9): has skinny extents
[16283.775681] BTRFS info (device dm-9): checking UUID tree
[16283.784057] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16283.784348] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[16283.796449] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[16283.807639] BTRFS info (device dm-9): balance: paused
[16283.910415] BTRFS info (device dm-9): flagging fs with big metadata feature
[16283.910642] BTRFS info (device dm-9): use zstd compression, level 3
[16283.910828] BTRFS info (device dm-9): turning on async discard
[16283.911005] BTRFS info (device dm-9): disk space caching is enabled
[16283.911186] BTRFS info (device dm-9): has skinny extents
[16283.913510] BTRFS info (device dm-9): checking UUID tree
[16283.921229] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[16283.921504] BTRFS info (device dm-9): balance: paused
[16284.017110] BTRFS info (device dm-9): flagging fs with big metadata feature
[16284.017331] BTRFS info (device dm-9): use zstd compression, level 3
[16284.017535] BTRFS info (device dm-9): turning on async discard
[16284.017720] BTRFS info (device dm-9): disk space caching is enabled
[16284.017903] BTRFS info (device dm-9): has skinny extents
[16284.123218] BTRFS info (device dm-9): flagging fs with big metadata feature
[16284.123455] BTRFS info (device dm-9): use zstd compression, level 3
[16284.123643] BTRFS info (device dm-9): turning on async discard
[16284.129366] BTRFS info (device dm-9): disk space caching is enabled
[16284.129561] BTRFS info (device dm-9): has skinny extents