[ 3515.045716] run fstests btrfs/146 at 2022-04-01 21:49:05
[ 3516.009971] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 1 transid 6 /dev/dm-9 scanned by systemd-udevd (487925)
[ 3516.022825] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 2 transid 6 /dev/dm-6 scanned by systemd-udevd (487925)
[ 3516.032617] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 3 transid 6 /dev/dm-5 scanned by systemd-udevd (487326)
[ 3516.033396] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 5 transid 6 /dev/dm-3 scanned by systemd-udevd (490685)
[ 3516.034637] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 4 transid 6 /dev/mapper/vg0-lv4 scanned by mkfs.btrfs (490680)
[ 3516.036867] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 6 transid 6 /dev/mapper/vg0-lv2 scanned by mkfs.btrfs (490680)
[ 3516.039453] BTRFS: device fsid 60467560-7d06-49ae-bff9-9dcbc92bb24e devid 7 transid 6 /dev/mapper/vg0-lv1 scanned by mkfs.btrfs (490680)
[ 3516.049667] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 3516.049911] BTRFS info (device dm-9): using free space tree
[ 3516.050108] BTRFS info (device dm-9): has skinny extents
[ 3516.052267] BTRFS info: devid 7 device path /dev/mapper/vg0-lv1 changed to /dev/dm-1 scanned by systemd-udevd (487403)
[ 3516.054012] BTRFS info: devid 7 device path /dev/dm-1 changed to /dev/mapper/vg0-lv1 scanned by systemd-udevd (487403)
[ 3516.065797] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (490685)
[ 3516.067084] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (490685)
[ 3516.067807] BTRFS info (device dm-9): checking UUID tree
[ 3516.071881] BTRFS info: devid 5 device path /dev/mapper/vg0-lv3 changed to /dev/dm-3 scanned by systemd-udevd (487925)
[ 3516.084335] BTRFS info: devid 5 device path /dev/dm-3 changed to /dev/mapper/vg0-lv3 scanned by systemd-udevd (487925)
[ 3516.089877] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (487326)
[ 3516.090548] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (490687)
[ 3516.100822] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (490687)
[ 3516.102481] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (487326)
[ 3516.132023] BTRFS info: devid 5 device path /dev/mapper/vg0-lv3 changed to /dev/dm-3 scanned by systemd-udevd (487925)
[ 3516.133415] BTRFS info: devid 5 device path /dev/dm-3 changed to /dev/mapper/vg0-lv3 scanned by systemd-udevd (487925)
[ 3516.148354] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (487326)
[ 3516.149851] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (487326)
[ 3516.156754] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (490687)
[ 3516.158155] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (490687)
[ 3516.981077] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (487925)
[ 3516.982506] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (487925)
[ 3517.044751] btrfs_dev_stat_print_on_error: 23 callbacks suppressed
[ 3517.044755] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 3517.045421] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[ 3517.046115] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[ 3517.046567] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[ 3517.047110] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[ 3517.047601] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[ 3517.048883] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[ 3517.049604] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[ 3517.050333] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[ 3517.051054] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[ 3517.630749] btrfs_end_super_write: 34 callbacks suppressed
[ 3517.630754] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[ 3517.631533] BTRFS error (device dm-9): error writing primary super block to device 1
[ 3517.746651] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (487925)
[ 3517.748111] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (487925)
[ 3517.774426] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (487925)
[ 3517.781373] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (487925)
[ 3517.873684] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3517.873956] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 491006, name: udevadm
[ 3517.874208] preempt_count: 100, expected: 0
[ 3517.874355] RCU nest depth: 0, expected: 0
[ 3517.874500] INFO: lockdep is turned off.
[ 3517.874643] Preemption disabled at:
[ 3517.874644] [] __do_softirq+0x5e/0x517
[ 3517.874960] CPU: 1 PID: 491006 Comm: udevadm Tainted: G        W         5.17.0+ #639
[ 3517.875205] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3517.875457] Call Trace:
[ 3517.875579]  
[ 3517.875694]  dump_stack_lvl+0x56/0x6f
[ 3517.875833]  __might_resched.cold+0xff/0x13a
[ 3517.875986]  blk_release_queue+0x24/0x120
[ 3517.876131]  kobject_put+0x7c/0x1d0
[ 3517.876271]  ? rcu_do_batch+0x1ac/0x570
[ 3517.876414]  blkg_free.part.0+0x41/0x60
[ 3517.876557]  rcu_do_batch+0x1e1/0x570
[ 3517.876696]  ? rcu_do_batch+0x1ac/0x570
[ 3517.876834]  rcu_core+0x2cb/0x460
[ 3517.876969]  __do_softirq+0x178/0x517
[ 3517.877110]  __irq_exit_rcu+0xe3/0x160
[ 3517.877906]  irq_exit_rcu+0xa/0x20
[ 3517.878050]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3517.878209]  
[ 3517.878325]  
[ 3517.878438]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3517.878604] RIP: 0010:task_vsize+0x0/0x20
[ 3517.878748] Code: 93 e8 34 b0 f7 ff 48 89 ee 4c 89 e7 48 83 c4 30 5b 5d 41 5c 41 5d 41 5e 41 5f e9 ab 83 ee ff 66 66 2e 0f 1f 84 00 00 00 00 00 <0f> 1f 44 00 00 48 8b 87 70 01 00 00 48 c1 e0 0c c3 66 66 2e 0f 1f
[ 3517.879201] RSP: 0018:ffffa59283893cc8 EFLAGS: 00000282
[ 3517.879369] RAX: ffff936090ca0680 RBX: 0000000080000001 RCX: 0000000000000000
[ 3517.879562] RDX: 0000000000000001 RSI: ffffffff9216680b RDI: ffff936090ca0680
[ 3517.879756] RBP: ffff93608e729cb0 R08: 0000000000000001 R09: 0000000000000000
[ 3517.879945] R10: 0000000000000000 R11: 0000000000000000 R12: ffff9360f9660000
[ 3517.880138] R13: ffff936090ca0680 R14: ffffffff93a89900 R15: 0000000000000001
[ 3517.880333]  ? get_task_mm+0x3b/0x60
[ 3517.880472]  do_task_stat+0xab/0xe00
[ 3517.880610]  ? lock_release+0x1ed/0x2c0
[ 3517.880753]  ? lock_acquire+0x176/0x2d0
[ 3517.880892]  ? mod_objcg_state+0x158/0x2e0
[ 3517.881037]  ? lock_release+0x1ed/0x2c0
[ 3517.881180]  ? rcu_read_lock_sched_held+0x12/0x60
[ 3517.881342]  ? lock_acquire+0x176/0x2d0
[ 3517.881482]  ? rcu_read_lock_sched_held+0x12/0x60
[ 3517.881638]  ? lock_release+0x1ed/0x2c0
[ 3517.881782]  proc_single_show+0x4d/0xb0
[ 3517.881926]  seq_read_iter+0x123/0x4d0
[ 3517.882066]  seq_read+0xe4/0x110
[ 3517.882202]  vfs_read+0xbb/0x1b0
[ 3517.882339]  ksys_read+0x55/0xd0
[ 3517.882468]  do_syscall_64+0x38/0x90
[ 3517.882606]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 3517.882768] RIP: 0033:0x7ff1291cc2e2
[ 3517.882904] Code: c0 e9 b2 fe ff ff 50 48 8d 3d ea 2e 0a 00 e8 95 e9 01 00 0f 1f 44 00 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 0f 05 <48> 3d 00 f0 ff ff 77 56 c3 0f 1f 44 00 00 48 83 ec 28 48 89 54 24
[ 3517.883379] RSP: 002b:00007ffdb3c3edd8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[ 3517.883714] RAX: ffffffffffffffda RBX: 00005648b0278650 RCX: 00007ff1291cc2e2
[ 3517.883996] RDX: 0000000000000400 RSI: 00005648b02788e0 RDI: 0000000000000003
[ 3517.884242] RBP: 00007ff1292a0300 R08: 0000000000000003 R09: 00007ff12929ea60
[ 3517.884489] R10: 0000000000001000 R11: 0000000000000246 R12: 00007ff128286338
[ 3517.884698] R13: 0000000000000d68 R14: 00007ff12929f700 R15: 0000000000000d68
[ 3517.884919]