From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from mail-it0-f41.google.com ([209.85.214.41]:33192 "EHLO mail-it0-f41.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1727371AbeISXSa (ORCPT ); Wed, 19 Sep 2018 19:18:30 -0400 Received: by mail-it0-f41.google.com with SMTP id j198-v6so17828865ita.0 for ; Wed, 19 Sep 2018 10:39:32 -0700 (PDT) MIME-Version: 1.0 References: <20180917211504.GA21269@fieldses.org> <20180917220107.GB21269@fieldses.org> <20180918181901.GC1218@fieldses.org> In-Reply-To: <20180918181901.GC1218@fieldses.org> From: Stan Hu Date: Wed, 19 Sep 2018 10:39:19 -0700 Message-ID: Subject: Re: Stale data after file is renamed while another process has an open file handle To: Bruce Fields Cc: linux-nfs@vger.kernel.org Content-Type: text/plain; charset="UTF-8" Sender: linux-nfs-owner@vger.kernel.org List-ID: On Tue, Sep 18, 2018 at 11:19 AM J. Bruce Fields wrote: > We know node B has that cat loop that will keep reopening the file. > > The only way node B could avoid translating those open syscalls into > on-the-wire OPENs is if the client holds a delegation. > > But it can't hold a delegation on the file that was newly renamed to > test.txt--delegations are revoked on rename, and it would need to do > another OPEN after the rename to get a new delegation. Similarly the > file that gets renamed over should have its delegation revoked--and we > can see that the client does return that delegation. The OPEN here is > actually part of that delegation return process--the CLAIM_DELEGATE_CUR > value on "claim type" is telling the server that this is an open that > the client had cached locally under the delegation it is about to > return. > > Looks like a client bug to me, possibly some sort of race handling the > delegation return and the new open. > > It might help if it were possible to confirm that this is still > reproduceable on the latest upstream kernel. Thanks for that information. I did more testing, and it looks like this stale file problem only appears to happen when the NFS client protocol is 4.0 (via the vers=4.0 mount option). 4.1 doesn't appear to have the problem. I've also confirmed this problem happens on the mainline kernel version (4.19.0-rc4). Do you have any idea why 4.1 would be working but 4.0 has this bug? https://s3.amazonaws.com/gitlab-support/nfs/nfs-4.0-kernel-4.19-0-rc4-rename.pcap is the latest capture that also includes the NFS callbacks. Here's what I see after the first RENAME from Node A: Node B: DELEGRETURN StateId: 0xa93 NFS server: DELEGRETURN Node A: RENAME From: test2.txt To: test.txt NFS server: RENAME Node B: GETATTR NFS Server: GETATTR (with old inode) Node B: READ StateId: 0xa93 NFS Server: READ In comparison, if I don't have a process with an open file to test.txt, things work and the trace looks like: Node B: DELEGRETURN StateId: 0xa93 NFS server: DELEGRETURN Node A: RENAME From: test2.txt To: test.txt NFS server: RENAME Node B: OPEN test.txt NFS Server: OPEN StateID: 0xa93 Node B: CLOSE StateID: 0xa93 NFS Server: CLOSE Node B: OPEN test.txt NFS Server: OPEN StateId: 0xa93 Node B: READ StateID: 0xa93 NFS Server: READ In the first case, since the client reused the StateId that it should have released in DELEGRETURN, does this suggest that perhaps the client isn't properly releasing that delegation? How might the open file affect this behavior? Any pointers to where things might be going awry in the code base would be appreciated here. > > --b. > > > > > On Mon, Sep 17, 2018 at 3:16 PM Stan Hu wrote: > > > > > > Attached is the compressed pcap of port 2049 traffic. The file is > > > pretty large because the while loop generated a fair amount of > > > traffic. > > > > > > On Mon, Sep 17, 2018 at 3:01 PM J. Bruce Fields wrote: > > > > > > > > On Mon, Sep 17, 2018 at 02:37:16PM -0700, Stan Hu wrote: > > > > > On Mon, Sep 17, 2018 at 2:15 PM J. Bruce Fields wrote: > > > > > > > > > > > Sounds like a bug to me, but I'm not sure where. What filesystem are > > > > > > you exporting? How much time do you think passes between steps 1 and 4? > > > > > > (I *think* it's possible you could hit a bug caused by low ctime > > > > > > granularity if you could get from step 1 to step 4 in less than a > > > > > > millisecond.) > > > > > > > > > > For CentOS, I am exporting xfs. In Ubuntu, I think I was using ext4. > > > > > > > > > > Steps 1 through 4 are all done by hand, so I don't think we're hitting > > > > > a millisecond issue. Just for good measure, I've done experiments > > > > > where I waited a few minutes between steps 1 and 4. > > > > > > > > > > > Those kernel versions--are those the client (node A and B) versions, or > > > > > > the server versions? > > > > > > > > > > The client and server kernel versions are the same across the board. I > > > > > didn't mix and match kernels. > > > > > > > > > > > > Note that with an Isilon NFS server, instead of seeing stale content, > > > > > > > I see "Stale file handle" errors indefinitely unless I perform one of > > > > > > > the corrective steps. > > > > > > > > > > > > You see "stale file handle" errors from the "cat test1.txt"? That's > > > > > > also weird. > > > > > > > > > > Yes, this is the problem I'm actually more concerned about, which led > > > > > to this investigation in the first place. > > > > > > > > It might be useful to look at the packets on the wire. So, run > > > > something on the server like: > > > > > > > > tcpdump -wtmp.pcap -s0 -ieth0 > > > > > > > > (replace eth0 by the relevant interface), then run the test, then kill > > > > the tcpdump and take a look at tmp.pcap in wireshark, or send tmp.pcap > > > > to the list (as long as there's no sensitive info in there). > > > > > > > > What we'd be looking for: > > > > - does the rename cause the directory's change attribute to > > > > change? > > > > - does the server give out a delegation, and, if so, does it > > > > return it before allowing the rename? > > > > - does the client do an open by filehandle or an open by name > > > > after the rename? > > > > > > > > --b.