[ 4395.210774] run fstests btrfs/190 at 2022-04-01 22:04:41
[ 4395.680859] BTRFS: device fsid 3c6722cf-4d52-470a-9034-cb0210d70c2a devid 1 transid 6 /dev/mapper/logwrites-test scanned by mkfs.btrfs (840236)
[ 4395.690283] BTRFS info (device dm-11): flagging fs with big metadata feature
[ 4395.690499] BTRFS info (device dm-11): turning on async discard
[ 4395.690685] BTRFS info (device dm-11): disk space caching is enabled
[ 4395.690867] BTRFS info (device dm-11): has skinny extents
[ 4395.692313] BTRFS info (device dm-11): checking UUID tree
[ 4395.702128] BTRFS warning (device dm-11): qgroup rescan is already in progress
[ 4395.720165] BTRFS info (device dm-11): qgroup scan completed (inconsistency flag cleared)
[ 4396.950511] BTRFS info (device dm-11): balance: start -f -m -s
[ 4396.951297] BTRFS info (device dm-11): relocating block group 30408704 flags metadata|dup
[ 4397.070173] BTRFS info (device dm-11): found 82 extents, stage: move data extents
[ 4397.110722] BTRFS info (device dm-11): relocating block group 22020096 flags system|dup
[ 4397.215728] BTRFS info (device dm-11): found 1 extents, stage: move data extents
[ 4397.258891] BTRFS info (device dm-11): balance: ended with status: 0
[ 4397.367068] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 4397.368137] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 840037, name: 190
[ 4397.368982] preempt_count: 102, expected: 0
[ 4397.369529] RCU nest depth: 0, expected: 0
[ 4397.370159] INFO: lockdep is turned off.
[ 4397.370783] Preemption disabled at:
[ 4397.370786] [<0000000000000000>] 0x0
[ 4397.371728] CPU: 1 PID: 840037 Comm: 190 Tainted: G        W         5.17.0+ #639
[ 4397.372490] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 4397.373254] Call Trace:
[ 4397.373583]  
[ 4397.373896]  dump_stack_lvl+0x56/0x6f
[ 4397.374296]  __might_resched.cold+0xff/0x13a
[ 4397.374717]  blk_release_queue+0x24/0x120
[ 4397.375111]  kobject_put+0x7c/0x1d0
[ 4397.375494]  ? rcu_do_batch+0x1ac/0x570
[ 4397.375821]  blkg_free.part.0+0x41/0x60
[ 4397.376186]  rcu_do_batch+0x1e1/0x570
[ 4397.376579]  ? rcu_do_batch+0x1ac/0x570
[ 4397.376990]  rcu_core+0x2cb/0x460
[ 4397.377361]  __do_softirq+0x178/0x517
[ 4397.377736]  __irq_exit_rcu+0xe3/0x160
[ 4397.378071]  irq_exit_rcu+0xa/0x20
[ 4397.378382]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 4397.378741]  
[ 4397.379000]  
[ 4397.379269]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 4397.379647] RIP: 0010:copy_page_range+0x8d7/0x1460
[ 4397.380872] Code: 1f 44 00 00 48 89 d7 8b 47 34 83 c0 7f 83 f8 7f 0f 86 fa 05 00 00 f0 ff 47 34 f0 ff 42 30 48 8b 42 08 48 8d 70 ff a8 01 75 08 <0f> 1f 44 00 00 48 89 d6 48 8d 84 24 24 01 00 00 f6 46 18 01 75 2e
[ 4397.381673] RSP: 0018:ffffb835c42a7b88 EFLAGS: 00000246
[ 4397.382054] RAX: ffffddd184d35ec8 RBX: 0000000000000018 RCX: 0000000000000000
[ 4397.382497] RDX: ffffddd184fee840 RSI: ffffddd184d35ec7 RDI: ffffddd184fee840
[ 4397.382910] RBP: ffff941854135dc8 R08: ffff941845697640 R09: 0000000000000000
[ 4397.383292] R10: 0000000000000000 R11: 0000000000000000 R12: ffff941844c71dc8
[ 4397.383684] R13: ffff941845697640 R14: 00005615743b9000 R15: 800000013fba1865
[ 4397.384082]  ? copy_page_range+0x839/0x1460
[ 4397.384390]  dup_mm+0x48a/0x610
[ 4397.384668]  copy_process+0x134f/0x1e90
[ 4397.384957]  kernel_clone+0x8b/0x3c0
[ 4397.385238]  ? rcu_read_lock_sched_held+0x12/0x60
[ 4397.385554]  ? rcu_read_lock_sched_held+0x12/0x60
[ 4397.385872]  ? lock_acquire+0x176/0x2d0
[ 4397.386155]  ? lock_acquire+0x176/0x2d0
[ 4397.386442]  ? rcu_read_lock_sched_held+0x12/0x60
[ 4397.386761]  ? lock_release+0x1ed/0x2c0
[ 4397.387048]  __do_sys_clone+0x52/0x60
[ 4397.387331]  do_syscall_64+0x38/0x90
[ 4397.387625]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 4397.387917] RIP: 0033:0x7ff17076a0df
[ 4397.388159] Code: ed 0f 85 f4 00 00 00 64 4c 8b 0c 25 10 00 00 00 45 31 c0 4d 8d 91 d0 02 00 00 31 d2 31 f6 bf 11 00 20 01 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 0f 87 8d 00 00 00 41 89 c5 85 c0 0f 85 9a 00 00
[ 4397.388976] RSP: 002b:00007ffffe7f10b0 EFLAGS: 00000246 ORIG_RAX: 0000000000000038
[ 4397.389405] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007ff17076a0df
[ 4397.389746] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011
[ 4397.390083] RBP: 0000000000000000 R08: 0000000000000000 R09: 00007ff170699740
[ 4397.390418] R10: 00007ff170699a10 R11: 0000000000000246 R12: 0000000000000000
[ 4397.390762] R13: 00007ffffe7f10f0 R14: 0000000000000000 R15: 000056157360dd31
[ 4397.391110]  
[ 4397.460676] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4397.460898] BTRFS info (device dm-9): turning on async discard
[ 4397.461090] BTRFS info (device dm-9): disk space caching is enabled
[ 4397.461275] BTRFS info (device dm-9): has skinny extents
[ 4397.538052] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4397.538276] BTRFS info (device dm-9): turning on async discard
[ 4397.538455] BTRFS info (device dm-9): disk space caching is enabled
[ 4397.538637] BTRFS info (device dm-9): has skinny extents
[ 4397.559513] BTRFS info (device dm-9): qgroup scan completed (inconsistency flag cleared)
[ 4397.705257] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4397.705499] BTRFS info (device dm-9): turning on async discard
[ 4397.705686] BTRFS info (device dm-9): disk space caching is enabled
[ 4397.705870] BTRFS info (device dm-9): has skinny extents
[ 4397.859548] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4397.859783] BTRFS info (device dm-9): turning on async discard
[ 4397.859956] BTRFS info (device dm-9): disk space caching is enabled
[ 4397.860156] BTRFS info (device dm-9): has skinny extents
[ 4397.915851] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4397.917145] BTRFS info (device dm-9): balance: paused
[ 4398.038915] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4398.039155] BTRFS info (device dm-9): turning on async discard
[ 4398.039333] BTRFS info (device dm-9): disk space caching is enabled
[ 4398.039517] BTRFS info (device dm-9): has skinny extents
[ 4398.042042] BTRFS info (device dm-9): checking UUID tree
[ 4398.053831] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4398.054767] BTRFS info (device dm-9): relocating block group 298844160 flags metadata|dup
[ 4398.096538] BTRFS info (device dm-9): balance: paused
[ 4398.275269] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4398.275534] BTRFS info (device dm-9): turning on async discard
[ 4398.275750] BTRFS info (device dm-9): disk space caching is enabled
[ 4398.275961] BTRFS info (device dm-9): has skinny extents
[ 4398.302909] BTRFS info (device dm-9): checking UUID tree
[ 4398.314081] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4398.314347] BTRFS info (device dm-9): balance: paused
[ 4398.440879] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4398.441149] BTRFS info (device dm-9): turning on async discard
[ 4398.441349] BTRFS info (device dm-9): disk space caching is enabled
[ 4398.441555] BTRFS info (device dm-9): has skinny extents
[ 4398.516565] BTRFS info (device dm-9): checking UUID tree
[ 4398.530398] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4398.530679] BTRFS info (device dm-9): balance: paused
[ 4398.658211] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4398.658464] BTRFS info (device dm-9): turning on async discard
[ 4398.658674] BTRFS info (device dm-9): disk space caching is enabled
[ 4398.658886] BTRFS info (device dm-9): has skinny extents
[ 4398.661044] BTRFS info (device dm-9): checking UUID tree
[ 4398.677453] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4398.677777] BTRFS info (device dm-9): balance: paused
[ 4398.802664] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4398.802915] BTRFS info (device dm-9): turning on async discard
[ 4398.803135] BTRFS info (device dm-9): disk space caching is enabled
[ 4398.803426] BTRFS info (device dm-9): has skinny extents
[ 4398.805703] BTRFS info (device dm-9): checking UUID tree
[ 4398.849502] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4398.858897] BTRFS info (device dm-9): balance: paused
[ 4398.995184] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4398.995443] BTRFS info (device dm-9): turning on async discard
[ 4398.995641] BTRFS info (device dm-9): disk space caching is enabled
[ 4398.995857] BTRFS info (device dm-9): has skinny extents
[ 4398.997728] BTRFS info (device dm-9): checking UUID tree
[ 4399.014605] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4399.014908] BTRFS info (device dm-9): balance: paused
[ 4399.213287] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4399.213601] BTRFS info (device dm-9): turning on async discard
[ 4399.213867] BTRFS info (device dm-9): disk space caching is enabled
[ 4399.214157] BTRFS info (device dm-9): has skinny extents
[ 4399.220185] BTRFS info (device dm-9): checking UUID tree
[ 4399.244314] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4399.246574] BTRFS info (device dm-9): balance: canceled
[ 4399.502647] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4399.502908] BTRFS info (device dm-9): turning on async discard
[ 4399.503146] BTRFS info (device dm-9): disk space caching is enabled
[ 4399.503363] BTRFS info (device dm-9): has skinny extents
[ 4399.505422] BTRFS info (device dm-9): checking UUID tree
[ 4399.543879] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4399.549867] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[ 4399.591182] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[ 4399.639354] BTRFS info (device dm-9): balance: paused
[ 4399.784962] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4399.785246] BTRFS info (device dm-9): turning on async discard
[ 4399.785458] BTRFS info (device dm-9): disk space caching is enabled
[ 4399.785680] BTRFS info (device dm-9): has skinny extents
[ 4399.788819] BTRFS info (device dm-9): checking UUID tree
[ 4399.839091] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4399.839583] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[ 4399.868031] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[ 4399.902709] BTRFS info (device dm-9): balance: paused
[ 4400.085441] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4400.085701] BTRFS info (device dm-9): turning on async discard
[ 4400.085903] BTRFS info (device dm-9): disk space caching is enabled
[ 4400.086134] BTRFS info (device dm-9): has skinny extents
[ 4400.088513] BTRFS info (device dm-9): checking UUID tree
[ 4400.101673] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[ 4400.102008] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[ 4400.127531] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[ 4400.179992] BTRFS info (device dm-9): balance: paused
[ 4400.365937] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 4400.366356] BTRFS info (device dm-9): turning on async discard
[ 4400.366701] BTRFS info (device dm-9): disk space caching is enabled
[ 4400.367069] BTRFS info (device dm-9): has skinny extents