Re: [xfs] 1fc3f2c3c9: xfstests.xfs.529.fail

["Darrick J. Wong" <djwong@kernel.org>]

[add chandan to cc since he wrote the extent count checking patches]

On Wed, Mar 23, 2022 at 03:08:34PM +0800, Oliver Sang wrote:
> hi, Darrick,
> 
> On Tue, Mar 22, 2022 at 09:27:33AM -0700, Darrick J. Wong wrote:
> > On Tue, Mar 22, 2022 at 09:54:02PM +0800, kernel test robot wrote:
> > > 
> > > 
> > > Greeting,
> > > 
> > > FYI, we noticed the following commit (built with gcc-9):
> > > 
> > > commit: 1fc3f2c3c928c88654d4716af5cc4dee07b3a1c4 ("xfs: only free posteof blocks on first close")
> > > https://git.kernel.org/cgit/linux/kernel/git/djwong/xfs-linux.git vectorized-scrub
> > > 
> > > in testcase: xfstests
> > > version: xfstests-x86_64-1de1db8-1_20220217
> > > with following parameters:
> > > 
> > > 	disk: 4HDD
> > > 	fs: xfs
> > > 	test: xfs-group-52
> > > 	ucode: 0x21
> > > 
> > > test-description: xfstests is a regression test suite for xfs and other files ystems.
> > > test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git
> > > 
> > > 
> > > on test machine: 4 threads 1 sockets Intel(R) Core(TM) i3-3220 CPU @ 3.30GHz with 8G memory
> > > 
> > > caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
> > > 
> > > 
> > > If you fix the issue, kindly add following tag
> > > Reported-by: kernel test robot <oliver.sang@intel.com>
> > > 
> > 
> > Could you export DIFF_LENGTH=0 in the test environment so that we get
> > the full diff here, please?
> 
> the full diff is as below (also attached dmesg, full log, log for 529 in case
> helpful):
> 
> xfs/529 [failed, exit status 1]- output mismatch (see /lkp/benchmarks/xfstests/results//xfs/529.out.bad)
>     --- tests/xfs/529.out       2022-02-17 11:55:00.000000000 +0000
>     +++ /lkp/benchmarks/xfstests/results//xfs/529.out.bad       2022-03-23 06:18:39.815036267 +0000
>     @@ -4,21 +4,4 @@
>      Inject reduce_max_iextents error tag
>      Create fragmented file
>      Verify $testfile's extent count
>     -Disable reduce_max_iextents error tag
>     -* Fallocate unwritten extents
>     -Inject reduce_max_iextents error tag
>     -Fallocate fragmented file
>     -Verify $testfile's extent count
>     -Disable reduce_max_iextents error tag
>     -* Directio write
>     -Inject reduce_max_iextents error tag
>     -Create fragmented file via directio writes
>     -Verify $testfile's extent count
>     -Disable reduce_max_iextents error tag
>     -* Extend quota inodes
>     -Consume free space
>     -Create fragmented filesystem
>     -Inject reduce_max_iextents error tag
>     -Inject bmap_alloc_minlen_extent error tag
>     -Extend uquota file
>     -Verify uquota inode's extent count
>     +Extent count overflow check failed: nextents = 15

Hey Chandan, could you take a look at this, please?

--D

> 
> 
> > 
> > --D
> > 


> 2022-03-23 06:18:24 export TEST_DIR=/fs/sdb1
> 2022-03-23 06:18:24 export TEST_DEV=/dev/sdb1
> 2022-03-23 06:18:24 export FSTYP=xfs
> 2022-03-23 06:18:24 export SCRATCH_MNT=/fs/scratch
> 2022-03-23 06:18:24 mkdir /fs/scratch -p
> 2022-03-23 06:18:24 export SCRATCH_DEV=/dev/sdb4
> 2022-03-23 06:18:24 export SCRATCH_LOGDEV=/dev/sdb2
> 2022-03-23 06:18:24 export SCRATCH_XFS_LIST_METADATA_FIELDS=u3.sfdir3.hdr.parent.i4
> 2022-03-23 06:18:24 export SCRATCH_XFS_LIST_FUZZ_VERBS=random
> DIFF_LENGTH=
> 0
> END
> 2022-03-23 06:18:24 sed "s:^:xfs/:" //lkp/benchmarks/xfstests/tests/xfs-group-52
> 2022-03-23 06:18:24 ./check xfs/520 xfs/522 xfs/523 xfs/524 xfs/525 xfs/526 xfs/527 xfs/529
> FSTYP         -- xfs (debug)
> PLATFORM      -- Linux/x86_64 lkp-ivb-d05 5.17.0-rc8-00340-g1fc3f2c3c928 #1 SMP Mon Mar 21 07:17:57 CST 2022
> MKFS_OPTIONS  -- -f /dev/sdb4
> MOUNT_OPTIONS -- /dev/sdb4 /fs/scratch
> 
> xfs/520	 3s
> xfs/522	[not run] need configuration file support in mkfs.xfs
> xfs/523	[not run] need configuration file support in mkfs.xfs
> xfs/524	[not run] need configuration file support in mkfs.xfs
> xfs/525	[not run] need configuration file support in mkfs.xfs
> xfs/526	[not run] need configuration file support in mkfs.xfs
> xfs/527	 2s
> xfs/529	[failed, exit status 1]- output mismatch (see /lkp/benchmarks/xfstests/results//xfs/529.out.bad)
>     --- tests/xfs/529.out	2022-02-17 11:55:00.000000000 +0000
>     +++ /lkp/benchmarks/xfstests/results//xfs/529.out.bad	2022-03-23 06:18:39.815036267 +0000
>     @@ -4,21 +4,4 @@
>      Inject reduce_max_iextents error tag
>      Create fragmented file
>      Verify $testfile's extent count
>     -Disable reduce_max_iextents error tag
>     -* Fallocate unwritten extents
>     -Inject reduce_max_iextents error tag
>     -Fallocate fragmented file
>     -Verify $testfile's extent count
>     -Disable reduce_max_iextents error tag
>     -* Directio write
>     -Inject reduce_max_iextents error tag
>     -Create fragmented file via directio writes
>     -Verify $testfile's extent count
>     -Disable reduce_max_iextents error tag
>     -* Extend quota inodes
>     -Consume free space
>     -Create fragmented filesystem
>     -Inject reduce_max_iextents error tag
>     -Inject bmap_alloc_minlen_extent error tag
>     -Extend uquota file
>     -Verify uquota inode's extent count
>     +Extent count overflow check failed: nextents = 15
> Ran: xfs/520 xfs/522 xfs/523 xfs/524 xfs/525 xfs/526 xfs/527 xfs/529
> Not run: xfs/522 xfs/523 xfs/524 xfs/525 xfs/526
> Failures: xfs/529
> Failed 1 of 8 tests
> 

> meta-data=/dev/sdb4              isize=512    agcount=4, agsize=32768 blks
>          =                       sectsz=4096  attr=2, projid32bit=1
>          =                       crc=1        finobt=1, sparse=1, rmapbt=0
>          =                       reflink=0
> data     =                       bsize=4096   blocks=131072, imaxpct=25
>          =                       sunit=0      swidth=0 blks
> naming   =version 2              bsize=4096   ascii-ci=0, ftype=1
> log      =internal log           bsize=4096   blocks=1605, version=2
>          =                       sectsz=4096  sunit=1 blks, lazy-count=1
> realtime =none                   extsz=4096   blocks=0, rtextents=0
> wrote 4096/4096 bytes at offset 0
> 4 KiB, 1 ops; 0.0185 sec (216.099 KiB/sec and 54.0249 ops/sec)
> wrote 4096/4096 bytes at offset 8192
> 4 KiB, 1 ops; 0.0157 sec (253.888 KiB/sec and 63.4719 ops/sec)
> wrote 4096/4096 bytes at offset 16384
> 4 KiB, 1 ops; 0.0158 sec (251.620 KiB/sec and 62.9050 ops/sec)
> wrote 4096/4096 bytes at offset 24576
> 4 KiB, 1 ops; 0.0160 sec (248.648 KiB/sec and 62.1620 ops/sec)
> wrote 4096/4096 bytes at offset 32768
> 4 KiB, 1 ops; 0.0160 sec (249.423 KiB/sec and 62.3558 ops/sec)
> wrote 4096/4096 bytes at offset 40960
> 4 KiB, 1 ops; 0.0158 sec (251.604 KiB/sec and 62.9010 ops/sec)
> wrote 4096/4096 bytes at offset 49152
> 4 KiB, 1 ops; 0.0161 sec (247.755 KiB/sec and 61.9387 ops/sec)
> wrote 4096/4096 bytes at offset 57344
> 4 KiB, 1 ops; 0.0159 sec (250.878 KiB/sec and 62.7195 ops/sec)
> pwrite: File too large
> pwrite: File too large
> pwrite: File too large
> pwrite: File too large
> pwrite: File too large
> pwrite: File too large
> pwrite: File too large

> QA output created by 529
> Format and mount fs
> * Delalloc to written extent conversion
> Inject reduce_max_iextents error tag
> Create fragmented file
> Verify $testfile's extent count
> Extent count overflow check failed: nextents = 15