Received-SPF: pass (google.com: domain of linux-nfs-owner@vger.kernel.org designates 23.128.96.18 as permitted sender) client-ip=23.128.96.18;
Content-Type: text/plain;
        charset=us-ascii
Mime-Version: 1.0 (Mac OS X Mail 13.4 \(3608.120.23.2.1\))
Subject: Re: still seeing single client NFS4ERR_DELAY / CB_RECALL
From:   Chuck Lever <chuck.lever@oracle.com>
In-Reply-To: <20200819212927.GB30476@fieldses.org>
Date:   Mon, 24 Aug 2020 09:39:31 -0400
Cc:     Linux NFS Mailing List <linux-nfs@vger.kernel.org>
Content-Transfer-Encoding: quoted-printable
Message-Id: <5D346E9E-C7C5-49F7-9694-8DD98AF1149A@oracle.com>
References: <20200809202739.GA29574@fieldses.org>
 <20200809212531.GB29574@fieldses.org>
 <227E18E8-5A45-47E3-981C-549042AFB391@oracle.com>
 <20200810190729.GB13266@fieldses.org>
 <00CAA5B7-418E-4AB5-AE08-FE2F87B06795@oracle.com>
 <20200810201001.GC13266@fieldses.org>
 <CA3288FC-8B9A-4F19-A51C-E1169726E946@oracle.com>
 <F20E4EC5-71DD-4A92-A583-41BEE177F53C@oracle.com>
 <20200817222034.GA6390@fieldses.org>
 <CD4B80B9-4F58-46B4-872C-F2F139AFB231@oracle.com>
 <20200819212927.GB30476@fieldses.org>
To:     Bruce Fields <bfields@fieldses.org>,
        Jeff Layton <jlayton@redhat.com>
Sender: linux-nfs-owner@vger.kernel.org
Precedence: bulk


> On Aug 19, 2020, at 5:29 PM, Bruce Fields <bfields@fieldses.org> =
wrote:
>=20
> On Tue, Aug 18, 2020 at 05:26:26PM -0400, Chuck Lever wrote:
>>=20
>>> On Aug 17, 2020, at 6:20 PM, Bruce Fields <bfields@fieldses.org> =
wrote:
>>>=20
>>> On Sun, Aug 16, 2020 at 04:46:00PM -0400, Chuck Lever wrote:
>>>=20
>>>> In order of application:
>>>>=20
>>>> 5920afa3c85f ("nfsd: hook nfsd_commit up to the nfsd_file cache")
>>>> 961.68user 5252.40system 20:12.30elapsed 512%CPU, 2541 DELAY errors
>>>> These results are similar to v5.3.
>>>>=20
>>>> fd4f83fd7dfb ("nfsd: convert nfs4_file->fi_fds array to use =
nfsd_files")
>>>> Does not build
>>>>=20
>>>> eb82dd393744 ("nfsd: convert fi_deleg_file and ls_file fields to =
nfsd_file")
>>>> 966.92user 5425.47system 33:52.79elapsed 314%CPU, 1330 DELAY errors
>>>>=20
>>>> Can you take a look and see if there's anything obvious?
>>>=20
>>> Unfortunately nothing about the file cache code is very obvious to =
me.
>>> I'm looking at it....
>>>=20
>>> It adds some new nfserr_jukebox returns in nfsd_file_acquire.  Those
>>> mostly look like kmalloc failures, the one I'm not sure about is the
>>> NFSD_FILE_HASHED check.
>>>=20
>>> Or maybe it's the lease break there.
>>=20
>> nfsd_file_acquire() always calls fh_verify() before it invokes =
nfsd_open().
>> Replacing nfs4_get_vfs_file's nfsd_open() call with =
nfsd_file_acquire() adds
>> almost 10 million fh_verify() calls to my test run.
>=20
> Checking out the code as of fd4f83fd7dfb....
>=20
> nfsd_file_acquire() calls nfsd_open_verified().
>=20
> And nfsd_open() is basically just fh_verify()+nfsd_open_verified().
>=20
> So it doesn't look like the replacement of nfsd_open() by
> nfsd_file_acquire() should have changed the number of fh_verify() =
calls.

I see a lot more vfs_setlease() failures after fd4f83fd7dfb.
check_conflicting_open() fails because "inode is open for write":

1780         if (arg =3D=3D F_RDLCK)
1781                 return inode_is_open_for_write(inode) ? -EAGAIN : =
0;

The behavior on the wire is that the server simply doesn't hand out
many delegations.

NFSv4 OPEN uses nfsd_file_acquire() now, but I don't see CLOSE
releasing the cached file descriptor. Wouldn't that cached
descriptor conflict with subsequent OPENs?


--
Chuck Lever