[18356.989266] run fstests btrfs/160 at 2022-04-04 02:02:25
[18357.240473] BTRFS info (device dm-0): flagging fs with big metadata feature
[18357.240758] BTRFS info (device dm-0): using free space tree
[18357.240946] BTRFS info (device dm-0): has skinny extents
[18358.160717] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 1 transid 6 /dev/dm-9 scanned by systemd-udevd (3010865)
[18358.167134] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 2 transid 6 /dev/dm-6 scanned by systemd-udevd (3011356)
[18358.178755] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 3 transid 6 /dev/mapper/vg0-lv5 scanned by mkfs.btrfs (3011352)
[18358.179247] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 4 transid 6 /dev/mapper/vg0-lv4 scanned by mkfs.btrfs (3011352)
[18358.180651] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 5 transid 6 /dev/mapper/vg0-lv3 scanned by mkfs.btrfs (3011352)
[18358.181108] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 6 transid 6 /dev/mapper/vg0-lv2 scanned by mkfs.btrfs (3011352)
[18358.184729] BTRFS: device fsid e64653ca-5738-4cc7-923b-de9b0be33e31 devid 7 transid 6 /dev/mapper/vg0-lv1 scanned by mkfs.btrfs (3011352)
[18358.193048] BTRFS info (device dm-9): flagging fs with big metadata feature
[18358.193271] BTRFS info (device dm-9): use zlib compression, level 3
[18358.193339] BTRFS info: devid 6 device path /dev/dm-2 changed to /dev/mapper/vg0-lv2 scanned by systemd-udevd (3011355)
[18358.193458] BTRFS info (device dm-9): using free space tree
[18358.193963] BTRFS info (device dm-9): has skinny extents
[18358.197992] BTRFS info: devid 7 device path /dev/mapper/vg0-lv1 changed to /dev/dm-1 scanned by systemd-udevd (3011353)
[18358.199636] BTRFS info: devid 7 device path /dev/dm-1 changed to /dev/mapper/vg0-lv1 scanned by systemd-udevd (3011353)
[18358.209520] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3010905)
[18358.210959] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3010905)
[18358.216917] BTRFS info: devid 5 device path /dev/mapper/vg0-lv3 changed to /dev/dm-3 scanned by systemd-udevd (3011356)
[18358.218359] BTRFS info: devid 5 device path /dev/dm-3 changed to /dev/mapper/vg0-lv3 scanned by systemd-udevd (3011356)
[18358.226038] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (3011357)
[18358.228207] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (3011357)
[18358.230947] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3011358)
[18358.232430] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3011358)
[18358.239599] BTRFS info (device dm-9): checking UUID tree
[18358.277652] BTRFS info: devid 5 device path /dev/mapper/vg0-lv3 changed to /dev/dm-3 scanned by systemd-udevd (3010865)
[18358.279191] BTRFS info: devid 5 device path /dev/dm-3 changed to /dev/mapper/vg0-lv3 scanned by systemd-udevd (3010865)
[18358.286600] BTRFS info: devid 2 device path /dev/mapper/vg0-lv6 changed to /dev/dm-6 scanned by systemd-udevd (3011356)
[18358.288014] BTRFS info: devid 2 device path /dev/dm-6 changed to /dev/mapper/vg0-lv6 scanned by systemd-udevd (3011356)
[18358.316520] BTRFS info: devid 4 device path /dev/mapper/vg0-lv4 changed to /dev/dm-4 scanned by systemd-udevd (3010865)
[18358.323455] BTRFS info: devid 4 device path /dev/dm-4 changed to /dev/mapper/vg0-lv4 scanned by systemd-udevd (3010865)
[18358.353329] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3010865)
[18358.354809] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3010865)
[18358.396292] btrfs_dev_stat_print_on_error: 38 callbacks suppressed
[18358.396297] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0
[18358.401826] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0
[18358.403878] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0
[18358.405838] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0
[18358.407793] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0
[18358.409264] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0
[18358.413672] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0
[18358.414249] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0
[18358.416806] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0
[18358.418949] BTRFS error (device dm-9): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0
[18358.493746] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[18358.494254] BTRFS warning (device dm-9): lost page write due to IO error on /dev/mapper/error-test (-5)
[18358.494998] BTRFS error (device dm-9): error writing primary super block to device 1
[18358.517246] BTRFS info: devid 1 device path /dev/mapper/error-test changed to /dev/dm-9 scanned by systemd-udevd (3010865)
[18358.518918] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/error-test scanned by systemd-udevd (3010865)
[18358.600513] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[18358.600809] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 3011444, name: 160
[18358.601092] preempt_count: 101, expected: 0
[18358.601254] RCU nest depth: 0, expected: 0
[18358.601413] INFO: lockdep is turned off.
[18358.601588] Preemption disabled at:
[18358.601589] [<0000000000000000>] 0x0
[18358.601896] CPU: 1 PID: 3011444 Comm: 160 Tainted: G        W         5.17.0+ #641
[18358.602168] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[18358.602450] Call Trace:
[18358.602588]  
[18358.602714]  dump_stack_lvl+0x56/0x6f
[18358.602871]  __might_resched.cold+0xff/0x13a
[18358.603039]  blk_release_queue+0x24/0x120
[18358.603209]  kobject_put+0x7c/0x1d0
[18358.609646]  ? rcu_do_batch+0x1ac/0x570
[18358.609814]  blkg_free.part.0+0x41/0x60
[18358.609974]  rcu_do_batch+0x1e1/0x570
[18358.610134]  ? rcu_do_batch+0x1ac/0x570
[18358.610294]  rcu_core+0x2cb/0x460
[18358.610443]  __do_softirq+0x178/0x517
[18358.610599]  __irq_exit_rcu+0xe3/0x160
[18358.610760]  irq_exit_rcu+0xa/0x20
[18358.610908]  sysvec_apic_timer_interrupt+0xa2/0xd0
[18358.611087]  
[18358.611217]  
[18358.611348]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[18358.611530] RIP: 0010:vm_normal_page+0xe/0xd0
[18358.611699] Code: c7 00 00 00 80 48 2b 3d 80 cd 58 01 eb a0 b8 f4 ff ff ff eb c7 0f 1f 80 00 00 00 00 0f 1f 44 00 00 49 89 f8 48 89 d7 48 89 f8 <0f> 1f 00 48 8b 15 38 40 5a 01 48 85 c0 74 3d 48 89 c1 48 f7 d1 83
[18358.612223] RSP: 0018:ffffb94a021afb20 EFLAGS: 00000202
[18358.612408] RAX: 0000000173f5a025 RBX: ffff96344e96ab28 RCX: 0000000000000001
[18358.612622] RDX: 0000000173f5a025 RSI: 00007f5627d65000 RDI: 0000000173f5a025
[18358.612840] RBP: 00007f5627d66000 R08: ffff96347ce8c960 R09: 0000000000000000
[18358.613054] R10: 0000000000000000 R11: ffffffffa23cd75c R12: ffffdfe605cfd580
[18358.613277] R13: 00007f5627d65000 R14: ffffb94a021afc80 R15: 0000000173f56025
[18358.613495]  ? free_unref_page_list+0x12c/0x560
[18358.613674]  unmap_page_range+0x5f5/0x10e0
[18358.613840]  ? lock_release+0x1ed/0x2c0
[18358.613998]  unmap_vmas+0xb3/0x130
[18358.614153]  exit_mmap+0x91/0x1b0
[18358.614309]  __mmput+0x4a/0x150
[18358.614455]  begin_new_exec+0x516/0xcf0
[18358.614616]  load_elf_binary+0x69a/0x1720
[18358.614787]  ? rcu_read_lock_sched_held+0x12/0x60
[18358.614967]  ? lock_acquire+0x176/0x2d0
[18358.615127]  ? rcu_read_lock_sched_held+0x12/0x60
[18358.615305]  bprm_execve+0x307/0x860
[18358.615461]  do_execveat_common+0x1b5/0x230
[18358.615625]  __x64_sys_execve+0x39/0x50
[18358.615782]  do_syscall_64+0x38/0x90
[18358.615939]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[18358.616126] RIP: 0033:0x7f5627da804b
[18358.616280] Code: Unable to access opcode bytes at RIP 0x7f5627da8021.
[18358.616484] RSP: 002b:00007ffe17400b78 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[18358.616747] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f5627da804b
[18358.616964] RDX: 00005589862cbc80 RSI: 0000558986021470 RDI: 00005589862c9f00
[18358.617184] RBP: 00005589862c9f00 R08: 0000558986021470 R09: 0000558900000001
[18358.617401] R10: 00000000000001b6 R11: 0000000000000246 R12: 00000000ffffffff
[18358.617623] R13: 0000558986021470 R14: 00005589862cbc80 R15: 00005589862ca360
[18358.617854]