[28387.213769] run fstests btrfs/190 at 2022-04-03 04:44:35
[28387.622289] BTRFS: device fsid 6b5bf1a2-a7f2-44db-84df-5b87bca9a357 devid 1 transid 6 /dev/mapper/logwrites-test scanned by mkfs.btrfs (3144883)
[28387.631631] BTRFS info (device dm-11): flagging fs with big metadata feature
[28387.631859] BTRFS info (device dm-11): turning on sync discard
[28387.632045] BTRFS info (device dm-11): enabling free space tree
[28387.632222] BTRFS info (device dm-11): using free space tree
[28387.632414] BTRFS info (device dm-11): has skinny extents
[28387.634386] BTRFS info (device dm-11): creating free space tree
[28387.634642] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[28387.634905] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[28387.644488] BTRFS info (device dm-11): cleaning free space cache v1
[28387.653756] BTRFS info (device dm-11): checking UUID tree
[28387.668150] BTRFS warning (device dm-11): qgroup rescan is already in progress
[28387.675339] BTRFS info (device dm-11): qgroup scan completed (inconsistency flag cleared)
[28388.889427] BTRFS info (device dm-11): balance: start -f -m -s
[28388.889864] BTRFS info (device dm-11): relocating block group 30408704 flags metadata|dup
[28388.953159] BTRFS info (device dm-11): found 83 extents, stage: move data extents
[28388.983839] BTRFS info (device dm-11): relocating block group 22020096 flags system|dup
[28389.014318] BTRFS info (device dm-11): found 1 extents, stage: move data extents
[28389.047488] BTRFS info (device dm-11): balance: ended with status: 0
[28389.104344] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[28389.104624] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3145948, name: 190
[28389.104867] preempt_count: 101, expected: 0
[28389.105017] RCU nest depth: 0, expected: 0
[28389.105163] INFO: lockdep is turned off.
[28389.105308] Preemption disabled at:
[28389.105309] [<0000000000000000>] 0x0
[28389.105602] CPU: 0 PID: 3145948 Comm: 190 Tainted: G        W         5.17.0+ #640
[28389.105836] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[28389.106094] Call Trace:
[28389.106214]  
[28389.106328]  dump_stack_lvl+0x56/0x6f
[28389.106469]  __might_resched.cold+0xff/0x13a
[28389.106624]  blk_release_queue+0x24/0x120
[28389.106770]  kobject_put+0x7c/0x1d0
[28389.106924]  ? rcu_do_batch+0x1ac/0x570
[28389.107067]  blkg_free.part.0+0x41/0x60
[28389.107211]  rcu_do_batch+0x1e1/0x570
[28389.107352]  ? rcu_do_batch+0x1ac/0x570
[28389.107492]  rcu_core+0x2cb/0x460
[28389.107629]  __do_softirq+0x178/0x517
[28389.107775]  __irq_exit_rcu+0xe3/0x160
[28389.107920]  irq_exit_rcu+0xa/0x20
[28389.108055]  sysvec_apic_timer_interrupt+0xa2/0xd0
[28389.108214]  
[28389.108325]  
[28389.108436]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[28389.108601] RIP: 0010:lock_release+0x15c/0x2c0
[28389.108753] Code: e8 a9 d4 c6 00 b8 ff ff ff ff 65 0f c1 05 54 9f e3 48 83 f8 01 0f 85 1a 01 00 00 41 f7 c5 00 02 00 00 74 06 fb 0f 1f 44 00 00 <48> 83 c4 10 5d 41 5c 41 5d 41 5e 41 5f c3 65 8b 05 2f 2b e3 48 89
[28389.109890] RSP: 0018:ffffb18fc6533ab8 EFLAGS: 00000246
[28389.110061] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000000000
[28389.110258] RDX: ffff9e59d3cd8000 RSI: ffffffffb742c217 RDI: ffffffffb8b9ce40
[28389.110455] RBP: ffffffffb8b9ce40 R08: 0000000000000000 R09: 0000000000000000
[28389.110651] R10: 0000000000000000 R11: ffffffffb73cd75c R12: ffff9e59c54f3578
[28389.110848] R13: 000055aea218a000 R14: ffffffffb742c217 R15: 000000017585e025
[28389.111044]  ? unlock_page_memcg+0x57/0x140
[28389.111194]  ? free_unref_page_list+0x12c/0x560
[28389.111347]  ? unlock_page_memcg+0x57/0x140
[28389.111500]  ? lock_release+0x1ed/0x2c0
[28389.111644]  page_remove_rmap+0x105/0x5c0
[28389.111787]  unmap_page_range+0x706/0x10e0
[28389.111935]  ? lock_release+0x1ed/0x2c0
[28389.112079]  unmap_vmas+0xb3/0x130
[28389.112217]  exit_mmap+0x91/0x1b0
[28389.117959]  __mmput+0x4a/0x150
[28389.118092]  begin_new_exec+0x516/0xcf0
[28389.118235]  load_elf_binary+0x69a/0x1720
[28389.118385]  ? xfs_iunlock+0x12e/0x180 [xfs]
[28389.118619]  ? rcu_read_lock_sched_held+0x12/0x60
[28389.118777]  ? lock_acquire+0x176/0x2d0
[28389.118921]  ? rcu_read_lock_sched_held+0x12/0x60
[28389.119081]  bprm_execve+0x307/0x860
[28389.119221]  do_execveat_common+0x1b5/0x230
[28389.119367]  __x64_sys_execve+0x39/0x50
[28389.119506]  do_syscall_64+0x38/0x90
[28389.119647]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[28389.119809] RIP: 0033:0x7f05f7de82fb
[28389.119972] Code: Unable to access opcode bytes at RIP 0x7f05f7de82d1.
[28389.120165] RSP: 002b:00007fff4b37b2c8 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[28389.120406] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f05f7de82fb
[28389.120598] RDX: 000055aea3a07030 RSI: 000055aea3a1cf90 RDI: 000055aea3a258b0
[28389.120792] RBP: 000055aea3a258b0 R08: 000055aea3a1cf90 R09: 000055ae00000001
[28389.120987] R10: 00000000000001b6 R11: 0000000000000246 R12: 00000000ffffffff
[28389.121181] R13: 000055aea3a1cf90 R14: 000055aea3a07030 R15: 000055aea3a0c030
[28389.121375]  
[28389.165666] BTRFS info (device dm-9): flagging fs with big metadata feature
[28389.165898] BTRFS info (device dm-9): turning on sync discard
[28389.166085] BTRFS info (device dm-9): using free space tree
[28389.166254] BTRFS info (device dm-9): has skinny extents
[28389.167776] BTRFS info (device dm-9): cleaning free space cache v1
[28389.178202] BTRFS info (device dm-9): checking UUID tree
[28389.250749] BTRFS info (device dm-9): flagging fs with big metadata feature
[28389.250984] BTRFS info (device dm-9): turning on sync discard
[28389.251160] BTRFS info (device dm-9): using free space tree
[28389.251347] BTRFS info (device dm-9): has skinny extents
[28390.046614] _btrfs_printk: 36 callbacks suppressed
[28390.046617] BTRFS info (device dm-9): flagging fs with big metadata feature
[28390.047018] BTRFS info (device dm-9): turning on sync discard
[28390.047194] BTRFS info (device dm-9): using free space tree
[28390.047388] BTRFS info (device dm-9): has skinny extents
[28390.068919] BTRFS info (device dm-9): checking UUID tree
[28390.079240] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28390.079532] BTRFS info (device dm-9): balance: paused
[28390.184280] BTRFS info (device dm-9): flagging fs with big metadata feature
[28390.184555] BTRFS info (device dm-9): turning on sync discard
[28390.184812] BTRFS info (device dm-9): using free space tree
[28390.185059] BTRFS info (device dm-9): has skinny extents
[28390.190651] BTRFS info (device dm-9): checking UUID tree
[28390.199144] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28390.199518] BTRFS info (device dm-9): relocating block group 298844160 flags metadata|dup
[28390.259963] BTRFS info (device dm-9): found 83 extents, stage: move data extents
[28390.285725] BTRFS info (device dm-9): balance: paused
[28390.409084] BTRFS info (device dm-9): flagging fs with big metadata feature
[28390.409310] BTRFS info (device dm-9): turning on sync discard
[28390.409507] BTRFS info (device dm-9): using free space tree
[28390.409680] BTRFS info (device dm-9): has skinny extents
[28390.412063] BTRFS info (device dm-9): checking UUID tree
[28390.422274] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28390.422559] BTRFS info (device dm-9): balance: paused
[28390.546717] BTRFS info (device dm-9): flagging fs with big metadata feature
[28390.546943] BTRFS info (device dm-9): turning on sync discard
[28390.547117] BTRFS info (device dm-9): using free space tree
[28390.547290] BTRFS info (device dm-9): has skinny extents
[28390.549759] BTRFS info (device dm-9): checking UUID tree
[28390.558063] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28390.566575] BTRFS info (device dm-9): relocating block group 298844160 flags metadata|dup
[28390.617380] BTRFS info (device dm-9): found 83 extents, stage: move data extents
[28390.643198] BTRFS info (device dm-9): balance: paused
[28390.737879] BTRFS info (device dm-9): flagging fs with big metadata feature
[28390.738107] BTRFS info (device dm-9): turning on sync discard
[28390.738283] BTRFS info (device dm-9): using free space tree
[28390.738474] BTRFS info (device dm-9): has skinny extents
[28390.740665] BTRFS info (device dm-9): checking UUID tree
[28390.753233] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28390.761228] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[28390.780984] BTRFS info (device dm-9): balance: paused
[28390.874157] BTRFS info (device dm-9): flagging fs with big metadata feature
[28390.874393] BTRFS info (device dm-9): turning on sync discard
[28390.874571] BTRFS info (device dm-9): using free space tree
[28390.874741] BTRFS info (device dm-9): has skinny extents
[28390.877076] BTRFS info (device dm-9): checking UUID tree
[28390.889059] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28390.889575] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[28390.923586] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[28390.947793] BTRFS info (device dm-9): balance: paused
[28391.041707] BTRFS info (device dm-9): flagging fs with big metadata feature
[28391.041942] BTRFS info (device dm-9): turning on sync discard
[28391.042120] BTRFS info (device dm-9): using free space tree
[28391.042290] BTRFS info (device dm-9): has skinny extents
[28391.044206] BTRFS info (device dm-9): checking UUID tree
[28391.055160] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28391.055540] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[28391.087277] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[28391.110722] BTRFS info (device dm-9): balance: paused
[28391.205517] BTRFS info (device dm-9): flagging fs with big metadata feature
[28391.205743] BTRFS info (device dm-9): turning on sync discard
[28391.205929] BTRFS info (device dm-9): using free space tree
[28391.206100] BTRFS info (device dm-9): has skinny extents
[28391.207973] BTRFS info (device dm-9): checking UUID tree
[28391.218081] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28391.218470] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[28391.249323] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[28391.272878] BTRFS info (device dm-9): balance: paused
[28391.368949] BTRFS info (device dm-9): flagging fs with big metadata feature
[28391.369170] BTRFS info (device dm-9): turning on sync discard
[28391.369361] BTRFS info (device dm-9): using free space tree
[28391.369532] BTRFS info (device dm-9): has skinny extents
[28391.371079] BTRFS info (device dm-9): checking UUID tree
[28391.381146] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28391.381530] BTRFS info (device dm-9): relocating block group 567279616 flags system|dup
[28391.413266] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[28391.437624] BTRFS info (device dm-9): balance: paused
[28391.538138] BTRFS info (device dm-9): flagging fs with big metadata feature
[28391.538438] BTRFS info (device dm-9): turning on sync discard
[28391.538627] BTRFS info (device dm-9): using free space tree
[28391.538798] BTRFS info (device dm-9): has skinny extents
[28391.544109] BTRFS info (device dm-9): checking UUID tree
[28391.554121] BTRFS info (device dm-9): balance: resume -f -musage=90 -susage=90
[28391.554405] BTRFS info (device dm-9): balance: paused
[28391.666056] BTRFS info (device dm-9): flagging fs with big metadata feature
[28391.666280] BTRFS info (device dm-9): turning on sync discard
[28391.666473] BTRFS info (device dm-9): using free space tree
[28391.666644] BTRFS info (device dm-9): has skinny extents
[28391.767718] BTRFS info (device dm-9): flagging fs with big metadata feature
[28391.767946] BTRFS info (device dm-9): turning on sync discard
[28391.768122] BTRFS info (device dm-9): using free space tree
[28391.768291] BTRFS info (device dm-9): has skinny extents