[ 3275.980576] run fstests btrfs/160 at 2022-04-03 21:51:04
[ 3276.175510] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3276.175746] BTRFS info (device dm-0): using free space tree
[ 3276.175915] BTRFS info (device dm-0): has skinny extents
[ 3276.787194] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 2 transid 6 /dev/dm-6 scanned by systemd-udevd (495995)
[ 3276.788993] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 1 transid 6 /dev/dm-9 scanned by systemd-udevd (496489)
[ 3276.790597] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 3 transid 6 /dev/mapper/vg0-lv5 scanned by mkfs.btrfs (496484)
[ 3276.791402] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 4 transid 6 /dev/mapper/vg0-lv4 scanned by mkfs.btrfs (496484)
[ 3276.792731] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 5 transid 6 /dev/mapper/vg0-lv3 scanned by mkfs.btrfs (496484)
[ 3276.793196] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 6 transid 6 /dev/mapper/vg0-lv2 scanned by mkfs.btrfs (496484)
[ 3276.793674] BTRFS: device fsid 8855760a-e039-45d4-91be-d2241d23f43d devid 7 transid 6 /dev/mapper/vg0-lv1 scanned by mkfs.btrfs (496484)
[ 3276.804046] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 3276.804341] BTRFS info (device dm-9): using free space tree
[ 3276.804564] BTRFS info (device dm-9): has skinny extents
[ 3276.808304] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (496488)
[ 3276.808852] BTRFS info: devid 6 device path /dev/mapper/vg0-lv2 changed to /dev/dm-2 scanned by systemd-udevd (496105)
[ 3276.811279] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (496488)
[ 3276.811994] BTRFS info: devid 5 device path /dev/mapper/vg0-lv3 changed to /dev/dm-3 scanned by systemd-udevd (496100)
[ 3276.812682] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (496491)
[ 3276.813176] BTRFS info: devid 5 device path /dev/dm-3 changed to /dev/mapper/vg0-lv3 scanned by systemd-udevd (496100)
[ 3276.814052] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (496491)
[ 3276.814495] BTRFS info: devid 6 device path /dev/dm-2 changed to /dev/mapper/vg0-lv2 scanned by systemd-udevd (496105)
[ 3276.822111] BTRFS info: devid 7 device path /dev/mapper/vg0-lv1 changed to /dev/dm-1 scanned by systemd-udevd (496489)
[ 3276.823672] BTRFS info: devid 7 device path /dev/dm-1 changed to /dev/mapper/vg0-lv1 scanned by systemd-udevd (496489)
[ 3276.827476] BTRFS info (device dm-9): checking UUID tree
[ 3276.849743] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (496093)
[ 3276.852101] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (496093)
[ 3277.024443] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (495995)
[ 3277.026294] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (495995)
[ 3277.037163] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (496489)
[ 3277.038485] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (496489)
[ 3277.043196] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (495995)
[ 3277.045721] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (495995)
[ 3277.062369] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (496489)
[ 3277.063738] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (496489)
[ 3277.069258] btrfs_dev_stat_print_on_error: 38 callbacks suppressed
[ 3277.069261] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[ 3277.070472] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[ 3277.071550] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[ 3277.073175] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[ 3277.074672] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[ 3277.075300] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[ 3277.075932] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[ 3277.076570] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[ 3277.077161] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[ 3277.077845] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[ 3277.091677] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (496489)
[ 3277.093531] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (496489)
[ 3277.109267] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (496489)
[ 3277.110728] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (496489)
[ 3277.125560] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (496489)
[ 3277.126924] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (496489)
[ 3277.585369] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[ 3277.585688] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[ 3277.586063] BTRFS error (device dm-9): error writing primary super block to device 1
[ 3277.647901] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (496489)
[ 3277.649341] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (496489)
[ 3278.049216] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3278.049498] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 496576, name: udevadm
[ 3278.049746] preempt_count: 100, expected: 0
[ 3278.049894] RCU nest depth: 0, expected: 0
[ 3278.050040] INFO: lockdep is turned off.
[ 3278.050186] Preemption disabled at:
[ 3278.050187] [] __do_softirq+0x5e/0x517
[ 3278.050515] CPU: 0 PID: 496576 Comm: udevadm Tainted: G        W         5.17.0+ #641
[ 3278.050766] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3278.051018] Call Trace:
[ 3278.051142]  
[ 3278.051257]  dump_stack_lvl+0x56/0x6f
[ 3278.051401]  __might_resched.cold+0xff/0x13a
[ 3278.051554]  blk_release_queue+0x24/0x120
[ 3278.051698]  kobject_put+0x7c/0x1d0
[ 3278.051839]  ? rcu_do_batch+0x1ac/0x570
[ 3278.051983]  blkg_free.part.0+0x41/0x60
[ 3278.052123]  rcu_do_batch+0x1e1/0x570
[ 3278.052261]  ? rcu_do_batch+0x1ac/0x570
[ 3278.052406]  rcu_core+0x2cb/0x460
[ 3278.052546]  __do_softirq+0x178/0x517
[ 3278.052694]  __irq_exit_rcu+0xe3/0x160
[ 3278.052839]  irq_exit_rcu+0xa/0x20
[ 3278.052975]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3278.053131]  
[ 3278.053241]  
[ 3278.053357]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3278.053525] RIP: 0010:__fsnotify_parent+0x5a/0x2f0
[ 3278.053683] Code: d2 74 0e 83 f9 01 75 09 48 8b 02 48 8d 50 e0 eb 02 31 d2 49 8b 5c 24 68 41 8b 34 24 81 e6 00 40 00 00 48 83 bb 68 04 00 00 00 <0f> 84 b7 01 00 00 f7 c5 00 00 00 40 0f 85 9f 01 00 00 48 8b 4b 28
[ 3278.054144] RSP: 0018:ffffb94a03677ca8 EFLAGS: 00000246
[ 3278.054310] RAX: ffff96345642ede0 RBX: ffff96345547cf40 RCX: 0000000000000001
[ 3278.054510] RDX: ffff96345642edc0 RSI: 0000000000000000 RDI: ffff9634560e6c30
[ 3278.054701] RBP: 0000000000010000 R08: 0000000000000001 R09: ffff96345547cf40
[ 3278.054896] R10: 0000000000010000 R11: 0000000000000000 R12: ffff9634560e6c30
[ 3278.055086] R13: ffff963451e4ee10 R14: 0000000000000001 R15: 0000000000008000
[ 3278.055284]  ? lock_acquire+0x176/0x2d0
[ 3278.055426]  ? lock_release+0x1ed/0x2c0
[ 3278.055574]  ? rcu_read_lock_sched_held+0x12/0x60
[ 3278.055731]  ? lock_release+0x1ed/0x2c0
[ 3278.055873]  do_dentry_open+0xed/0x370
[ 3278.056043]  path_openat+0x3bd/0xab0
[ 3278.056186]  do_filp_open+0x96/0x120
[ 3278.056324]  ? __lookup_hash+0xa0/0xa0
[ 3278.056468]  ? _raw_spin_unlock+0x29/0x40
[ 3278.056617]  ? alloc_fd+0x130/0x1f0
[ 3278.056753]  do_sys_openat2+0x7b/0x140
[ 3278.056894]  __x64_sys_openat+0x46/0x70
[ 3278.057040]  do_syscall_64+0x38/0x90
[ 3278.057180]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[ 3278.057341] RIP: 0033:0x7f698a18e268
[ 3278.057482] Code: f9 41 89 f0 41 83 e2 40 75 30 89 f0 25 00 00 41 00 3d 00 00 41 00 74 22 44 89 c2 4c 89 ce bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 30 c3 0f 1f 80 00 00 00 00 48 8d 44 24 08 c7
[ 3278.057947] RSP: 002b:00007ffe75237108 EFLAGS: 00000287 ORIG_RAX: 0000000000000101
[ 3278.064618] RAX: ffffffffffffffda RBX: 00007ffe752371cf RCX: 00007f698a18e268
[ 3278.064899] RDX: 0000000000080000 RSI: 00007f6989469a90 RDI: 00000000ffffff9c
[ 3278.065178] RBP: 00007ffe75237170 R08: 0000000000080000 R09: 00007f6989469a90
[ 3278.065456] R10: 0000000000000000 R11: 0000000000000287 R12: 00007f6989c69a00
[ 3278.065657] R13: 00007ffe752371e0 R14: 00007f6989469a90 R15: 00007ffe752371e0
[ 3278.065852]