[15046.684091] run fstests btrfs/160 at 2022-04-02 01:01:17
[15046.864834] BTRFS info (device dm-0): flagging fs with big metadata feature
[15046.865093] BTRFS info (device dm-0): using free space tree
[15046.865268] BTRFS info (device dm-0): has skinny extents
[15047.950584] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 1 transid 6 /dev/dm-9 scanned by systemd-udevd (3028670)
[15047.960829] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 2 transid 6 /dev/dm-6 scanned by systemd-udevd (3028670)
[15047.968001] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 3 transid 6 /dev/dm-5 scanned by systemd-udevd (3028672)
[15047.975005] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 4 transid 6 /dev/dm-4 scanned by systemd-udevd (3028670)
[15047.982568] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 5 transid 6 /dev/dm-3 scanned by systemd-udevd (3028672)
[15047.985113] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 6 transid 6 /dev/dm-2 scanned by systemd-udevd (3028670)
[15047.989062] BTRFS: device fsid c0647b54-2fb3-4987-9d42-b9459684a1bb devid 7 transid 6 /dev/mapper/vg0-lv1 scanned by mkfs.btrfs (3028665)
[15047.998941] BTRFS info (device dm-9): flagging fs with big metadata feature
[15047.999156] BTRFS info (device dm-9): use zlib compression, level 3
[15047.999340] BTRFS info (device dm-9): using free space tree
[15047.999512] BTRFS info (device dm-9): has skinny extents
[15048.001181] BTRFS info (device dm-9): checking UUID tree
[15048.023264] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3028669)
[15048.024685] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3028669)
[15048.030146] BTRFS info: devid 5 device path /dev/mapper/vg0-lv3 changed to /dev/dm-3 scanned by systemd-udevd (3028178)
[15048.031510] BTRFS info: devid 5 device path /dev/dm-3 changed to /dev/mapper/vg0-lv3 scanned by systemd-udevd (3028178)
[15048.039170] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (3028218)
[15048.040629] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (3028218)
[15048.042390] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (3028671)
[15048.044799] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (3028671)
[15048.056440] BTRFS info: devid 6 device path /dev/mapper/vg0-lv2 changed to /dev/dm-2 scanned by systemd-udevd (3028670)
[15048.072083] BTRFS info: devid 6 device path /dev/dm-2 changed to /dev/mapper/vg0-lv2 scanned by systemd-udevd (3028670)
[15048.093111] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3028666)
[15048.094688] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3028666)
[15048.111419] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3028672)
[15048.113235] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3028672)
[15048.118935] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3028670)
[15048.120315] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3028670)
[15048.141824] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3028670)
[15048.143214] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3028670)
[15048.167143] btrfs_dev_stat_print_on_error: 38 callbacks suppressed
[15048.167147] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[15048.167758] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[15048.168197] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[15048.172891] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[15048.173315] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[15048.173766] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[15048.174307] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[15048.174794] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[15048.177401] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[15048.177833] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[15048.185147] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3028670)
[15048.186880] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3028670)
[15048.203906] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3028670)
[15048.205645] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3028670)
[15048.259180] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[15048.259492] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[15048.260099] BTRFS error (device dm-9): error writing primary super block to device 1
[15048.276634] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3028670)
[15048.278160] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3028670)
[15048.335786] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15048.336057] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3028761, name: udevadm
[15048.336306] preempt_count: 100, expected: 0
[15048.336454] RCU nest depth: 0, expected: 0
[15048.336601] INFO: lockdep is turned off.
[15048.336742] Preemption disabled at:
[15048.336742] [] __do_softirq+0x5e/0x517
[15048.337058] CPU: 1 PID: 3028761 Comm: udevadm Tainted: G        W         5.17.0+ #639
[15048.337309] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15048.337567] Call Trace:
[15048.337687]  
[15048.337799]  dump_stack_lvl+0x56/0x6f
[15048.337939]  __might_resched.cold+0xff/0x13a
[15048.338089]  blk_release_queue+0x24/0x120
[15048.338234]  kobject_put+0x7c/0x1d0
[15048.338377]  ? rcu_do_batch+0x1ac/0x570
[15048.338523]  blkg_free.part.0+0x41/0x60
[15048.338666]  rcu_do_batch+0x1e1/0x570
[15048.338802]  ? rcu_do_batch+0x1ac/0x570
[15048.338945]  rcu_core+0x2cb/0x460
[15048.339079]  __do_softirq+0x178/0x517
[15048.339217]  __irq_exit_rcu+0xe3/0x160
[15048.339361]  irq_exit_rcu+0xa/0x20
[15048.339500]  sysvec_apic_timer_interrupt+0xa2/0xd0
[15048.339655]  
[15048.339765]  
[15048.339877]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[15048.340037] RIP: 0010:lock_is_held_type+0x1a/0x140
[15048.340190] Code: 44 24 04 eb bd 66 66 2e 0f 1f 84 00 00 00 00 00 41 57 41 56 41 55 41 bd ff ff ff ff 41 54 55 53 48 83 ec 08 8b 0d b2 ef 02 01 <85> c9 0f 84 e2 00 00 00 65 8b 05 1f d8 1c 6d 85 c0 0f 85 d3 00 00
[15048.346172] RSP: 0018:ffffa592837abcc0 EFLAGS: 00000286
[15048.346333] RAX: ffff9360e671b200 RBX: 0000000000000000 RCX: 0000000000000000
[15048.346553] RDX: 0000000000000000 RSI: 00000000ffffffff RDI: ffffffff93b9ce00
[15048.346747] RBP: ffffffff93836e6d R08: 0000000000000000 R09: ffff9360e671b200
[15048.346940] R10: ffff936094fe6500 R11: ffff936082c208f0 R12: 00000000000001c6
[15048.347133] R13: 00000000ffffffff R14: ffff93608e75e118 R15: 00007f7144600000
[15048.347331]  __might_resched+0x26/0x160
[15048.347487]  change_protection+0x86d/0x1020
[15048.347634]  ? up_write+0x17/0x130
[15048.347769]  ? vma_set_page_prot+0x8e/0xe0
[15048.347915]  mprotect_fixup+0x195/0x2f0
[15048.348057]  do_mprotect_pkey+0x1ef/0x370
[15048.348199]  __x64_sys_mprotect+0x1b/0x20
[15048.348346]  do_syscall_64+0x38/0x90
[15048.348485]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[15048.348644] RIP: 0033:0x7f7144b3f4cb
[15048.348781] Code: 73 01 c3 48 8d 0d fd 1c 01 00 f7 d8 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 f3 0f 1e fa b8 0a 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8d 0d cd 1c 01 00 f7 d8 89 01 48 83
[15048.349236] RSP: 002b:00007ffdbc9c5e88 EFLAGS: 00000206 ORIG_RAX: 000000000000000a
[15048.349482] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f7144b3f4cb
[15048.349673] RDX: 0000000000000000 RSI: 000000000002e000 RDI: 00007f71445db000
[15048.349867] RBP: 00007ffdbc9c61f0 R08: 0000000000000003 R09: 0000000000000000
[15048.350060] R10: 0000000000000001 R11: 0000000000000206 R12: 0000000000000fff
[15048.350253] R13: 00007ffdbc9c6280 R14: 0000000000000000 R15: 00007f714461a500
[15048.350458]