[ 4736.511956] run fstests btrfs/190 at 2022-04-02 22:10:24
[ 4736.941242] BTRFS: device fsid 72c5e336-3e23-494f-a4d6-e8499578dbf9 devid 1 transid 6 /dev/mapper/logwrites-test scanned by mkfs.btrfs (839527)
[ 4736.949954] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 4736.950209] BTRFS info (device dm-11): turning on async discard
[ 4736.950388] BTRFS info (device dm-11): disk space caching is enabled
[ 4736.950569] BTRFS info (device dm-11): has skinny extents
[ 4736.966253] BTRFS info (device dm-11): checking UUID tree
[ 4736.983951] BTRFS warning (device dm-11): qgroup rescan is already in progress
[ 4736.992258] BTRFS info (device dm-11): qgroup scan completed (inconsistency flag cleared)
[ 4738.222293] BTRFS info (device dm-11): balance: start -f -m -s
[ 4738.222795] BTRFS info (device dm-11): relocating block group 30408704 flags metadata|dup
[ 4738.289852] BTRFS info (device dm-11): found 82 extents, stage: move data extents
[ 4738.323168] BTRFS info (device dm-11): relocating block group 22020096 flags system|dup
[ 4738.357752] BTRFS info (device dm-11): found 1 extents, stage: move data extents
[ 4738.387543] BTRFS info (device dm-11): balance: ended with status: 0
[ 4738.443087] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4738.443386] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[ 4738.443648] preempt_count: 101, expected: 0
[ 4738.443827] RCU nest depth: 0, expected: 0
[ 4738.444896] INFO: lockdep is turned off.
[ 4738.445047] Preemption disabled at:
[ 4738.445048] [] schedule_preempt_disabled+0x1e/0x20
[ 4738.445431] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #640
[ 4738.445676] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4738.445949] Call Trace:
[ 4738.446079]  
[ 4738.446197]  dump_stack_lvl+0x56/0x6f
[ 4738.446343]  __might_resched.cold+0xff/0x13a
[ 4738.446495]  blk_release_queue+0x24/0x120
[ 4738.446641]  kobject_put+0x7c/0x1d0
[ 4738.446781]  ? rcu_do_batch+0x1ac/0x570
[ 4738.446926]  blkg_free.part.0+0x41/0x60
[ 4738.447072]  rcu_do_batch+0x1e1/0x570
[ 4738.447211]  ? rcu_do_batch+0x1ac/0x570
[ 4738.447358]  rcu_core+0x2cb/0x460
[ 4738.447498]  __do_softirq+0x178/0x517
[ 4738.447639]  __irq_exit_rcu+0xe3/0x160
[ 4738.447784]  irq_exit_rcu+0xa/0x20
[ 4738.447922]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4738.448087]  
[ 4738.448202]  
[ 4738.448333]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4738.448501] RIP: 0010:native_safe_halt+0xb/0x10
[ 4738.448654] 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
[ 4738.449128] RSP: 0018:ffffffffb8a03ea8 EFLAGS: 00000246
[ 4738.449303] RAX: ffffffffb7e64a80 RBX: ffffffffb8a30a00 RCX: 0000000000000000
[ 4738.449506] RDX: ffffffffb8a30a00 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[ 4738.449703] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 4738.449904] R10: 0000000000000000 R11: ffff9e5a3bc20c88 R12: 0000000000000000
[ 4738.450112] R13: 0000000000000000 R14: ffffffffb8a30118 R15: 0000000000000065
[ 4738.450308]  ? mwait_idle+0x80/0x80
[ 4738.450449]  ? default_idle_call+0x26/0xa0
[ 4738.450600]  default_idle+0xa/0x10
[ 4738.450735]  default_idle_call+0x5e/0xa0
[ 4738.450877]  do_idle+0x1e9/0x240
[ 4738.451021]  cpu_startup_entry+0x19/0x20
[ 4738.451164]  start_kernel+0x98c/0x99e
[ 4738.451305]  ? copy_bootdata+0x18/0x55
[ 4738.451454]  secondary_startup_64_no_verify+0xd5/0xdb
[ 4738.451628]  
[ 4738.505324] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4738.505542] BTRFS info (device dm-9): turning on async discard
[ 4738.505723] BTRFS info (device dm-9): disk space caching is enabled
[ 4738.505906] BTRFS info (device dm-9): has skinny extents
[ 4738.576810] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4738.577045] BTRFS info (device dm-9): turning on async discard
[ 4738.577240] BTRFS info (device dm-9): disk space caching is enabled
[ 4738.577423] BTRFS info (device dm-9): has skinny extents
[ 4738.591923] BTRFS info (device dm-9): qgroup scan completed (inconsistency flag cleared)
[ 4738.687048] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4738.687273] BTRFS info (device dm-9): turning on async discard
[ 4738.687446] BTRFS info (device dm-9): disk space caching is enabled
[ 4738.687623] BTRFS info (device dm-9): has skinny extents
[ 4738.792111] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4738.792330] BTRFS info (device dm-9): turning on async discard
[ 4738.792507] BTRFS info (device dm-9): disk space caching is enabled
[ 4738.792690] BTRFS info (device dm-9): has skinny extents
[ 4738.809493] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4738.809763] BTRFS info (device dm-9): balance: paused
[ 4738.913953] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4738.914199] BTRFS info (device dm-9): turning on async discard
[ 4738.920147] BTRFS info (device dm-9): disk space caching is enabled
[ 4738.920342] BTRFS info (device dm-9): has skinny extents
[ 4738.923466] BTRFS info (device dm-9): checking UUID tree
[ 4738.934241] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4738.934502] BTRFS info (device dm-9): balance: canceled
[ 4739.063875] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4739.064121] BTRFS info (device dm-9): turning on async discard
[ 4739.064299] BTRFS info (device dm-9): disk space caching is enabled
[ 4739.064480] BTRFS info (device dm-9): has skinny extents
[ 4739.094351] BTRFS info (device dm-9): checking UUID tree
[ 4739.104625] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4739.104910] BTRFS info (device dm-9): balance: canceled
[ 4739.233876] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4739.234161] BTRFS info (device dm-9): turning on async discard
[ 4739.234340] BTRFS info (device dm-9): disk space caching is enabled
[ 4739.234523] BTRFS info (device dm-9): has skinny extents
[ 4739.255424] BTRFS info (device dm-9): checking UUID tree
[ 4739.266149] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4739.266408] BTRFS info (device dm-9): balance: paused
[ 4739.386835] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4739.387113] BTRFS info (device dm-9): turning on async discard
[ 4739.387292] BTRFS info (device dm-9): disk space caching is enabled
[ 4739.387472] BTRFS info (device dm-9): has skinny extents
[ 4739.389366] BTRFS info (device dm-9): checking UUID tree
[ 4739.402554] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4739.402896] BTRFS info (device dm-9): relocating block group 298844160 flags metadata|dup
[ 4739.455650] BTRFS info (device dm-9): found 82 extents, stage: move data extents
[ 4739.481456] BTRFS info (device dm-9): balance: paused
[ 4739.606828] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4739.607072] BTRFS info (device dm-9): turning on async discard
[ 4739.607259] BTRFS info (device dm-9): disk space caching is enabled
[ 4739.607445] BTRFS info (device dm-9): has skinny extents
[ 4739.608814] BTRFS info (device dm-9): checking UUID tree
[ 4739.622777] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4739.623150] BTRFS info (device dm-9): relocating block group 298844160 flags metadata|dup
[ 4739.672479] BTRFS info (device dm-9): found 82 extents, stage: move data extents
[ 4739.698684] BTRFS info (device dm-9): balance: paused
[ 4739.824730] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4739.824958] BTRFS info (device dm-9): turning on async discard
[ 4739.825170] BTRFS info (device dm-9): disk space caching is enabled
[ 4739.825368] BTRFS info (device dm-9): has skinny extents
[ 4739.827261] BTRFS info (device dm-9): checking UUID tree
[ 4739.839606] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4739.839879] BTRFS info (device dm-9): balance: paused
[ 4739.966921] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4739.967204] BTRFS info (device dm-9): turning on async discard
[ 4739.967395] BTRFS info (device dm-9): disk space caching is enabled
[ 4739.967583] BTRFS info (device dm-9): has skinny extents
[ 4739.969651] BTRFS info (device dm-9): checking UUID tree
[ 4739.984686] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4739.984965] BTRFS info (device dm-9): balance: paused
[ 4740.110223] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4740.110444] BTRFS info (device dm-9): turning on async discard
[ 4740.110620] BTRFS info (device dm-9): disk space caching is enabled
[ 4740.110800] BTRFS info (device dm-9): has skinny extents
[ 4740.112572] BTRFS info (device dm-9): checking UUID tree
[ 4740.124623] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4740.133205] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[ 4740.158268] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[ 4740.183185] BTRFS info (device dm-9): balance: paused
[ 4740.365625] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4740.365847] BTRFS info (device dm-9): turning on async discard
[ 4740.366031] BTRFS info (device dm-9): disk space caching is enabled
[ 4740.366232] BTRFS info (device dm-9): has skinny extents
[ 4740.367858] BTRFS info (device dm-9): checking UUID tree
[ 4740.379646] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4740.380287] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[ 4740.412139] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[ 4740.436500] BTRFS info (device dm-9): balance: paused
[ 4740.572357] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4740.572578] BTRFS info (device dm-9): turning on async discard
[ 4740.572753] BTRFS info (device dm-9): disk space caching is enabled
[ 4740.572934] BTRFS info (device dm-9): has skinny extents