[27638.761850] run fstests btrfs/159 at 2022-04-03 04:32:06
[27639.034869] BTRFS info (device dm-0): flagging fs with big metadata feature
[27639.035105] BTRFS info (device dm-0): using free space tree
[27639.035320] BTRFS info (device dm-0): has skinny extents
[27639.348161] BTRFS: device fsid ae89cd98-344f-4154-abc4-1963f8851eab devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2789694)
[27639.417289] BTRFS info (device dm-11): flagging fs with big metadata feature
[27639.417506] BTRFS info (device dm-11): turning on sync discard
[27639.417703] BTRFS info (device dm-11): enabling free space tree
[27639.417881] BTRFS info (device dm-11): using free space tree
[27639.418051] BTRFS info (device dm-11): has skinny extents
[27639.419781] BTRFS info (device dm-11): creating free space tree
[27639.420057] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[27639.420985] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[27639.488028] BTRFS info (device dm-11): cleaning free space cache v1
[27639.519857] BTRFS info (device dm-11): checking UUID tree
[27643.398781] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2791406)
[27643.399840] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2791406)
[27643.497503] BTRFS info (device dm-11): flagging fs with big metadata feature
[27643.497741] BTRFS info (device dm-11): turning on sync discard
[27643.497921] BTRFS info (device dm-11): using free space tree
[27643.498092] BTRFS info (device dm-11): has skinny extents
[27643.500245] BTRFS info (device dm-11): start tree-log replay
[27644.573540] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[27644.573814] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2791449, name: (spawn)
[27644.574065] preempt_count: 101, expected: 0
[27644.574280] RCU nest depth: 0, expected: 0
[27644.574496] INFO: lockdep is turned off.
[27644.574651] Preemption disabled at:
[27644.574652] [<0000000000000000>] 0x0
[27644.575040] CPU: 0 PID: 2791449 Comm: (spawn) Tainted: G        W         5.17.0+ #640
[27644.575364] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[27644.575622] Call Trace:
[27644.575745]  
[27644.575859]  dump_stack_lvl+0x56/0x6f
[27644.576006]  __might_resched.cold+0xff/0x13a
[27644.576154]  blk_release_queue+0x24/0x120
[27644.576312]  kobject_put+0x7c/0x1d0
[27644.576455]  ? rcu_do_batch+0x1ac/0x570
[27644.576600]  blkg_free.part.0+0x41/0x60
[27644.576752]  rcu_do_batch+0x1e1/0x570
[27644.576959]  ? rcu_do_batch+0x1ac/0x570
[27644.577141]  rcu_core+0x2cb/0x460
[27644.577290]  __do_softirq+0x178/0x517
[27644.577454]  __irq_exit_rcu+0xe3/0x160
[27644.577663]  irq_exit_rcu+0xa/0x20
[27644.577866]  sysvec_apic_timer_interrupt+0xa2/0xd0
[27644.578094]  
[27644.578318]  
[27644.578453]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[27644.578654] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[27644.578894] Code: 48 83 c7 18 53 48 89 f3 48 8b 74 24 10 e8 45 1b 38 ff 48 89 ef e8 dd 44 38 ff 80 e7 02 74 0b e8 53 70 44 ff fb 0f 1f 44 00 00  01 00 00 00 e8 93 d8 33 ff 65 8b 05 6c ac 1b 48 85 c0 74 03 5b
[27644.579566] RSP: 0018:ffffb18fc2be3b88 EFLAGS: 00000246
[27644.579809] RAX: 0000000080000001 RBX: 0000000000000246 RCX: ffffe16704112588
[27644.580100] RDX: ffff9e59c01e8948 RSI: ffffffffb7404dc8 RDI: ffffffffb7e651fd
[27644.580380] RBP: ffff9e59c01e8900 R08: 0000000000000000 R09: 0000000000000000
[27644.580634] R10: 0000000000000000 R11: ffffffffb73cd701 R12: ffff9e59c01e8900
[27644.589812] R13: 0000000000000246 R14: ffffe16704586040 R15: ffffe16704112580
[27644.590102]  ? free_unref_page_list+0xd1/0x560
[27644.590323]  ? __unfreeze_partials+0x148/0x180
[27644.590546]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[27644.590776]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[27644.590971]  __unfreeze_partials+0x148/0x180
[27644.591148]  ? trace_hardirqs_on+0x1b/0xe0
[27644.591309]  ? put_cpu_partial+0x122/0x1d0
[27644.591457]  ? remove_vma+0x6c/0x80
[27644.591595]  kmem_cache_free+0x3c4/0x3e0
[27644.591742]  remove_vma+0x6c/0x80
[27644.591874]  exit_mmap+0xc5/0x1b0
[27644.592009]  __mmput+0x4a/0x150
[27644.592143]  begin_new_exec+0x516/0xcf0
[27644.592296]  load_elf_binary+0x69a/0x1720
[27644.592442]  ? xfs_iunlock+0x12e/0x180 [xfs]
[27644.592804]  ? rcu_read_lock_sched_held+0x12/0x60
[27644.592966]  ? lock_acquire+0x176/0x2d0
[27644.593109]  ? rcu_read_lock_sched_held+0x12/0x60
[27644.593276]  bprm_execve+0x307/0x860
[27644.593419]  do_execveat_common+0x1b5/0x230
[27644.593566]  __x64_sys_execve+0x39/0x50
[27644.593706]  do_syscall_64+0x38/0x90
[27644.593847]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[27644.594010] RIP: 0033:0x7f31a71032fb
[27644.594151] Code: Unable to access opcode bytes at RIP 0x7f31a71032d1.
[27644.594375] RSP: 002b:00007ffd27b24b48 EFLAGS: 00000202 ORIG_RAX: 000000000000003b
[27644.594614] RAX: ffffffffffffffda RBX: 0000557dd1688080 RCX: 00007f31a71032fb
[27644.594805] RDX: 0000557dd1636bc0 RSI: 0000557dd16834b0 RDI: 0000557dd1636490
[27644.594999] RBP: 00007ffd27b24c50 R08: 0000000000000000 R09: 0000000000000000
[27644.595201] R10: 00007ffd27b24b20 R11: 0000000000000202 R12: 00007ffd27b24cc0
[27644.595407] R13: 0000557dd16834b0 R14: 0000000000000000 R15: 000000000aba9500
[27644.595620]  
[27644.705703] BTRFS: device fsid 9388cead-6d39-4424-a464-f5f5c6992f50 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2791448)
[27644.777461] BTRFS info (device dm-11): flagging fs with big metadata feature
[27644.777700] BTRFS info (device dm-11): turning on sync discard
[27644.777886] BTRFS info (device dm-11): enabling free space tree
[27644.778062] BTRFS info (device dm-11): using free space tree
[27644.778239] BTRFS info (device dm-11): has skinny extents
[27644.780099] BTRFS info (device dm-11): creating free space tree
[27644.780402] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[27644.780695] BTRFS info (device dm-11): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[27644.790098] BTRFS info (device dm-11): cleaning free space cache v1
[27644.798959] BTRFS info (device dm-11): checking UUID tree
[27648.627065] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2793161)
[27648.628464] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2793161)
[27648.725912] BTRFS info (device dm-11): flagging fs with big metadata feature
[27648.726136] BTRFS info (device dm-11): turning on sync discard
[27648.726323] BTRFS info (device dm-11): using free space tree
[27648.726511] BTRFS info (device dm-11): has skinny extents
[27648.728651] BTRFS info (device dm-11): start tree-log replay
[27649.689493] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[27649.689764] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0
[27649.690010] preempt_count: 101, expected: 0
[27649.690155] RCU nest depth: 0, expected: 0
[27649.690310] INFO: lockdep is turned off.
[27649.690453] Preemption disabled at:
[27649.690454] [] schedule_preempt_disabled+0x1e/0x20
[27649.690790] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G        W         5.17.0+ #640
[27649.691030] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[27649.691286] Call Trace:
[27649.691408]  
[27649.691522]  dump_stack_lvl+0x56/0x6f
[27649.691665]  __might_resched.cold+0xff/0x13a
[27649.691818]  blk_release_queue+0x24/0x120
[27649.691963]  kobject_put+0x7c/0x1d0
[27649.692100]  ? rcu_do_batch+0x1ac/0x570
[27649.692247]  blkg_free.part.0+0x41/0x60
[27649.692399]  rcu_do_batch+0x1e1/0x570
[27649.692538]  ? rcu_do_batch+0x1ac/0x570
[27649.692683]  rcu_core+0x2cb/0x460
[27649.692819]  __do_softirq+0x178/0x517
[27649.692959]  __irq_exit_rcu+0xe3/0x160
[27649.693100]  irq_exit_rcu+0xa/0x20
[27649.693242]  sysvec_apic_timer_interrupt+0xa2/0xd0
[27649.693399]  
[27649.693510]  
[27649.693625]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[27649.693787] RIP: 0010:native_safe_halt+0xb/0x10
[27649.693941] 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
[27649.694406] RSP: 0018:ffffffffb8a03ea8 EFLAGS: 00000246
[27649.694572] RAX: ffffffffb7e64a80 RBX: ffffffffb8a30a00 RCX: 0000000000000000
[27649.694767] RDX: ffffffffb8a30a00 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[27649.694960] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[27649.695155] R10: 0000000000000000 R11: ffff9e5a3bc20c88 R12: 0000000000000000
[27649.695350] R13: 0000000000000000 R14: ffffffffb8a30118 R15: 0000000000000065
[27649.695546]  ? mwait_idle+0x80/0x80
[27649.695681]  ? default_idle_call+0x26/0xa0
[27649.695834]  default_idle+0xa/0x10
[27649.695970]  default_idle_call+0x5e/0xa0
[27649.696114]  do_idle+0x1e9/0x240
[27649.696252]  cpu_startup_entry+0x19/0x20
[27649.696399]  start_kernel+0x98c/0x99e
[27649.696538]  ? copy_bootdata+0x18/0x55
[27649.696681]  secondary_startup_64_no_verify+0xd5/0xdb
[27649.696848]