Weird lockups when using NFSv4
Fredrik Tolf
fredrik at dolda2000.com
Thu Aug 9 21:52:41 EDT 2007
Hi all,
I'm using NFSv4 on my home network regularly these days, and it works
great most of the time. However, every once in a while, I get a weird
problem where some client will lock up for a seemingly random interval,
ranging from a few seconds up to more than a minute. When I say "lock
up", I mean that one, several or all processes (seemingly randomly
selected) will sleep uninterruptibly when trying read or write from the
NFS mount.
I'm trying to debug it, but I'm having some trouble due to not really
knowing the NFS code. I'll try and present the facts that I know right
now and hope that someone will be able to help me a bit on the way.
* It happens on more than one client.
* While sleeping, `ps -l' indicates that the affected processes are
sleeping in a function beginning with "sync_p" (the WCHAN field is
truncated after that), which I'm assuming is one of the variations of
sync_page*.
* If I don't turn on NFS debugging via the relevant sysctls, I don't get
any dmesgs at all on either the client or the server.
When it happened recently, I had prepared for it and set the sysctl
sunrpc.nfs_debug to 65535 (on the client). The output got a little bit
long, so I'm attaching it as a separate MIME part. It begins with what
appears to be the last read call before the lockup occurs, at 23:05:23.
After that comes a few messages at 23:05:37, which appear to be
unrelated since they appear regularly about 1 minute apart. Then, at
23:05:56 comes the next read call. My analysis is as follows:
> Aug 9 23:05:23 pc6 kernel: NFS: nfs_readpages (0:12/885372 120)
This is the actual request from user space to read 120 pages of 4096
bytes each, from the file with i-number 885372 on the NFS mount
identified at the client by the 0:12 device.
> Aug 9 23:05:23 pc6 kernel: NFS: 26645 initiated read call
> (req 0:12/885372, 32768 bytes @ offset 138608640)
> Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
(15 times)
This is the encoding of the user space request into 15 NFS read calls,
each reading 8 pages.
> Aug 9 23:05:23 pc6 kernel: NFS: 26645 nfs_readpage_result,
> (status 32768)
> Aug 9 23:05:23 pc6 kernel: NFS: read done
> (0:12/885372 4096 at 138608640)
(15 times, the last line repeated 8 times for each)
This the confirmation that the client has received the response from the
NFS server.
I had expected this to be a server issue, but if my analysis above is
correct, it appears that the client receives a proper response to each
read request, and then, for some reason, it just waits 33 seconds to
make the next call.
Is my reasoning correct? Does anyone have a clue as to why this happens?
Fredrik Tolf
-------------- next part --------------
Aug 9 23:05:23 pc6 kernel: NFS: nfs_readpages (0:12/885372 120)
Aug 9 23:05:23 pc6 kernel: NFS: 26645 initiated read call (req 0:12/885372, 32768 bytes @ offset 138608640)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26646 initiated read call (req 0:12/885372, 32768 bytes @ offset 138641408)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26647 initiated read call (req 0:12/885372, 32768 bytes @ offset 138674176)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26648 initiated read call (req 0:12/885372, 32768 bytes @ offset 138706944)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26649 initiated read call (req 0:12/885372, 32768 bytes @ offset 138739712)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26650 initiated read call (req 0:12/885372, 32768 bytes @ offset 138772480)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26651 initiated read call (req 0:12/885372, 32768 bytes @ offset 138805248)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26652 initiated read call (req 0:12/885372, 32768 bytes @ offset 138838016)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26653 initiated read call (req 0:12/885372, 32768 bytes @ offset 138870784)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26654 initiated read call (req 0:12/885372, 32768 bytes @ offset 138903552)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26655 initiated read call (req 0:12/885372, 32768 bytes @ offset 138936320)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26656 initiated read call (req 0:12/885372, 32768 bytes @ offset 138969088)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26657 initiated read call (req 0:12/885372, 32768 bytes @ offset 139001856)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26658 initiated read call (req 0:12/885372, 32768 bytes @ offset 139034624)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26659 initiated read call (req 0:12/885372, 32768 bytes @ offset 139067392)
Aug 9 23:05:23 pc6 kernel: encode_compound: tag=
Aug 9 23:05:23 pc6 kernel: NFS: 26645 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138608640)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138612736)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138616832)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138620928)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138625024)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138629120)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138633216)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138637312)
Aug 9 23:05:23 pc6 kernel: NFS: 26647 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138674176)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138678272)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138682368)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138686464)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138690560)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138694656)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138698752)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138702848)
Aug 9 23:05:23 pc6 kernel: NFS: 26646 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138641408)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138645504)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138649600)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138653696)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138657792)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138661888)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138665984)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138670080)
Aug 9 23:05:23 pc6 kernel: NFS: 26648 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138706944)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138711040)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138715136)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138719232)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138723328)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138727424)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138731520)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138735616)
Aug 9 23:05:23 pc6 kernel: NFS: 26649 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138739712)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138743808)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138747904)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138752000)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138756096)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138760192)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138764288)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138768384)
Aug 9 23:05:23 pc6 kernel: NFS: 26651 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138805248)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138809344)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138813440)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138817536)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138821632)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138825728)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138829824)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138833920)
Aug 9 23:05:23 pc6 kernel: NFS: 26650 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138772480)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138776576)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138780672)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138784768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138788864)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138792960)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138797056)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138801152)
Aug 9 23:05:23 pc6 kernel: NFS: 26653 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138870784)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138874880)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138878976)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138883072)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138887168)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138891264)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138895360)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138899456)
Aug 9 23:05:23 pc6 kernel: NFS: 26652 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138838016)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138842112)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138846208)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138850304)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138854400)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138858496)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138862592)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138866688)
Aug 9 23:05:23 pc6 kernel: NFS: 26657 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139001856)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139005952)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139010048)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139014144)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139018240)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139022336)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139026432)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139030528)
Aug 9 23:05:23 pc6 kernel: NFS: 26658 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139034624)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139038720)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139042816)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139046912)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139051008)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139055104)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139059200)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139063296)
Aug 9 23:05:23 pc6 kernel: NFS: 26659 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139067392)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139071488)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139075584)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139079680)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139083776)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139087872)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139091968)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 139096064)
Aug 9 23:05:23 pc6 kernel: NFS: 26656 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138969088)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138973184)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138977280)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138981376)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138985472)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138989568)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138993664)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138997760)
Aug 9 23:05:23 pc6 kernel: NFS: 26654 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138903552)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138907648)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138911744)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138915840)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138919936)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138924032)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138928128)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138932224)
Aug 9 23:05:23 pc6 kernel: NFS: 26655 nfs_readpage_result, (status 32768)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138936320)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138940416)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138944512)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138948608)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138952704)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138956800)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138960896)
Aug 9 23:05:23 pc6 kernel: NFS: read done (0:12/885372 4096 at 138964992)
Aug 9 23:05:37 pc6 kernel: nfs4_renew_state: start
Aug 9 23:05:37 pc6 kernel: nfs4_renew_state: failed to call renewd. Reason: lease not expired
Aug 9 23:05:37 pc6 kernel: nfs4_renew_state: requeueing work. Lease period = 47
Aug 9 23:05:37 pc6 kernel: nfs4_renew_state: done
Aug 9 23:05:56 pc6 kernel: NFS: nfs_readpages (0:12/885372 120)
Aug 9 23:05:56 pc6 kernel: NFS: 26660 initiated read call (req 0:12/885372, 32768 bytes @ offset 139100160)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26661 initiated read call (req 0:12/885372, 32768 bytes @ offset 139132928)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26662 initiated read call (req 0:12/885372, 32768 bytes @ offset 139165696)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26663 initiated read call (req 0:12/885372, 32768 bytes @ offset 139198464)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26664 initiated read call (req 0:12/885372, 32768 bytes @ offset 139231232)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26665 initiated read call (req 0:12/885372, 32768 bytes @ offset 139264000)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26666 initiated read call (req 0:12/885372, 32768 bytes @ offset 139296768)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26667 initiated read call (req 0:12/885372, 32768 bytes @ offset 139329536)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26668 initiated read call (req 0:12/885372, 32768 bytes @ offset 139362304)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26669 initiated read call (req 0:12/885372, 32768 bytes @ offset 139395072)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26670 initiated read call (req 0:12/885372, 32768 bytes @ offset 139427840)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26671 initiated read call (req 0:12/885372, 32768 bytes @ offset 139460608)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26672 initiated read call (req 0:12/885372, 32768 bytes @ offset 139493376)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26673 initiated read call (req 0:12/885372, 32768 bytes @ offset 139526144)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26674 initiated read call (req 0:12/885372, 32768 bytes @ offset 139558912)
Aug 9 23:05:56 pc6 kernel: encode_compound: tag=
Aug 9 23:05:56 pc6 kernel: NFS: 26660 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139100160)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139104256)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139108352)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139112448)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139116544)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139120640)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139124736)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139128832)
Aug 9 23:05:56 pc6 kernel: NFS: 26661 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139132928)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139137024)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139141120)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139145216)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139149312)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139153408)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139157504)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139161600)
Aug 9 23:05:56 pc6 kernel: NFS: 26662 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139165696)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139169792)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139173888)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139177984)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139182080)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139186176)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139190272)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139194368)
Aug 9 23:05:56 pc6 kernel: NFS: 26664 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139231232)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139235328)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139239424)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139243520)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139247616)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139251712)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139255808)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139259904)
Aug 9 23:05:56 pc6 kernel: NFS: 26665 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139264000)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139268096)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139272192)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139276288)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139280384)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139284480)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139288576)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139292672)
Aug 9 23:05:56 pc6 kernel: NFS: 26666 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139296768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139300864)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139304960)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139309056)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139313152)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139317248)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139321344)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139325440)
Aug 9 23:05:56 pc6 kernel: NFS: 26667 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139329536)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139333632)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139337728)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139341824)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139345920)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139350016)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139354112)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139358208)
Aug 9 23:05:56 pc6 kernel: NFS: 26670 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139427840)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139431936)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139436032)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139440128)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139444224)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139448320)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139452416)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139456512)
Aug 9 23:05:56 pc6 kernel: NFS: 26663 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139198464)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139202560)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139206656)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139210752)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139214848)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139218944)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139223040)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139227136)
Aug 9 23:05:56 pc6 kernel: NFS: 26671 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139460608)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139464704)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139468800)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139472896)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139476992)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139481088)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139485184)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139489280)
Aug 9 23:05:56 pc6 kernel: NFS: 26668 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139362304)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139366400)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139370496)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139374592)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139378688)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139382784)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139386880)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139390976)
Aug 9 23:05:56 pc6 kernel: NFS: 26674 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139558912)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139563008)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139567104)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139571200)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139575296)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139579392)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139583488)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139587584)
Aug 9 23:05:56 pc6 kernel: NFS: 26672 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139493376)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139497472)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139501568)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139505664)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139509760)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139513856)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139517952)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139522048)
Aug 9 23:05:56 pc6 kernel: NFS: 26673 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139526144)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139530240)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139534336)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139538432)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139542528)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139546624)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139550720)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139554816)
Aug 9 23:05:56 pc6 kernel: NFS: 26669 nfs_readpage_result, (status 32768)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139395072)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139399168)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139403264)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139407360)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139411456)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139415552)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139419648)
Aug 9 23:05:56 pc6 kernel: NFS: read done (0:12/885372 4096 at 139423744)
More information about the NFSv4
mailing list