xfstests xfs/104 fails on ppc64le with 1k blockszie xfs FSTYP -- xfs (debug) PLATFORM -- Linux/ppc64le ibm-p9z-18-lp7 5.14.0-rc1+ #1 SMP Thu Jul 29 12:07:08 EDT 2021 MKFS_OPTIONS -- -f -m crc=1,finobt=1,reflink=1,rmapbt=1,bigtime=1,inobtcount=1 -b size=1024 /dev/sda3 MOUNT_OPTIONS -- -o context=system_u:object_r:root_t:s0 /dev/sda3 /mnt/xfstests/scratch xfs/104 _check_dmesg: something found in dmesg (see /var/lib/xfstests/results//xfs/104.dmesg) Ran: xfs/104 Failures: xfs/104 Failed 1 of 1 tests xfs/104 hit kernel assertion: [44729.688228] XFS: Assertion failed: agno < mp->m_sb.sb_agcount, file: fs/xfs/libxfs/xfs_types.c, line: 22 ... [44761.832781] XFS: Assertion failed: atomic_read(&pag->pag_ref) == 0, file: fs/xfs/libxfs/xfs_ag.c, line: 195 Dmesg output as below: [44697.129906] run fstests xfs/104 at 2021-07-30 01:35:29 [44717.189106] XFS (sda3): EXPERIMENTAL big timestamp feature in use. Use at your own risk! [44717.189122] XFS (sda3): EXPERIMENTAL inode btree counters feature in use. Use at your own risk! [44717.231858] XFS (sda3): Mounting V5 Filesystem [44717.603379] XFS (sda3): Ending clean mount [44729.688228] XFS: Assertion failed: agno < mp->m_sb.sb_agcount, file: fs/xfs/libxfs/xfs_types.c, line: 22 [44729.688267] ------------[ cut here ]------------ [44729.688272] WARNING: CPU: 7 PID: 3725648 at fs/xfs/xfs_message.c:112 assfail+0x54/0x70 [xfs] [44729.688350] Modules linked in: ext2 overlay dm_zero dm_log_writes dm_thin_pool dm_persistent_data dm_bio_prison dm_snapshot dm_bufio ext4 mbcache jbd2 loop dm_flakey dm_mod bonding tls rfkill sunrpc pseries_rng drm fuse drm_panel_orientation_quirks xfs libcrc32c sd_mod t10_pi ibmvscsi ibmveth scsi_transport_srp vmx_crypto [last unloaded: scsi_debug] [44729.688391] CPU: 7 PID: 3725648 Comm: xfsaild/sda3 Tainted: G W 5.14.0-rc1+ #1 [44729.688399] NIP: c0080000063776d0 LR: c0080000063776b8 CTR: 000000007fffffff [44729.688405] REGS: c000000488cbb550 TRAP: 0700 Tainted: G W (5.14.0-rc1+) [44729.688411] MSR: 800000000282b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE> CR: 28002404 XER: 0000000d [44729.688426] CFAR: c0080000063776c8 IRQMASK: 0 GPR00: c0080000063776b8 c000000488cbb7f0 c0080000063e8000 ffffffffffffffea GPR04: 000000000000000a c000000488cbb770 ffffffffffffffc0 0000000000000000 GPR08: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 GPR12: c0080000063b63a0 c00000001eca7700 c0000000001a4cb8 c0000000c5aab1c0 GPR16: 0000000100001d96 00000000000000e5 0000000000000000 0000000000000000 GPR20: 0000000000000000 c000000002cc0a30 c000000002cc03c0 0000000000000001 GPR24: c008000006336f24 000000000000000f 0000000000000003 0000000000000460 GPR28: 00000000000e6440 000000000000000f c000000013c01800 c000000479268000 [44729.688490] NIP [c0080000063776d0] assfail+0x54/0x70 [xfs] [44729.688565] LR [c0080000063776b8] assfail+0x3c/0x70 [xfs] [44729.688641] Call Trace: [44729.688644] [c000000488cbb7f0] [c0080000063776b8] assfail+0x3c/0x70 [xfs] (unreliable) [44729.688722] [c000000488cbb850] [c0080000062bb1dc] xfs_verify_icount+0x134/0x198 [xfs] [44729.688793] [c000000488cbb8b0] [c0080000062b58b4] xfs_validate_sb_write.isra.0+0x5c/0x198 [xfs] [44729.688861] [c000000488cbb920] [c0080000062b7920] xfs_sb_write_verify+0xc8/0x140 [xfs] [44729.688931] [c000000488cbba70] [c0080000062cba0c] _xfs_buf_ioapply+0x64/0x1f0 [xfs] [44729.689006] [c000000488cbbb30] [c0080000062cbc64] __xfs_buf_submit+0xcc/0x3a0 [xfs] [44729.689076] [c000000488cbbb80] [c0080000062ccaa0] xfs_buf_delwri_submit_buffers+0x188/0x430 [xfs] [44729.689150] [c000000488cbbc30] [c008000006336f24] xfsaild_push+0x1ac/0xd00 [xfs] [44729.689229] [c000000488cbbd20] [c008000006337bc0] xfsaild+0x148/0x400 [xfs] [44729.689302] [c000000488cbbda0] [c0000000001a4e54] kthread+0x1a4/0x1b0 [44729.689310] [c000000488cbbe10] [c00000000000cf64] ret_from_kernel_thread+0x5c/0x64 [44729.689318] Instruction dump: [44729.689322] e888dc70 7d264b78 7d455378 f8010010 f821ffa1 4bfff70d 3d220000 e929dc78 [44729.689333] 8929000c 2c090000 41820008 0fe00000 <0fe00000> 38210060 e8010010 7c0803a6 [44729.689345] irq event stamp: 0 [44729.689348] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [44729.689354] hardirqs last disabled at (0): [<c00000000015de44>] copy_process+0x814/0x19c0 [44729.689361] softirqs last enabled at (0): [<c00000000015de44>] copy_process+0x814/0x19c0 [44729.689367] softirqs last disabled at (0): [<0000000000000000>] 0x0 [44729.689373] ---[ end trace 2ec6fe5d770be2e0 ]--- [44740.553974] XFS (sda3): xlog_verify_grant_tail: space > BBTOB(tail_blocks) [44740.553975] XFS (sda3): xlog_verify_grant_tail: space > BBTOB(tail_blocks) [44761.392965] XFS (sda3): Unmounting Filesystem [44761.832781] XFS: Assertion failed: atomic_read(&pag->pag_ref) == 0, file: fs/xfs/libxfs/xfs_ag.c, line: 195 [44761.832819] ------------[ cut here ]------------ [44761.832824] WARNING: CPU: 3 PID: 3725946 at fs/xfs/xfs_message.c:112 assfail+0x54/0x70 [xfs] [44761.832906] Modules linked in: ext2 overlay dm_zero dm_log_writes dm_thin_pool dm_persistent_data dm_bio_prison dm_snapshot dm_bufio ext4 mbcache jbd2 loop dm_flakey dm_mod bonding tls rfkill sunrpc pseries_rng drm fuse drm_panel_orientation_quirks xfs libcrc32c sd_mod t10_pi ibmvscsi ibmveth scsi_transport_srp vmx_crypto [last unloaded: scsi_debug] [44761.832947] CPU: 3 PID: 3725946 Comm: umount Tainted: G W 5.14.0-rc1+ #1 [44761.832954] NIP: c0080000063776d0 LR: c0080000063776b8 CTR: 000000007fffffff [44761.832967] REGS: c000000019d57760 TRAP: 0700 Tainted: G W (5.14.0-rc1+) [44761.832973] MSR: 800000000282b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE> CR: 28002484 XER: 00000010 [44761.832989] CFAR: c0080000063776c8 IRQMASK: 0 GPR00: c0080000063776b8 c000000019d57a00 c0080000063e8000 ffffffffffffffea GPR04: 000000000000000a c000000019d57980 ffffffffffffffc0 0000000000000000 GPR08: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 GPR12: c0080000063b63a0 c00000001ecacb00 0000000000000000 ffffffffffffffff GPR16: 0000000000000000 00007ffff1fdf530 0000000000000000 00007ffff1fdf5c4 GPR20: 000000000ee6b280 000000012db165a8 00007ffff1fdf530 c0000000203a4700 GPR24: 0000000002002000 0000000000000002 c008000006230b58 c000000479268670 GPR28: c000000479268000 c0000004792686c8 0000000000000010 c000000013c07800 [44761.833052] NIP [c0080000063776d0] assfail+0x54/0x70 [xfs] [44761.833125] LR [c0080000063776b8] assfail+0x3c/0x70 [xfs] [44761.833197] Call Trace: [44761.833200] [c000000019d57a00] [c0080000063776b8] assfail+0x3c/0x70 [xfs] (unreliable) [44761.833279] [c000000019d57a60] [c0080000062325fc] xfs_free_perag+0x124/0x170 [xfs] [44761.833341] [c000000019d57ab0] [c0080000063000dc] xfs_unmountfs+0xe4/0x1a0 [xfs] [44761.833417] [c000000019d57b40] [c008000006309144] xfs_fs_put_super+0x5c/0xe0 [xfs] [44761.833497] [c000000019d57bb0] [c0000000005e5ed0] generic_shutdown_super+0xc0/0x180 [44761.833506] [c000000019d57c30] [c0000000005e62a8] kill_block_super+0x38/0xb0 [44761.833514] [c000000019d57c60] [c0000000005e7730] deactivate_locked_super+0x80/0x140 [44761.833521] [c000000019d57ca0] [c0000000006246dc] cleanup_mnt+0x15c/0x240 [44761.833528] [c000000019d57cf0] [c00000000019f554] task_work_run+0xb4/0x120 [44761.833535] [c000000019d57d40] [c000000000022ff4] do_notify_resume+0x134/0x140 [44761.833543] [c000000019d57d70] [c0000000000312a4] interrupt_exit_user_prepare_main+0x224/0x2a0 [44761.833551] [c000000019d57de0] [c0000000000316e4] syscall_exit_prepare+0xe4/0x1e0 [44761.833558] [c000000019d57e10] [c00000000000c17c] system_call_vectored_common+0xfc/0x280 [44761.833566] --- interrupt: 3000 at 0x7fff9fda1134 [44761.833572] NIP: 00007fff9fda1134 LR: 0000000000000000 CTR: 0000000000000000 [44761.833577] REGS: c000000019d57e80 TRAP: 3000 Tainted: G W (5.14.0-rc1+) [44761.833582] MSR: 800000000280f033 <SF,VEC,VSX,EE,PR,FP,ME,IR,DR,RI,LE> CR: 28002404 XER: 00000000 [44761.833598] IRQMASK: 0 GPR00: 0000000000000034 00007ffff1fdf250 00007fff9fe87100 0000000000000000 GPR04: 0000000000000000 00007ffff1fdf268 0000000000000000 0000000000000073 GPR08: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 GPR12: 0000000000000000 00007fff9ffbc4c0 0000000000000000 ffffffffffffffff GPR16: 0000000000000000 00007ffff1fdf530 0000000000000000 00007ffff1fdf5c4 GPR20: 000000000ee6b280 000000012db165a8 00007ffff1fdf530 00007ffff1fe0bd0 GPR24: 000000012db16510 0000000000000000 000000012db16508 000001000da30630 GPR28: 000001000da30510 0000000000000000 000001000da350f0 000001000da30400 [44761.833659] NIP [00007fff9fda1134] 0x7fff9fda1134 [44761.833663] LR [0000000000000000] 0x0 [44761.833667] --- interrupt: 3000 [44761.833671] Instruction dump: [44761.833674] e888dc70 7d264b78 7d455378 f8010010 f821ffa1 4bfff70d 3d220000 e929dc78 [44761.833686] 8929000c 2c090000 41820008 0fe00000 <0fe00000> 38210060 e8010010 7c0803a6 [44761.833698] irq event stamp: 0 [44761.833701] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [44761.833706] hardirqs last disabled at (0): [<c00000000015de44>] copy_process+0x814/0x19c0 [44761.833716] softirqs last enabled at (0): [<c00000000015de44>] copy_process+0x814/0x19c0 [44761.833724] softirqs last disabled at (0): [<0000000000000000>] 0x0 [44761.833729] ---[ end trace 2ec6fe5d770be2e1 ]--- [44761.882713] XFS: Assertion failed: atomic_read(&pag->pag_ref) == 0, file: fs/xfs/libxfs/xfs_ag.c, line: 176 [44761.882735] ------------[ cut here ]------------ [44761.882739] WARNING: CPU: 3 PID: 0 at fs/xfs/xfs_message.c:112 assfail+0x54/0x70 [xfs] [44761.882813] Modules linked in: ext2 overlay dm_zero dm_log_writes dm_thin_pool dm_persistent_data dm_bio_prison dm_snapshot dm_bufio ext4 mbcache jbd2 loop dm_flakey dm_mod bonding tls rfkill sunrpc pseries_rng drm fuse drm_panel_orientation_quirks xfs libcrc32c sd_mod t10_pi ibmvscsi ibmveth scsi_transport_srp vmx_crypto [last unloaded: scsi_debug] [44761.882852] CPU: 3 PID: 0 Comm: swapper/3 Tainted: G W 5.14.0-rc1+ #1 [44761.882858] NIP: c0080000063776d0 LR: c0080000063776b8 CTR: 000000007fffffff [44761.882864] REGS: c00000000d667400 TRAP: 0700 Tainted: G W (5.14.0-rc1+) [44761.882870] MSR: 8000000000029033 <SF,EE,ME,IR,DR,RI,LE> CR: 28022484 XER: 00000010 [44761.882882] CFAR: c0080000063776c8 IRQMASK: 0 GPR00: c0080000063776b8 c00000000d6676a0 c0080000063e8000 ffffffffffffffea GPR04: 000000000000000a c00000000d667620 ffffffffffffffc0 0000000000000000 GPR08: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 GPR12: c0080000063b63a0 c00000001ecacb00 0000000000000001 000000001ef3c860 GPR16: c000000002c97a00 0000000000000000 0000000000000000 c000000002aedd00 GPR20: c0000006ec355aa0 c000000000265ea0 c000000000265ea0 0000000000000012 GPR24: 000000000000000a c0000006ec355a00 c00000000d667750 c00000000104a600 GPR28: c000000002abda00 c00000000d5b1b00 c000000013c07800 c000000013c07bd8 [44761.882944] NIP [c0080000063776d0] assfail+0x54/0x70 [xfs] [44761.883020] LR [c0080000063776b8] assfail+0x3c/0x70 [xfs] [44761.883096] Call Trace: [44761.883099] [c00000000d6676a0] [c0080000063776b8] assfail+0x3c/0x70 [xfs] (unreliable) [44761.883180] [c00000000d667700] [c008000006230c08] __xfs_free_perag+0xb0/0xe0 [xfs] [44761.883241] [c00000000d667730] [c000000000265f0c] rcu_do_batch+0x25c/0x760 [44761.883249] [c00000000d6677f0] [c00000000026949c] rcu_core+0x25c/0x320 [44761.883256] [c00000000d667830] [c0000000010057bc] __do_softirq+0x22c/0x6fc [44761.883264] [c00000000d667940] [c00000000016f0f4] __irq_exit_rcu+0x234/0x250 [44761.883272] [c00000000d667970] [c00000000016f340] irq_exit+0x20/0x50 [44761.883279] [c00000000d667990] [c000000000029b00] timer_interrupt+0x1c0/0x540 [44761.883287] [c00000000d667a00] [c000000000009a00] decrementer_common_virt+0x210/0x220 [44761.883294] --- interrupt: 900 at plpar_hcall_norets_notrace+0x18/0x2c [44761.883301] NIP: c0000000000ff47c LR: c000000000c19a14 CTR: 0000000000000000 [44761.883306] REGS: c00000000d667a70 TRAP: 0900 Tainted: G W (5.14.0-rc1+) [44761.883311] MSR: 800000000280b033 <SF,VEC,VSX,EE,FP,ME,IR,DR,RI,LE> CR: 28000888 XER: 20040010 [44761.883326] CFAR: 0000000000000c00 IRQMASK: 0 GPR00: 0000000000000000 c00000000d667d10 c000000002c72b00 0000000000000000 GPR04: 00000000000000c0 0000000000000080 001edb18e2640b62 000000000000013a GPR08: 000000000001f400 0000000000000001 0000000000000000 0000000000000000 GPR12: 0000000000000000 c00000001ecacb00 0000000000000000 000000001ef3c860 GPR16: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 GPR20: 0000000000000000 0000000000000000 0000000000000000 0000000000000001 GPR24: 0000000000000003 0000000000000000 000028b5efdcbe70 0000000000000001 GPR28: 0000000000000000 0000000000000001 c000000002151bb8 c000000002151bc0 [44761.883388] NIP [c0000000000ff47c] plpar_hcall_norets_notrace+0x18/0x2c [44761.883394] LR [c000000000c19a14] check_and_cede_processor.part.0+0x24/0x70 [44761.883401] --- interrupt: 900 [44761.883404] [c00000000d667d10] [0000000000000000] 0x0 (unreliable) [44761.883411] [c00000000d667d70] [c000000000c1a034] dedicated_cede_loop+0x164/0x210 [44761.883419] [c00000000d667db0] [c000000000c169bc] cpuidle_enter_state+0x2bc/0x500 [44761.883426] [c00000000d667e10] [c000000000c16c9c] cpuidle_enter+0x4c/0x70 [44761.883432] [c00000000d667e50] [c0000000001cfe60] cpuidle_idle_call+0x1c0/0x2f0 [44761.883440] [c00000000d667ea0] [c0000000001d0114] do_idle+0x184/0x240 [44761.883450] [c00000000d667f00] [c0000000001d05a8] cpu_startup_entry+0x38/0x40 [44761.883458] [c00000000d667f30] [c000000000062bf0] start_secondary+0x280/0x2a0 [44761.883465] [c00000000d667f90] [c00000000000d254] start_secondary_prolog+0x10/0x14 [44761.883472] Instruction dump: [44761.883476] e888dc70 7d264b78 7d455378 f8010010 f821ffa1 4bfff70d 3d220000 e929dc78 [44761.883487] 8929000c 2c090000 41820008 0fe00000 <0fe00000> 38210060 e8010010 7c0803a6 [44761.883499] irq event stamp: 242794715 [44761.883502] hardirqs last enabled at (242794715): [<c0000000000174f4>] prep_irq_for_idle+0x44/0x70 [44761.883510] hardirqs last disabled at (242794714): [<c0000000001d00cc>] do_idle+0x13c/0x240 [44761.883516] softirqs last enabled at (242794710): [<c000000001005c1c>] __do_softirq+0x68c/0x6fc [44761.883524] softirqs last disabled at (242794701): [<c00000000016f0f4>] __irq_exit_rcu+0x234/0x250 [44761.883531] ---[ end trace 2ec6fe5d770be2e2 ]--- [44762.065716] XFS (sda5): Unmounting Filesystem .full output as below: meta-data=/dev/sda3 isize=512 agcount=4, agsize=3932160 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=15728640, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=10240, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 meta-data=/dev/sda3 isize=512 agcount=4, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=128000, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 *** stressing a 128000 block filesystem seed = 1628351693 *** growing to a 171008 block filesystem meta-data=/dev/sda3 isize=512 agcount=4, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=128000, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 128000 to 171008 AGCOUNT=4 *** stressing a 171008 block filesystem seed = 1627435428 *** growing to a 214016 block filesystem meta-data=/dev/sda3 isize=512 agcount=6, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=171008, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 171008 to 214016 AGCOUNT=6 *** stressing a 214016 block filesystem seed = 1628102755 *** growing to a 257024 block filesystem meta-data=/dev/sda3 isize=512 agcount=7, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=214016, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 214016 to 257024 AGCOUNT=7 *** stressing a 257024 block filesystem seed = 1627616710 *** growing to a 300032 block filesystem meta-data=/dev/sda3 isize=512 agcount=9, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=257024, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 257024 to 300032 AGCOUNT=9 *** stressing a 300032 block filesystem seed = 1627863592 *** growing to a 343040 block filesystem meta-data=/dev/sda3 isize=512 agcount=10, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=300032, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 300032 to 343040 AGCOUNT=10 *** stressing a 343040 block filesystem seed = 1628039937 *** growing to a 386048 block filesystem meta-data=/dev/sda3 isize=512 agcount=11, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=343040, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 343040 to 386048 AGCOUNT=11 *** stressing a 386048 block filesystem seed = 1627624784 *** growing to a 429056 block filesystem meta-data=/dev/sda3 isize=512 agcount=13, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=386048, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 386048 to 429056 AGCOUNT=13 *** stressing a 429056 block filesystem seed = 1627859438 *** growing to a 472064 block filesystem meta-data=/dev/sda3 isize=512 agcount=14, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=429056, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 429056 to 472064 AGCOUNT=14 *** stressing a 472064 block filesystem seed = 1628419199 *** growing to a 515072 block filesystem meta-data=/dev/sda3 isize=512 agcount=15, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=472064, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 472064 to 515072 AGCOUNT=15 *** stressing a 515072 block filesystem seed = 1627821002 *** growing to a 558080 block filesystem meta-data=/dev/sda3 isize=512 agcount=17, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=515072, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 515072 to 558080 AGCOUNT=17 *** stressing a 558080 block filesystem seed = 1627556624 *** growing to a 601088 block filesystem meta-data=/dev/sda3 isize=512 agcount=18, agsize=32000 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=1, sparse=1, rmapbt=1 = reflink=1 bigtime=1 inobtcount=1 data = bsize=1024 blocks=558080, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0, ftype=1 log =internal log bsize=1024 blocks=5363, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 558080 to 601088 AGCOUNT=18
FYI: Hit again: [38631.895571] run fstests xfs/104 at 2021-08-13 01:26:58 [38638.618323] XFS (sda3): Mounting V5 Filesystem [38638.669558] XFS (sda3): Ending clean mount [38641.815797] XFS (sda3): xlog_verify_grant_tail: space > BBTOB(tail_blocks) [38654.539685] XFS: Assertion failed: agno < mp->m_sb.sb_agcount, file: fs/xfs/libxfs/xfs_types.c, line: 22 [38654.544409] ------------[ cut here ]------------ [38654.545856] WARNING: CPU: 1 PID: 779087 at fs/xfs/xfs_message.c:112 assfail+0x56/0x59 [xfs] [38654.549424] Modules linked in: dm_flakey dm_mod rfkill snd_hda_codec_generic ledtrig_audio snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hda_core snd_hwdep kvm_intel qxl snd_seq sunrpc drm_ttm_helper ttm snd_seq_device kvm drm_kms_helper snd_pcm syscopyarea snd_timer sysfillrect snd sysimgblt fb_sys_fops irqbypass cec virtio_balloon joydev soundcore i2c_piix4 pcspkr drm fuse xfs libcrc32c sd_mod t10_pi ata_generic ata_piix crct10dif_pclmul libata crc32_pclmul crc32c_intel virtio_console 8139too ghash_clmulni_intel 8139cp serio_raw mii [38654.564435] CPU: 1 PID: 779087 Comm: xfsaild/sda3 Kdump: loaded Not tainted 5.14.0-rc4+ #1 [38654.566837] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [38654.568542] RIP: 0010:assfail+0x56/0x59 [xfs] [38654.570389] Code: 2a 83 e0 07 48 c1 e9 03 8a 14 11 38 c2 7f 10 84 d2 74 0c 48 c7 c7 4c 91 a0 c0 e8 bb 53 ca d0 80 3d b0 06 17 00 00 74 02 0f 0b <0f> 0b c3 48 8d 45 10 48 8d 54 24 28 4c 89 f6 48 c7 c7 40 6c 8c c0 [38654.576770] RSP: 0018:ffffc90001837818 EFLAGS: 00010246 [38654.578744] RAX: 0000000000000004 RBX: 000000000000000f RCX: 1ffffffff8141229 [38654.581257] RDX: dffffc0000000000 RSI: dffffc0000000000 RDI: fffff52000306ef5 [38654.583635] RBP: 00000000000e6440 R08: ffffc900018376a8 R09: ffff8881793f1647 [38654.586213] R10: ffffed102f27e2c8 R11: 0000000000000001 R12: ffff88810f10c000 [38654.588768] R13: ffffed1021e2180b R14: ffff88810f10c1e7 R15: dffffc0000000000 [38654.591376] FS: 0000000000000000(0000) GS:ffff888179200000(0000) knlGS:0000000000000000 [38654.594278] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [38654.596388] CR2: 00000000008e3000 CR3: 00000001022e2004 CR4: 00000000000206e0 [38654.599058] Call Trace: [38654.600445] xfs_verify_icount+0x262/0x3c0 [xfs] [38654.602559] ? xfs_validate_sb_common+0x98e/0x1160 [xfs] [38654.604801] xfs_validate_sb_write.isra.0+0xf2/0x3c0 [xfs] [38654.607036] xfs_sb_write_verify+0x1ac/0x3b0 [xfs] [38654.609016] ? xfs_validate_sb_common+0x1160/0x1160 [xfs] [38654.617256] ? validate_chain+0x14c/0xde0 [38654.618986] _xfs_buf_ioapply+0x15f/0x5b0 [xfs] [38654.620708] ? xfs_buf_wait_unpin+0xc3/0x2a0 [xfs] [38654.622410] ? xfs_buf_ioapply_map+0x690/0x690 [xfs] [38654.624207] ? __lock_contended+0x910/0x910 [38654.625541] ? _xfs_buf_map_pages+0x420/0x420 [xfs] [38654.627298] ? do_raw_spin_trylock+0xb5/0x180 [38654.628875] ? wake_up_q+0xf0/0xf0 [38654.630327] ? __xfs_buf_submit+0x128/0x690 [xfs] [38654.632046] ? xfs_buf_delwri_submit_buffers+0x329/0xac0 [xfs] [38654.634045] __xfs_buf_submit+0x21c/0x690 [xfs] [38654.635805] xfs_buf_delwri_submit_buffers+0x329/0xac0 [xfs] [38654.637787] ? __lock_release+0x494/0xa40 [38654.639094] ? xfs_buf_ioend_work+0x20/0x20 [xfs] [38654.640764] ? xfsaild_push+0x424/0x1bf0 [xfs] [38654.642656] xfsaild_push+0x42e/0x1bf0 [xfs] [38654.644274] ? xfs_trans_ail_cursor_first+0x180/0x180 [xfs] [38654.646182] xfsaild+0x136/0x950 [xfs] [38654.647614] ? xfsaild_push+0x1bf0/0x1bf0 [xfs] [38654.649246] kthread+0x329/0x3e0 [38654.650260] ? _raw_spin_unlock_irq+0x24/0x30 [38654.651915] ? set_kthread_struct+0x100/0x100 [38654.653433] ret_from_fork+0x22/0x30 [38654.654782] irq event stamp: 13451 [38654.656026] hardirqs last enabled at (13461): [<ffffffff90f7bdbd>] console_unlock+0x4dd/0x5f0 [38654.658549] hardirqs last disabled at (13470): [<ffffffff90f7bd4e>] console_unlock+0x46e/0x5f0 [38654.661126] softirqs last enabled at (13070): [<ffffffff930005ca>] __do_softirq+0x5ca/0x90f [38654.663561] softirqs last disabled at (13065): [<ffffffff90dfb8a7>] __irq_exit_rcu+0x207/0x280 [38654.666485] ---[ end trace 64794ca72522b1a1 ]--- [38768.778662] XFS (sda3): Unmounting Filesystem [38769.401075] XFS: Assertion failed: atomic_read(&pag->pag_ref) == 0, file: fs/xfs/libxfs/xfs_ag.c, line: 195 [38769.404514] ------------[ cut here ]------------ [38769.406150] WARNING: CPU: 5 PID: 779398 at fs/xfs/xfs_message.c:112 assfail+0x56/0x59 [xfs] [38769.410661] Modules linked in: dm_flakey dm_mod rfkill snd_hda_codec_generic ledtrig_audio snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hda_core snd_hwdep kvm_intel qxl snd_seq sunrpc drm_ttm_helper ttm snd_seq_device kvm drm_kms_helper snd_pcm syscopyarea snd_timer sysfillrect snd sysimgblt fb_sys_fops irqbypass cec virtio_balloon joydev soundcore i2c_piix4 pcspkr drm fuse xfs libcrc32c sd_mod t10_pi ata_generic ata_piix crct10dif_pclmul libata crc32_pclmul crc32c_intel virtio_console 8139too ghash_clmulni_intel 8139cp serio_raw mii [38769.426722] CPU: 5 PID: 779398 Comm: umount Kdump: loaded Tainted: G W 5.14.0-rc4+ #1 [38769.429849] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [38769.431565] RIP: 0010:assfail+0x56/0x59 [xfs] [38769.433184] Code: 2a 83 e0 07 48 c1 e9 03 8a 14 11 38 c2 7f 10 84 d2 74 0c 48 c7 c7 4c 91 a0 c0 e8 bb 53 ca d0 80 3d b0 06 17 00 00 74 02 0f 0b <0f> 0b c3 48 8d 45 10 48 8d 54 24 28 4c 89 f6 48 c7 c7 40 6c 8c c0 [38769.438438] RSP: 0018:ffffc900026afcf8 EFLAGS: 00010246 [38769.439999] RAX: 0000000000000004 RBX: ffff88810bbd9000 RCX: 1ffffffff8141229 [38769.442033] RDX: dffffc0000000000 RSI: dffffc0000000000 RDI: fffff520004d5f91 [38769.444123] RBP: 0000000000000010 R08: ffffc900026afb88 R09: ffff88817a3f1647 [38769.446191] R10: ffffed102f47e2c8 R11: 0000000000000001 R12: ffff88810f10c6c8 [38769.448223] R13: ffff88810f10c000 R14: ffff88810bbd900c R15: ffff88810f10c670 [38769.450245] FS: 00007f00b1d834c0(0000) GS:ffff88817a200000(0000) knlGS:0000000000000000 [38769.452525] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [38769.454183] CR2: 00007f41f9342000 CR3: 0000000105ae6002 CR4: 00000000000206e0 [38769.456221] Call Trace: [38769.457078] xfs_free_perag+0x152/0x1a0 [xfs] [38769.458588] xfs_unmountfs+0x112/0x1b0 [xfs] [38769.460120] ? xfs_mountfs+0x1a50/0x1a50 [xfs] [38769.461687] ? kfree+0xe0/0x4b0 [38769.462782] xfs_fs_put_super+0x67/0x340 [xfs] [38769.464422] generic_shutdown_super+0x136/0x330 [38769.466072] kill_block_super+0x95/0xd0 [38769.467291] deactivate_locked_super+0x8d/0x140 [38769.468630] cleanup_mnt+0x31f/0x4a0 [38769.469759] task_work_run+0xce/0x170 [38769.470918] exit_to_user_mode_loop+0x180/0x190 [38769.472297] exit_to_user_mode_prepare+0xf4/0x170 [38769.473741] syscall_exit_to_user_mode+0x19/0x60 [38769.475162] do_syscall_64+0x48/0x90 [38769.476275] entry_SYSCALL_64_after_hwframe+0x44/0xae [38769.477772] RIP: 0033:0x7f00b1f8f74b [38769.478893] Code: e3 86 0e 00 f7 d8 64 89 01 48 83 c8 ff c3 90 f3 0f 1e fa 31 f6 e9 05 00 00 00 0f 1f 44 00 00 f3 0f 1e fa b8 a6 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 05 c3 0f 1f 40 00 48 8b 15 a9 86 0e 00 f7 d8 [38769.484046] RSP: 002b:00007ffee1af3fd8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6 [38769.486368] RAX: 0000000000000000 RBX: 000055e495a8b630 RCX: 00007f00b1f8f74b [38769.488421] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 000055e495a900e0 [38769.490456] RBP: 000055e495a8b400 R08: 0000000000000000 R09: 00007ffee1af2d60 [38769.492492] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 [38769.494524] R13: 000055e495a900e0 R14: 000055e495a8b510 R15: 000055e495a8b400 [38769.496678] irq event stamp: 709585 [38769.497757] hardirqs last enabled at (709595): [<ffffffff90f7bdbd>] console_unlock+0x4dd/0x5f0 [38769.500215] hardirqs last disabled at (709604): [<ffffffff90f7bd4e>] console_unlock+0x46e/0x5f0 [38769.502653] softirqs last enabled at (709272): [<ffffffff930005ca>] __do_softirq+0x5ca/0x90f [38769.505156] softirqs last disabled at (709263): [<ffffffff90dfb8a7>] __irq_exit_rcu+0x207/0x280 [38769.507670] ---[ end trace 64794ca72522b1a2 ]--- [38769.521736] XFS: Assertion failed: atomic_read(&pag->pag_ref) == 0, file: fs/xfs/libxfs/xfs_ag.c, line: 176 [38769.524773] ------------[ cut here ]------------ [38769.526538] WARNING: CPU: 5 PID: 0 at fs/xfs/xfs_message.c:112 assfail+0x56/0x59 [xfs]
*** This bug has been marked as a duplicate of bug 213625 ***