[18323.189380] run fstests btrfs/146 at 2022-04-04 02:01:51
[18324.498078] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 1 transid 6 /dev/dm-9 scanned by systemd-udevd (3001579)
[18324.508196] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 2 transid 6 /dev/dm-6 scanned by systemd-udevd (2998104)
[18324.510185] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 3 transid 6 /dev/dm-5 scanned by systemd-udevd (3001579)
[18324.517698] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 4 transid 6 /dev/dm-4 scanned by systemd-udevd (2997977)
[18324.518475] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 5 transid 6 /dev/mapper/vg0-lv3 scanned by mkfs.btrfs (3001574)
[18324.519115] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 6 transid 6 /dev/mapper/vg0-lv2 scanned by mkfs.btrfs (3001574)
[18324.520384] BTRFS: device fsid 4fc185f7-bd62-48cb-a4cb-692cae9d297b devid 7 transid 6 /dev/mapper/vg0-lv1 scanned by mkfs.btrfs (3001574)
[18324.531352] BTRFS info: devid 6 device path /dev/mapper/vg0-lv2 changed to /dev/dm-2 scanned by systemd-udevd (3001579)
[18324.531971] BTRFS info (device dm-9): flagging fs with big metadata feature
[18324.532180] BTRFS info (device dm-9): use zlib compression, level 3
[18324.532371] BTRFS info (device dm-9): using free space tree
[18324.532541] BTRFS info (device dm-9): has skinny extents
[18324.533017] BTRFS info: devid 6 device path /dev/dm-2 changed to /dev/mapper/vg0-lv2 scanned by systemd-udevd (3001579)
[18324.534963] BTRFS info: devid 7 device path /dev/mapper/vg0-lv1 changed to /dev/dm-1 scanned by systemd-udevd (2998104)
[18324.536494] BTRFS info: devid 7 device path /dev/dm-1 changed to /dev/mapper/vg0-lv1 scanned by systemd-udevd (2998104)
[18324.550686] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3001580)
[18324.552367] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3001580)
[18324.560546] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (2998095)
[18324.562181] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (2997977)
[18324.563570] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (2997977)
[18324.564873] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (2998095)
[18324.573275] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (2998090)
[18324.574986] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (2998090)
[18324.576153] BTRFS info (device dm-9): checking UUID tree
[18324.595322] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3001579)
[18324.596824] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3001579)
[18324.607224] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (2998104)
[18324.616249] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (2997977)
[18324.618723] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (2998104)
[18324.620169] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (2997977)
[18324.637861] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (3001577)
[18324.639290] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (3001577)
[18324.665988] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (3001579)
[18324.667343] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (3001579)
[18324.683306] BTRFS info: devid 3 device path /dev/mapper/vg0-lv5 changed to /dev/dm-5 scanned by systemd-udevd (3001579)
[18324.684681] BTRFS info: devid 3 device path /dev/dm-5 changed to /dev/mapper/vg0-lv5 scanned by systemd-udevd (3001579)
[18325.067375] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3001579)
[18325.068721] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3001579)
[18325.133395] btrfs_dev_stat_print_on_error: 23 callbacks suppressed
[18325.133400] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[18325.133995] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[18325.134629] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[18325.135099] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[18325.135472] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[18325.135995] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[18325.136369] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[18325.136750] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[18325.147392] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[18325.147795] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[18325.387185] btrfs_end_super_write: 34 callbacks suppressed
[18325.387188] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[18325.387759] BTRFS error (device dm-9): error writing primary super block to device 1
[18325.495559] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3001579)
[18325.497217] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3001579)
[18325.534639] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3001579)
[18325.536142] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3001579)
[18325.684815] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[18325.685104] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3001905, name: umount
[18325.691510] preempt_count: 100, expected: 0
[18325.691677] RCU nest depth: 0, expected: 0
[18325.691848] INFO: lockdep is turned off.
[18325.692005] Preemption disabled at:
[18325.692006] [] __do_softirq+0x5e/0x517
[18325.692337] CPU: 1 PID: 3001905 Comm: umount Tainted: G        W         5.17.0+ #641
[18325.692610] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[18325.692888] Call Trace:
[18325.693020]  
[18325.693140]  dump_stack_lvl+0x56/0x6f
[18325.693296]  __might_resched.cold+0xff/0x13a
[18325.693463]  blk_release_queue+0x24/0x120
[18325.693619]  kobject_put+0x7c/0x1d0
[18325.693770]  ? rcu_do_batch+0x1ac/0x570
[18325.693927]  blkg_free.part.0+0x41/0x60
[18325.694080]  rcu_do_batch+0x1e1/0x570
[18325.694229]  ? rcu_do_batch+0x1ac/0x570
[18325.694389]  rcu_core+0x2cb/0x460
[18325.694535]  __do_softirq+0x178/0x517
[18325.694715]  __irq_exit_rcu+0xe3/0x160
[18325.694870]  irq_exit_rcu+0xa/0x20
[18325.695017]  sysvec_apic_timer_interrupt+0xa2/0xd0
[18325.695186]  
[18325.695312]  
[18325.695444]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[18325.695623] RIP: 0010:free_uid+0xb/0x90
[18325.695775] Code: ff ff ff 4c 89 ee 48 c7 c7 80 1a a8 a3 49 89 c4 e8 4a 9d ce 00 4c 89 e0 5d 41 5c 41 5d c3 90 0f 1f 44 00 00 48 85 ff 74 7e 55 <48> c7 c6 80 1a a8 a3 48 89 fd 48 83 ec 08 48 89 e2 e8 ff 73 62 00
[18325.696279] RSP: 0018:ffffb94a01dd7ec0 EFLAGS: 00000282
[18325.696464] RAX: ffff9634403ee510 RBX: ffff963451265ee0 RCX: 00000014b3a96001
[18325.696675] RDX: 00000014b3a94001 RSI: 6bb266dec4de29de RDI: ffffffffa3a81980
[18325.696884] RBP: ffff963451265e40 R08: 0000000000000000 R09: 0000000000000000
[18325.697098] R10: 0000000000000000 R11: 0000000000000000 R12: fffffffffffffffe
[18325.697307] R13: 00007f5b2b6b3500 R14: 0000000000000000 R15: 0000000000000000
[18325.697530]  put_cred_rcu+0x62/0xd0
[18325.697681]  do_faccessat+0x111/0x240
[18325.697838]  do_syscall_64+0x38/0x90
[18325.697987]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[18325.698166] RIP: 0033:0x7f5b2b6a90fb
[18325.698318] Code: 77 05 c3 0f 1f 40 00 f7 d8 89 05 c8 20 01 00 48 c7 c0 ff ff ff ff c3 0f 1f 84 00 00 00 00 00 f3 0f 1e fa b8 15 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 05 c3 0f 1f 40 00 f7 d8 89 05 98 20 01 00 b8
[18325.698816] RSP: 002b:00007fff9dbd6d58 EFLAGS: 00000246 ORIG_RAX: 0000000000000015
[18325.699073] RAX: ffffffffffffffda RBX: 00007f5b2b6bb0c0 RCX: 00007f5b2b6a90fb
[18325.699284] RDX: 000055891710faf0 RSI: 0000000000000004 RDI: 00007f5b2b6b3500
[18325.699508] RBP: 00007fff9dbd7020 R08: 0000000000000000 R09: 0000000000000007
[18325.699715] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
[18325.699926] R13: 0000000000000001 R14: 00007f5b2b6b3500 R15: 0000000000000000
[18325.700139]