From: Michal Simek <monstr@monstr.eu>
To: Chuck Lever <chuck.lever@oracle.com>,
Trond Myklebust <Trond.Myklebust@netapp.com>,
"J. Bruce Fields" <bfields@fieldses.org>,
Neil Brown <neilb@suse.de>,
linux-nfs@vger.kernel.org
Subject: NFS problem on Microblaze LE
Date: Wed, 02 Mar 2011 14:04:18 +0100 [thread overview]
Message-ID: <4D6E4052.7050201@monstr.eu> (raw)
Hi,
I am getting some troubles to get nfs work on new Microblaze little-endian platform and I would like
to ask you for some recommendations how to debug it.
First of all I need to write that Microblaze big-endian platforms have no problem.
The problem only happen if I use mount without -o nolock option
(mount -t nfs 192.168.0.101:/tftpboot/nfs /mnt)
If I use -o nolock option I have no problem to use nfs.
I use xilinx emaclite and axi emac(it is not in the mainline now) driver and I have no problem to
use dhcp, ftp, http, telnet and other internet protocols.
I compared debug logs on big and little endian platform(rootfs has the same setting) I found that
little-endian got packet which is shorter than on big endian which I have added to the log below.
The second thing, which I think is connected to the previous point, is that I am getting BADCRED in
rpc_verify_headers.
Is there any option/macro/recommended debug technique how to see packets? I need to get some clue
how to see packet and then how they are passed to rpc_verify_header function.
Thanks for you help,
Michal
sp605-emaclite-chipscope login: root
Password:
~ # echo 65535 > /proc/sys/sunrpc/nfs_debug
~ # echo 65535 > /proc/sys/sunrpc/nfsd_debug
~ # echo 65535 > /proc/sys/sunrpc/nlm_debug
~ # echo 65535 > /proc/sys/sunrpc/rpc_debug
~ #
~ #
~ # mount -t nfs 192.168.0.101:/tftpboot/nfs /mnt
RPC: looking up machine cred
RPC: allocated machine cred c6f9c320 for uid 0 gid 0
RPC: set up xprt to 192.168.0.101 (port 2049) via udp
RPC: created transport c6fac400 with 16 slots
RPC: creating nfs client for 192.168.0.101 (xprt c6fac400)
RPC: creating UNIX authenticator for client c6f322a0
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 1 __rpc_execute flags=0x680
RPC: 1 call_start nfs3 proc NULL (sync)
RPC: 1 call_reserve (status 0)
RPC: 1 reserved req c0810000 xid 2c351377
RPC: 1 call_reserveresult (status 0)
RPC: 1 call_refresh (status 0)
RPC: 1 holding NULL cred c0335c98
RPC: 1 refreshing NULL cred c0335c98
RPC: 1 call_refreshresult (status 0)
RPC: 1 call_allocate (status 0)
RPC: 1 allocated buffer of size 92 at c0811800
RPC: 1 call_bind (status 0)
RPC: 1 call_connect xprt c6fac400 is not connected
RPC: 1 xprt_connect xprt c6fac400 is not connected
RPC: 1 xprt_cwnd_limited cong = 0 cwnd = 256
RPC: 1 sleep_on(queue "xprt_pending" time 4294924896)
RPC: 1 added to queue c6fac5ac "xprt_pending"
RPC: 1 setting alarm for 700 ms
RPC: xs_connect scheduled xprt c6fac400
RPC: 1 sync task going to sleep
RPC: xs_bind4 0.0.0.0:1016: ok (0)
RPC: worker connecting xprt c6fac400 via udp to 192.168.0.101 (port 2049)
RPC: 1 __rpc_wake_up_task (now 4294924904)
RPC: 1 disabling timer
RPC: 1 removed from queue c6fac5ac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: 1 sync task resuming
RPC: 1 xprt_connect_status: connection established
RPC: 1 call_connect_status (status 0)
RPC: 1 call_transmit (status 0)
RPC: 1 xprt_prepare_transmit
RPC: 1 rpc_xdr_encode (status 0)
RPC: 1 marshaling NULL cred c0335c98
RPC: 1 using AUTH_NULL cred c0335c98 to wrap rpc data
RPC: 1 xprt_transmit(40)
RPC: xs_udp_data_ready...
RPC: cong 256, cwnd was 256, now 512
RPC: 1 xid 2c351377 complete (24 bytes received)
RPC: xs_udp_send_request(40) = 40
RPC: 1 xmit complete
RPC: wake_up_next(c6fac554 "xprt_resend")
RPC: wake_up_next(c6fac4fc "xprt_sending")
RPC: 1 call_status (status 24)
RPC: 1 call_decode (status 24)
RPC: 1 validating NULL cred c0335c98
RPC: 1 using AUTH_NULL cred c0335c98 to unwrap rpc data
RPC: 1 call_decode result 0
RPC: 1 return 0, status 0
RPC: 1 release task
RPC: freeing buffer of size 92 at c0811800
RPC: 1 release request c0810000
RPC: wake_up_next(c6fac604 "xprt_backlog")
RPC: rpc_release_client(c6f322a0)
RPC: 1 freeing task
RPC: set up xprt to 127.0.0.1 (port 111) via tcp
RPC: created transport c080bc00 with 16 slots
RPC: creating rpcbind client for localhost (xprt c080bc00)
RPC: creating UNIX authenticator for client c6f323a0
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 2 __rpc_execute flags=0x680
RPC: 2 call_start rpcbind4 proc NULL (sync)
RPC: 2 call_reserve (status 0)
RPC: 2 reserved req c0812000 xid 562efd36
RPC: 2 call_reserveresult (status 0)
RPC: 2 call_refresh (status 0)
RPC: 2 holding NULL cred c0335c98
RPC: 2 refreshing NULL cred c0335c98
RPC: 2 call_refreshresult (status 0)
RPC: 2 call_allocate (status 0)
RPC: 2 allocated buffer of size 92 at c0811800
RPC: 2 call_bind (status 0)
RPC: 2 call_connect xprt c080bc00 is not connected
RPC: 2 xprt_connect xprt c080bc00 is not connected
RPC: 2 sleep_on(queue "xprt_pending" time 4294924982)
RPC: 2 added to queue c080bdac "xprt_pending"
RPC: 2 setting alarm for 60000 ms
RPC: xs_connect scheduled xprt c080bc00
RPC: 2 sync task going to sleep
RPC: xs_bind4 0.0.0.0:1006: ok (0)
RPC: worker connecting xprt c080bc00 via tcp to 127.0.0.1 (port 111)
RPC: xs_tcp_state_change client c080bc00...
RPC: state 1 conn 0 dead 0 zapped 1 sk_shutdown 0
RPC: 2 __rpc_wake_up_task (now 4294924994)
RPC: 2 disabling timer
RPC: 2 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: 2 sync task resuming
RPC: 2 xprt_connect_status: retrying
RPC: 2 call_connect_status (status -11)
RPC: 2 call_transmit (status 0)
RPC: 2 xprt_prepare_transmit
RPC: 2 rpc_xdr_encode (status 0)
RPC: 2 marshaling NULL cred c0335c98
RPC: 2 using AUTH_NULL cred c0335c98 to wrap rpc data
RPC: 2 xprt_transmit(44)
RPC: c080bc00 connect status 115 connected 1 sock state 1
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 32
RPC: reading XID (4 bytes)
RPC: reading request with XID 562efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 562efd36
RPC: XID 562efd36 read 24 bytes
RPC: xprt = c080bc00, tcp_copied = 32, tcp_offset = 32, tcp_reclen = 32
RPC: 2 xid 562efd36 complete (32 bytes received)
RPC: xs_tcp_data_recv done
RPC: xs_tcp_send_request(44) = 44
RPC: 2 xmit complete
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 2 call_status (status 32)
RPC: 2 call_decode (status 32)
RPC: 2 validating NULL cred c0335c98
RPC: 2 rpc_verify_header: program 100000, version 4 unsupported by server localhost
RPC: 2 rpc_verify_header: call failed with error -93
RPC: 2 return 0, status -93
RPC: 2 release task
RPC: freeing buffer of size 92 at c0811800
RPC: 2 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f324a0)
RPC: 2 freeing task
RPC: shutting down rpcbind client for localhost
RPC: rpc_release_client(c6f324a0)
RPC: destroying rpcbind client for localhost
RPC: rpc_release_client(c6f323a0)
RPC: failed to create local rpcbind v4 cleint (errno -93).
RPC: unregistering (100021, 1, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 3 __rpc_execute flags=0x680
RPC: 3 call_start rpcbind2 proc UNSET (sync)
RPC: 3 call_reserve (status 0)
RPC: 3 reserved req c0812000 xid 572efd36
RPC: 3 call_reserveresult (status 0)
RPC: 3 call_refresh (status 0)
RPC: 3 looking up UNIX cred
RPC: looking up UNIX cred
RPC: allocating UNIX cred for uid 0 gid 0
RPC: 3 refreshing UNIX cred c6fb7d60
RPC: 3 call_refreshresult (status 0)
RPC: 3 call_allocate (status 0)
RPC: 3 allocated buffer of size 412 at c0811800
RPC: 3 call_bind (status 0)
RPC: 3 call_connect xprt c080bc00 is connected
RPC: 3 call_transmit (status 0)
RPC: 3 xprt_prepare_transmit
RPC: 3 rpc_xdr_encode (status 0)
RPC: 3 marshaling UNIX cred c6fb7d60
RPC: 3 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 3 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 3 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 3 xmit complete
RPC: 3 sleep_on(queue "xprt_pending" time 4294925115)
RPC: 3 added to queue c080bdac "xprt_pending"
RPC: 3 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 3 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
BIG endian MB gets length 28.
RPC: reading XID (4 bytes)
RPC: reading reply for XID 572efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 572efd36
RPC: XID 572efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 3 xid 572efd36 complete (20 bytes received)
RPC: 3 __rpc_wake_up_task (now 4294925146)
RPC: 3 disabling timer
RPC: 3 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 3 sync task resuming
RPC: 3 call_status (status 20)
RPC: 3 call_decode (status 20)
BADCRED here from rpc_verify_header
RPC: 3 rpc_verify_header: retry garbled creds
RPC: 3 call_bind (status 0)
RPC: 3 call_connect xprt c080bc00 is connected
RPC: 3 call_transmit (status 0)
RPC: 3 xprt_prepare_transmit
RPC: 3 rpc_xdr_encode (status 0)
RPC: 3 marshaling UNIX cred c6fb7d60
RPC: 3 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 3 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 3 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 3 xmit complete
RPC: 3 sleep_on(queue "xprt_pending" time 4294925182)
RPC: 3 added to queue c080bdac "xprt_pending"
RPC: 3 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 3 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 572efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 572efd36
RPC: XID 572efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 3 xid 572efd36 complete (20 bytes received)
RPC: 3 __rpc_wake_up_task (now 4294925214)
RPC: 3 disabling timer
RPC: 3 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 3 sync task resuming
RPC: 3 call_status (status 20)
RPC: 3 call_decode (status 20)
RPC: 3 rpc_verify_header: retry garbled creds
RPC: 3 call_bind (status 0)
RPC: 3 call_connect xprt c080bc00 is connected
RPC: 3 call_transmit (status 0)
RPC: 3 xprt_prepare_transmit
RPC: 3 rpc_xdr_encode (status 0)
RPC: 3 marshaling UNIX cred c6fb7d60
RPC: 3 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 3 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 3 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 3 xmit complete
RPC: 3 sleep_on(queue "xprt_pending" time 4294925250)
RPC: 3 added to queue c080bdac "xprt_pending"
RPC: 3 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 3 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 572efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 572efd36
RPC: XID 572efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 3 xid 572efd36 complete (20 bytes received)
RPC: 3 __rpc_wake_up_task (now 4294925281)
RPC: 3 disabling timer
RPC: 3 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 3 sync task resuming
RPC: 3 call_status (status 20)
RPC: 3 call_decode (status 20)
RPC: 3 rpc_verify_header: call rejected 1
RPC: 3 rpc_verify_header: call failed with error -13
RPC: 3 return 0, status -13
RPC: 3 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 3 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 3 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 3, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 4 __rpc_execute flags=0x680
RPC: 4 call_start rpcbind2 proc UNSET (sync)
RPC: 4 call_reserve (status 0)
RPC: 4 reserved req c0812000 xid 582efd36
RPC: 4 call_reserveresult (status 0)
RPC: 4 call_refresh (status 0)
RPC: 4 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 4 refreshing UNIX cred c6fb7d60
RPC: 4 call_refreshresult (status 0)
RPC: 4 call_allocate (status 0)
RPC: 4 allocated buffer of size 412 at c0811800
RPC: 4 call_bind (status 0)
RPC: 4 call_connect xprt c080bc00 is connected
RPC: 4 call_transmit (status 0)
RPC: 4 xprt_prepare_transmit
RPC: 4 rpc_xdr_encode (status 0)
RPC: 4 marshaling UNIX cred c6fb7d60
RPC: 4 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 4 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 4 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 4 xmit complete
RPC: 4 sleep_on(queue "xprt_pending" time 4294925356)
RPC: 4 added to queue c080bdac "xprt_pending"
RPC: 4 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 4 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 582efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 582efd36
RPC: XID 582efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 4 xid 582efd36 complete (20 bytes received)
RPC: 4 __rpc_wake_up_task (now 4294925387)
RPC: 4 disabling timer
RPC: 4 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 4 sync task resuming
RPC: 4 call_status (status 20)
RPC: 4 call_decode (status 20)
RPC: 4 rpc_verify_header: retry garbled creds
RPC: 4 call_bind (status 0)
RPC: 4 call_connect xprt c080bc00 is connected
RPC: 4 call_transmit (status 0)
RPC: 4 xprt_prepare_transmit
RPC: 4 rpc_xdr_encode (status 0)
RPC: 4 marshaling UNIX cred c6fb7d60
RPC: 4 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 4 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 4 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 4 xmit complete
RPC: 4 sleep_on(queue "xprt_pending" time 4294925424)
RPC: 4 added to queue c080bdac "xprt_pending"
RPC: 4 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 4 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 582efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 582efd36
RPC: XID 582efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 4 xid 582efd36 complete (20 bytes received)
RPC: 4 __rpc_wake_up_task (now 4294925455)
RPC: 4 disabling timer
RPC: 4 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 4 sync task resuming
RPC: 4 call_status (status 20)
RPC: 4 call_decode (status 20)
RPC: 4 rpc_verify_header: retry garbled creds
RPC: 4 call_bind (status 0)
RPC: 4 call_connect xprt c080bc00 is connected
RPC: 4 call_transmit (status 0)
RPC: 4 xprt_prepare_transmit
RPC: 4 rpc_xdr_encode (status 0)
RPC: 4 marshaling UNIX cred c6fb7d60
RPC: 4 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 4 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 4 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 4 xmit complete
RPC: 4 sleep_on(queue "xprt_pending" time 4294925491)
RPC: 4 added to queue c080bdac "xprt_pending"
RPC: 4 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 4 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 582efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 582efd36
RPC: XID 582efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 4 xid 582efd36 complete (20 bytes received)
RPC: 4 __rpc_wake_up_task (now 4294925522)
RPC: 4 disabling timer
RPC: 4 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 4 sync task resuming
RPC: 4 call_status (status 20)
RPC: 4 call_decode (status 20)
RPC: 4 rpc_verify_header: call rejected 1
RPC: 4 rpc_verify_header: call failed with error -13
RPC: 4 return 0, status -13
RPC: 4 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 4 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 4 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 4, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 5 __rpc_execute flags=0x680
RPC: 5 call_start rpcbind2 proc UNSET (sync)
RPC: 5 call_reserve (status 0)
RPC: 5 reserved req c0812000 xid 592efd36
RPC: 5 call_reserveresult (status 0)
RPC: 5 call_refresh (status 0)
RPC: 5 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 5 refreshing UNIX cred c6fb7d60
RPC: 5 call_refreshresult (status 0)
RPC: 5 call_allocate (status 0)
RPC: 5 allocated buffer of size 412 at c0811800
RPC: 5 call_bind (status 0)
RPC: 5 call_connect xprt c080bc00 is connected
RPC: 5 call_transmit (status 0)
RPC: 5 xprt_prepare_transmit
RPC: 5 rpc_xdr_encode (status 0)
RPC: 5 marshaling UNIX cred c6fb7d60
RPC: 5 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 5 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 5 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 5 xmit complete
RPC: 5 sleep_on(queue "xprt_pending" time 4294925597)
RPC: 5 added to queue c080bdac "xprt_pending"
RPC: 5 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 5 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 592efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 592efd36
RPC: XID 592efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 5 xid 592efd36 complete (20 bytes received)
RPC: 5 __rpc_wake_up_task (now 4294925629)
RPC: 5 disabling timer
RPC: 5 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 5 sync task resuming
RPC: 5 call_status (status 20)
RPC: 5 call_decode (status 20)
RPC: 5 rpc_verify_header: retry garbled creds
RPC: 5 call_bind (status 0)
RPC: 5 call_connect xprt c080bc00 is connected
RPC: 5 call_transmit (status 0)
RPC: 5 xprt_prepare_transmit
RPC: 5 rpc_xdr_encode (status 0)
RPC: 5 marshaling UNIX cred c6fb7d60
RPC: 5 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 5 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 5 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 5 xmit complete
RPC: 5 sleep_on(queue "xprt_pending" time 4294925665)
RPC: 5 added to queue c080bdac "xprt_pending"
RPC: 5 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 5 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 592efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 592efd36
RPC: XID 592efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 5 xid 592efd36 complete (20 bytes received)
RPC: 5 __rpc_wake_up_task (now 4294925696)
RPC: 5 disabling timer
RPC: 5 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 5 sync task resuming
RPC: 5 call_status (status 20)
RPC: 5 call_decode (status 20)
RPC: 5 rpc_verify_header: retry garbled creds
RPC: 5 call_bind (status 0)
RPC: 5 call_connect xprt c080bc00 is connected
RPC: 5 call_transmit (status 0)
RPC: 5 xprt_prepare_transmit
RPC: 5 rpc_xdr_encode (status 0)
RPC: 5 marshaling UNIX cred c6fb7d60
RPC: 5 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 5 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 5 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 5 xmit complete
RPC: 5 sleep_on(queue "xprt_pending" time 4294925732)
RPC: 5 added to queue c080bdac "xprt_pending"
RPC: 5 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 5 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 592efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 592efd36
RPC: XID 592efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 5 xid 592efd36 complete (20 bytes received)
RPC: 5 __rpc_wake_up_task (now 4294925763)
RPC: 5 disabling timer
RPC: 5 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 5 sync task resuming
RPC: 5 call_status (status 20)
RPC: 5 call_decode (status 20)
RPC: 5 rpc_verify_header: call rejected 1
RPC: 5 rpc_verify_header: call failed with error -13
RPC: 5 return 0, status -13
RPC: 5 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 5 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 5 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: registering (100021, 1, 17, 54688) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 6 __rpc_execute flags=0x680
RPC: 6 call_start rpcbind2 proc SET (sync)
RPC: 6 call_reserve (status 0)
RPC: 6 reserved req c0812000 xid 5a2efd36
RPC: 6 call_reserveresult (status 0)
RPC: 6 call_refresh (status 0)
RPC: 6 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 6 refreshing UNIX cred c6fb7d60
RPC: 6 call_refreshresult (status 0)
RPC: 6 call_allocate (status 0)
RPC: 6 allocated buffer of size 412 at c0811800
RPC: 6 call_bind (status 0)
RPC: 6 call_connect xprt c080bc00 is connected
RPC: 6 call_transmit (status 0)
RPC: 6 xprt_prepare_transmit
RPC: 6 rpc_xdr_encode (status 0)
RPC: 6 marshaling UNIX cred c6fb7d60
RPC: 6 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 6 encoding PMAP_SET call (100021, 1, 17, 54688)
RPC: 6 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 6 xmit complete
RPC: 6 sleep_on(queue "xprt_pending" time 4294925843)
RPC: 6 added to queue c080bdac "xprt_pending"
RPC: 6 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 6 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5a2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5a2efd36
RPC: XID 5a2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 6 xid 5a2efd36 complete (20 bytes received)
RPC: 6 __rpc_wake_up_task (now 4294925874)
RPC: 6 disabling timer
RPC: 6 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 6 sync task resuming
RPC: 6 call_status (status 20)
RPC: 6 call_decode (status 20)
RPC: 6 rpc_verify_header: retry garbled creds
RPC: 6 call_bind (status 0)
RPC: 6 call_connect xprt c080bc00 is connected
RPC: 6 call_transmit (status 0)
RPC: 6 xprt_prepare_transmit
RPC: 6 rpc_xdr_encode (status 0)
RPC: 6 marshaling UNIX cred c6fb7d60
RPC: 6 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 6 encoding PMAP_SET call (100021, 1, 17, 54688)
RPC: 6 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 6 xmit complete
RPC: 6 sleep_on(queue "xprt_pending" time 4294925910)
RPC: 6 added to queue c080bdac "xprt_pending"
RPC: 6 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 6 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5a2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5a2efd36
RPC: XID 5a2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 6 xid 5a2efd36 complete (20 bytes received)
RPC: 6 __rpc_wake_up_task (now 4294925941)
RPC: 6 disabling timer
RPC: 6 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 6 sync task resuming
RPC: 6 call_status (status 20)
RPC: 6 call_decode (status 20)
RPC: 6 rpc_verify_header: retry garbled creds
RPC: 6 call_bind (status 0)
RPC: 6 call_connect xprt c080bc00 is connected
RPC: 6 call_transmit (status 0)
RPC: 6 xprt_prepare_transmit
RPC: 6 rpc_xdr_encode (status 0)
RPC: 6 marshaling UNIX cred c6fb7d60
RPC: 6 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 6 encoding PMAP_SET call (100021, 1, 17, 54688)
RPC: 6 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 6 xmit complete
RPC: 6 sleep_on(queue "xprt_pending" time 4294925977)
RPC: 6 added to queue c080bdac "xprt_pending"
RPC: 6 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 6 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5a2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5a2efd36
RPC: XID 5a2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 6 xid 5a2efd36 complete (20 bytes received)
RPC: 6 __rpc_wake_up_task (now 4294926008)
RPC: 6 disabling timer
RPC: 6 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 6 sync task resuming
RPC: 6 call_status (status 20)
RPC: 6 call_decode (status 20)
RPC: 6 rpc_verify_header: call rejected 1
RPC: 6 rpc_verify_header: call failed with error -13
RPC: 6 return 0, status -13
RPC: 6 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 6 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 6 freeing task
RPC: failed to contact local rpcbind server (errno 13).
svc: failed to register lockdv1 RPC service (errno 13).
RPC: unregistering (100021, 1, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 7 __rpc_execute flags=0x680
RPC: 7 call_start rpcbind2 proc UNSET (sync)
RPC: 7 call_reserve (status 0)
RPC: 7 reserved req c0812000 xid 5b2efd36
RPC: 7 call_reserveresult (status 0)
RPC: 7 call_refresh (status 0)
RPC: 7 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 7 refreshing UNIX cred c6fb7d60
RPC: 7 call_refreshresult (status 0)
RPC: 7 call_allocate (status 0)
RPC: 7 allocated buffer of size 412 at c0811800
RPC: 7 call_bind (status 0)
RPC: 7 call_connect xprt c080bc00 is connected
RPC: 7 call_transmit (status 0)
RPC: 7 xprt_prepare_transmit
RPC: 7 rpc_xdr_encode (status 0)
RPC: 7 marshaling UNIX cred c6fb7d60
RPC: 7 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 7 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 7 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 7 xmit complete
RPC: 7 sleep_on(queue "xprt_pending" time 4294926087)
RPC: 7 added to queue c080bdac "xprt_pending"
RPC: 7 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 7 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5b2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5b2efd36
RPC: XID 5b2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 7 xid 5b2efd36 complete (20 bytes received)
RPC: 7 __rpc_wake_up_task (now 4294926118)
RPC: 7 disabling timer
RPC: 7 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 7 sync task resuming
RPC: 7 call_status (status 20)
RPC: 7 call_decode (status 20)
RPC: 7 rpc_verify_header: retry garbled creds
RPC: 7 call_bind (status 0)
RPC: 7 call_connect xprt c080bc00 is connected
RPC: 7 call_transmit (status 0)
RPC: 7 xprt_prepare_transmit
RPC: 7 rpc_xdr_encode (status 0)
RPC: 7 marshaling UNIX cred c6fb7d60
RPC: 7 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 7 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 7 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 7 xmit complete
RPC: 7 sleep_on(queue "xprt_pending" time 4294926154)
RPC: 7 added to queue c080bdac "xprt_pending"
RPC: 7 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 7 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5b2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5b2efd36
RPC: XID 5b2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 7 xid 5b2efd36 complete (20 bytes received)
RPC: 7 __rpc_wake_up_task (now 4294926185)
RPC: 7 disabling timer
RPC: 7 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 7 sync task resuming
RPC: 7 call_status (status 20)
RPC: 7 call_decode (status 20)
RPC: 7 rpc_verify_header: retry garbled creds
RPC: 7 call_bind (status 0)
RPC: 7 call_connect xprt c080bc00 is connected
RPC: 7 call_transmit (status 0)
RPC: 7 xprt_prepare_transmit
RPC: 7 rpc_xdr_encode (status 0)
RPC: 7 marshaling UNIX cred c6fb7d60
RPC: 7 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 7 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 7 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 7 xmit complete
RPC: 7 sleep_on(queue "xprt_pending" time 4294926221)
RPC: 7 added to queue c080bdac "xprt_pending"
RPC: 7 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 7 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5b2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5b2efd36
RPC: XID 5b2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 7 xid 5b2efd36 complete (20 bytes received)
RPC: 7 __rpc_wake_up_task (now 4294926252)
RPC: 7 disabling timer
RPC: 7 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 7 sync task resuming
RPC: 7 call_status (status 20)
RPC: 7 call_decode (status 20)
RPC: 7 rpc_verify_header: call rejected 1
RPC: 7 rpc_verify_header: call failed with error -13
RPC: 7 return 0, status -13
RPC: 7 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 7 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 7 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 3, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 8 __rpc_execute flags=0x680
RPC: 8 call_start rpcbind2 proc UNSET (sync)
RPC: 8 call_reserve (status 0)
RPC: 8 reserved req c0812000 xid 5c2efd36
RPC: 8 call_reserveresult (status 0)
RPC: 8 call_refresh (status 0)
RPC: 8 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 8 refreshing UNIX cred c6fb7d60
RPC: 8 call_refreshresult (status 0)
RPC: 8 call_allocate (status 0)
RPC: 8 allocated buffer of size 412 at c0811800
RPC: 8 call_bind (status 0)
RPC: 8 call_connect xprt c080bc00 is connected
RPC: 8 call_transmit (status 0)
RPC: 8 xprt_prepare_transmit
RPC: 8 rpc_xdr_encode (status 0)
RPC: 8 marshaling UNIX cred c6fb7d60
RPC: 8 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 8 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 8 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 8 xmit complete
RPC: 8 sleep_on(queue "xprt_pending" time 4294926327)
RPC: 8 added to queue c080bdac "xprt_pending"
RPC: 8 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 8 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5c2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5c2efd36
RPC: XID 5c2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 8 xid 5c2efd36 complete (20 bytes received)
RPC: 8 __rpc_wake_up_task (now 4294926359)
RPC: 8 disabling timer
RPC: 8 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 8 sync task resuming
RPC: 8 call_status (status 20)
RPC: 8 call_decode (status 20)
RPC: 8 rpc_verify_header: retry garbled creds
RPC: 8 call_bind (status 0)
RPC: 8 call_connect xprt c080bc00 is connected
RPC: 8 call_transmit (status 0)
RPC: 8 xprt_prepare_transmit
RPC: 8 rpc_xdr_encode (status 0)
RPC: 8 marshaling UNIX cred c6fb7d60
RPC: 8 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 8 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 8 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 8 xmit complete
RPC: 8 sleep_on(queue "xprt_pending" time 4294926396)
RPC: 8 added to queue c080bdac "xprt_pending"
RPC: 8 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 8 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5c2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5c2efd36
RPC: XID 5c2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 8 xid 5c2efd36 complete (20 bytes received)
RPC: 8 __rpc_wake_up_task (now 4294926427)
RPC: 8 disabling timer
RPC: 8 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 8 sync task resuming
RPC: 8 call_status (status 20)
RPC: 8 call_decode (status 20)
RPC: 8 rpc_verify_header: retry garbled creds
RPC: 8 call_bind (status 0)
RPC: 8 call_connect xprt c080bc00 is connected
RPC: 8 call_transmit (status 0)
RPC: 8 xprt_prepare_transmit
RPC: 8 rpc_xdr_encode (status 0)
RPC: 8 marshaling UNIX cred c6fb7d60
RPC: 8 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 8 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 8 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 8 xmit complete
RPC: 8 sleep_on(queue "xprt_pending" time 4294926463)
RPC: 8 added to queue c080bdac "xprt_pending"
RPC: 8 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 8 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5c2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5c2efd36
RPC: XID 5c2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 8 xid 5c2efd36 complete (20 bytes received)
RPC: 8 __rpc_wake_up_task (now 4294926494)
RPC: 8 disabling timer
RPC: 8 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 8 sync task resuming
RPC: 8 call_status (status 20)
RPC: 8 call_decode (status 20)
RPC: 8 rpc_verify_header: call rejected 1
RPC: 8 rpc_verify_header: call failed with error -13
RPC: 8 return 0, status -13
RPC: 8 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 8 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 8 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 4, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 9 __rpc_execute flags=0x680
RPC: 9 call_start rpcbind2 proc UNSET (sync)
RPC: 9 call_reserve (status 0)
RPC: 9 reserved req c0812000 xid 5d2efd36
RPC: 9 call_reserveresult (status 0)
RPC: 9 call_refresh (status 0)
RPC: 9 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 9 refreshing UNIX cred c6fb7d60
RPC: 9 call_refreshresult (status 0)
RPC: 9 call_allocate (status 0)
RPC: 9 allocated buffer of size 412 at c0811800
RPC: 9 call_bind (status 0)
RPC: 9 call_connect xprt c080bc00 is connected
RPC: 9 call_transmit (status 0)
RPC: 9 xprt_prepare_transmit
RPC: 9 rpc_xdr_encode (status 0)
RPC: 9 marshaling UNIX cred c6fb7d60
RPC: 9 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 9 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 9 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 9 xmit complete
RPC: 9 sleep_on(queue "xprt_pending" time 4294926569)
RPC: 9 added to queue c080bdac "xprt_pending"
RPC: 9 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 9 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5d2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5d2efd36
RPC: XID 5d2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 9 xid 5d2efd36 complete (20 bytes received)
RPC: 9 __rpc_wake_up_task (now 4294926600)
RPC: 9 disabling timer
RPC: 9 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 9 sync task resuming
RPC: 9 call_status (status 20)
RPC: 9 call_decode (status 20)
RPC: 9 rpc_verify_header: retry garbled creds
RPC: 9 call_bind (status 0)
RPC: 9 call_connect xprt c080bc00 is connected
RPC: 9 call_transmit (status 0)
RPC: 9 xprt_prepare_transmit
RPC: 9 rpc_xdr_encode (status 0)
RPC: 9 marshaling UNIX cred c6fb7d60
RPC: 9 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 9 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 9 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 9 xmit complete
RPC: 9 sleep_on(queue "xprt_pending" time 4294926637)
RPC: 9 added to queue c080bdac "xprt_pending"
RPC: 9 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 9 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5d2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5d2efd36
RPC: XID 5d2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 9 xid 5d2efd36 complete (20 bytes received)
RPC: 9 __rpc_wake_up_task (now 4294926668)
RPC: 9 disabling timer
RPC: 9 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 9 sync task resuming
RPC: 9 call_status (status 20)
RPC: 9 call_decode (status 20)
RPC: 9 rpc_verify_header: retry garbled creds
RPC: 9 call_bind (status 0)
RPC: 9 call_connect xprt c080bc00 is connected
RPC: 9 call_transmit (status 0)
RPC: 9 xprt_prepare_transmit
RPC: 9 rpc_xdr_encode (status 0)
RPC: 9 marshaling UNIX cred c6fb7d60
RPC: 9 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 9 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 9 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 9 xmit complete
RPC: 9 sleep_on(queue "xprt_pending" time 4294926704)
RPC: 9 added to queue c080bdac "xprt_pending"
RPC: 9 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 9 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5d2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5d2efd36
RPC: XID 5d2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 9 xid 5d2efd36 complete (20 bytes received)
RPC: 9 __rpc_wake_up_task (now 4294926735)
RPC: 9 disabling timer
RPC: 9 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 9 sync task resuming
RPC: 9 call_status (status 20)
RPC: 9 call_decode (status 20)
RPC: 9 rpc_verify_header: call rejected 1
RPC: 9 rpc_verify_header: call failed with error -13
RPC: 9 return 0, status -13
RPC: 9 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 9 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 9 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: shutting down nfs client for 192.168.0.101
RPC: rpc_release_client(c6f322a0)
RPC: destroying nfs client for 192.168.0.101
RPC: destroying transport c6fac400
RPC: xs_destroy xprt c6fac400
RPC: xs_close xprt c6fac400
RPC: disconnected transport c6fac400
RPC: looking up machine cred
RPC: set up xprt to 192.168.0.101 (port 2049) via udp
RPC: created transport c6fac400 with 16 slots
RPC: creating nfs client for 192.168.0.101 (xprt c6fac400)
RPC: creating UNIX authenticator for client c6f322a0
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 10 __rpc_execute flags=0x680
RPC: 10 call_start nfs3 proc NULL (sync)
RPC: 10 call_reserve (status 0)
RPC: 10 reserved req c0810000 xid 356611d2
RPC: 10 call_reserveresult (status 0)
RPC: 10 call_refresh (status 0)
RPC: 10 holding NULL cred c0335c98
RPC: 10 refreshing NULL cred c0335c98
RPC: 10 call_refreshresult (status 0)
RPC: 10 call_allocate (status 0)
RPC: 10 allocated buffer of size 92 at c0811800
RPC: 10 call_bind (status 0)
RPC: 10 call_connect xprt c6fac400 is not connected
RPC: 10 xprt_connect xprt c6fac400 is not connected
RPC: 10 xprt_cwnd_limited cong = 0 cwnd = 256
RPC: 10 sleep_on(queue "xprt_pending" time 4294926824)
RPC: 10 added to queue c6fac5ac "xprt_pending"
RPC: 10 setting alarm for 700 ms
RPC: xs_connect scheduled xprt c6fac400
RPC: 10 sync task going to sleep
RPC: xs_bind4 0.0.0.0:950: ok (0)
RPC: worker connecting xprt c6fac400 via udp to 192.168.0.101 (port 2049)
RPC: 10 __rpc_wake_up_task (now 4294926832)
RPC: 10 disabling timer
RPC: 10 removed from queue c6fac5ac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: 10 sync task resuming
RPC: 10 xprt_connect_status: connection established
RPC: 10 call_connect_status (status 0)
RPC: 10 call_transmit (status 0)
RPC: 10 xprt_prepare_transmit
RPC: 10 rpc_xdr_encode (status 0)
RPC: 10 marshaling NULL cred c0335c98
RPC: 10 using AUTH_NULL cred c0335c98 to wrap rpc data
RPC: 10 xprt_transmit(40)
RPC: xs_udp_data_ready...
RPC: cong 256, cwnd was 256, now 512
RPC: 10 xid 356611d2 complete (24 bytes received)
RPC: xs_udp_send_request(40) = 40
RPC: 10 xmit complete
RPC: wake_up_next(c6fac554 "xprt_resend")
RPC: wake_up_next(c6fac4fc "xprt_sending")
RPC: 10 call_status (status 24)
RPC: 10 call_decode (status 24)
RPC: 10 validating NULL cred c0335c98
RPC: 10 using AUTH_NULL cred c0335c98 to unwrap rpc data
RPC: 10 call_decode result 0
RPC: 10 return 0, status 0
RPC: 10 release task
RPC: freeing buffer of size 92 at c0811800
RPC: 10 release request c0810000
RPC: wake_up_next(c6fac604 "xprt_backlog")
RPC: rpc_release_client(c6f322a0)
RPC: 10 freeing task
RPC: unregistering (100021, 1, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 11 __rpc_execute flags=0x680
RPC: 11 call_start rpcbind2 proc UNSET (sync)
RPC: 11 call_reserve (status 0)
RPC: 11 reserved req c0812000 xid 5e2efd36
RPC: 11 call_reserveresult (status 0)
RPC: 11 call_refresh (status 0)
RPC: 11 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 11 refreshing UNIX cred c6fb7d60
RPC: 11 call_refreshresult (status 0)
RPC: 11 call_allocate (status 0)
RPC: 11 allocated buffer of size 412 at c0811800
RPC: 11 call_bind (status 0)
RPC: 11 call_connect xprt c080bc00 is connected
RPC: 11 call_transmit (status 0)
RPC: 11 xprt_prepare_transmit
RPC: 11 rpc_xdr_encode (status 0)
RPC: 11 marshaling UNIX cred c6fb7d60
RPC: 11 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 11 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 11 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 11 xmit complete
RPC: 11 sleep_on(queue "xprt_pending" time 4294926916)
RPC: 11 added to queue c080bdac "xprt_pending"
RPC: 11 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 11 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5e2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5e2efd36
RPC: XID 5e2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 11 xid 5e2efd36 complete (20 bytes received)
RPC: 11 __rpc_wake_up_task (now 4294926947)
RPC: 11 disabling timer
RPC: 11 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 11 sync task resuming
RPC: 11 call_status (status 20)
RPC: 11 call_decode (status 20)
RPC: 11 rpc_verify_header: retry garbled creds
RPC: 11 call_bind (status 0)
RPC: 11 call_connect xprt c080bc00 is connected
RPC: 11 call_transmit (status 0)
RPC: 11 xprt_prepare_transmit
RPC: 11 rpc_xdr_encode (status 0)
RPC: 11 marshaling UNIX cred c6fb7d60
RPC: 11 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 11 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 11 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 11 xmit complete
RPC: 11 sleep_on(queue "xprt_pending" time 4294926983)
RPC: 11 added to queue c080bdac "xprt_pending"
RPC: 11 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 11 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5e2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5e2efd36
RPC: XID 5e2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 11 xid 5e2efd36 complete (20 bytes received)
RPC: 11 __rpc_wake_up_task (now 4294927014)
RPC: 11 disabling timer
RPC: 11 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 11 sync task resuming
RPC: 11 call_status (status 20)
RPC: 11 call_decode (status 20)
RPC: 11 rpc_verify_header: retry garbled creds
RPC: 11 call_bind (status 0)
RPC: 11 call_connect xprt c080bc00 is connected
RPC: 11 call_transmit (status 0)
RPC: 11 xprt_prepare_transmit
RPC: 11 rpc_xdr_encode (status 0)
RPC: 11 marshaling UNIX cred c6fb7d60
RPC: 11 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 11 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 11 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 11 xmit complete
RPC: 11 sleep_on(queue "xprt_pending" time 4294927050)
RPC: 11 added to queue c080bdac "xprt_pending"
RPC: 11 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 11 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5e2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5e2efd36
RPC: XID 5e2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 11 xid 5e2efd36 complete (20 bytes received)
RPC: 11 __rpc_wake_up_task (now 4294927081)
RPC: 11 disabling timer
RPC: 11 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 11 sync task resuming
RPC: 11 call_status (status 20)
RPC: 11 call_decode (status 20)
RPC: 11 rpc_verify_header: call rejected 1
RPC: 11 rpc_verify_header: call failed with error -13
RPC: 11 return 0, status -13
RPC: 11 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 11 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 11 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 3, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 12 __rpc_execute flags=0x680
RPC: 12 call_start rpcbind2 proc UNSET (sync)
RPC: 12 call_reserve (status 0)
RPC: 12 reserved req c0812000 xid 5f2efd36
RPC: 12 call_reserveresult (status 0)
RPC: 12 call_refresh (status 0)
RPC: 12 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 12 refreshing UNIX cred c6fb7d60
RPC: 12 call_refreshresult (status 0)
RPC: 12 call_allocate (status 0)
RPC: 12 allocated buffer of size 412 at c0811800
RPC: 12 call_bind (status 0)
RPC: 12 call_connect xprt c080bc00 is connected
RPC: 12 call_transmit (status 0)
RPC: 12 xprt_prepare_transmit
RPC: 12 rpc_xdr_encode (status 0)
RPC: 12 marshaling UNIX cred c6fb7d60
RPC: 12 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 12 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 12 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 12 xmit complete
RPC: 12 sleep_on(queue "xprt_pending" time 4294927157)
RPC: 12 added to queue c080bdac "xprt_pending"
RPC: 12 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 12 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5f2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5f2efd36
RPC: XID 5f2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 12 xid 5f2efd36 complete (20 bytes received)
RPC: 12 __rpc_wake_up_task (now 4294927188)
RPC: 12 disabling timer
RPC: 12 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 12 sync task resuming
RPC: 12 call_status (status 20)
RPC: 12 call_decode (status 20)
RPC: 12 rpc_verify_header: retry garbled creds
RPC: 12 call_bind (status 0)
RPC: 12 call_connect xprt c080bc00 is connected
RPC: 12 call_transmit (status 0)
RPC: 12 xprt_prepare_transmit
RPC: 12 rpc_xdr_encode (status 0)
RPC: 12 marshaling UNIX cred c6fb7d60
RPC: 12 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 12 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 12 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 12 xmit complete
RPC: 12 sleep_on(queue "xprt_pending" time 4294927224)
RPC: 12 added to queue c080bdac "xprt_pending"
RPC: 12 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 12 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5f2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5f2efd36
RPC: XID 5f2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 12 xid 5f2efd36 complete (20 bytes received)
RPC: 12 __rpc_wake_up_task (now 4294927255)
RPC: 12 disabling timer
RPC: 12 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 12 sync task resuming
RPC: 12 call_status (status 20)
RPC: 12 call_decode (status 20)
RPC: 12 rpc_verify_header: retry garbled creds
RPC: 12 call_bind (status 0)
RPC: 12 call_connect xprt c080bc00 is connected
RPC: 12 call_transmit (status 0)
RPC: 12 xprt_prepare_transmit
RPC: 12 rpc_xdr_encode (status 0)
RPC: 12 marshaling UNIX cred c6fb7d60
RPC: 12 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 12 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 12 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 12 xmit complete
RPC: 12 sleep_on(queue "xprt_pending" time 4294927291)
RPC: 12 added to queue c080bdac "xprt_pending"
RPC: 12 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 12 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 5f2efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 5f2efd36
RPC: XID 5f2efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 12 xid 5f2efd36 complete (20 bytes received)
RPC: 12 __rpc_wake_up_task (now 4294927322)
RPC: 12 disabling timer
RPC: 12 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 12 sync task resuming
RPC: 12 call_status (status 20)
RPC: 12 call_decode (status 20)
RPC: 12 rpc_verify_header: call rejected 1
RPC: 12 rpc_verify_header: call failed with error -13
RPC: 12 return 0, status -13
RPC: 12 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 12 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 12 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 4, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 13 __rpc_execute flags=0x680
RPC: 13 call_start rpcbind2 proc UNSET (sync)
RPC: 13 call_reserve (status 0)
RPC: 13 reserved req c0812000 xid 602efd36
RPC: 13 call_reserveresult (status 0)
RPC: 13 call_refresh (status 0)
RPC: 13 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 13 refreshing UNIX cred c6fb7d60
RPC: 13 call_refreshresult (status 0)
RPC: 13 call_allocate (status 0)
RPC: 13 allocated buffer of size 412 at c0811800
RPC: 13 call_bind (status 0)
RPC: 13 call_connect xprt c080bc00 is connected
RPC: 13 call_transmit (status 0)
RPC: 13 xprt_prepare_transmit
RPC: 13 rpc_xdr_encode (status 0)
RPC: 13 marshaling UNIX cred c6fb7d60
RPC: 13 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 13 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 13 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 13 xmit complete
RPC: 13 sleep_on(queue "xprt_pending" time 4294927398)
RPC: 13 added to queue c080bdac "xprt_pending"
RPC: 13 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 13 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 602efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 602efd36
RPC: XID 602efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 13 xid 602efd36 complete (20 bytes received)
RPC: 13 __rpc_wake_up_task (now 4294927429)
RPC: 13 disabling timer
RPC: 13 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 13 sync task resuming
RPC: 13 call_status (status 20)
RPC: 13 call_decode (status 20)
RPC: 13 rpc_verify_header: retry garbled creds
RPC: 13 call_bind (status 0)
RPC: 13 call_connect xprt c080bc00 is connected
RPC: 13 call_transmit (status 0)
RPC: 13 xprt_prepare_transmit
RPC: 13 rpc_xdr_encode (status 0)
RPC: 13 marshaling UNIX cred c6fb7d60
RPC: 13 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 13 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 13 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 13 xmit complete
RPC: 13 sleep_on(queue "xprt_pending" time 4294927465)
RPC: 13 added to queue c080bdac "xprt_pending"
RPC: 13 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 13 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 602efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 602efd36
RPC: XID 602efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 13 xid 602efd36 complete (20 bytes received)
RPC: 13 __rpc_wake_up_task (now 4294927496)
RPC: 13 disabling timer
RPC: 13 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 13 sync task resuming
RPC: 13 call_status (status 20)
RPC: 13 call_decode (status 20)
RPC: 13 rpc_verify_header: retry garbled creds
RPC: 13 call_bind (status 0)
RPC: 13 call_connect xprt c080bc00 is connected
RPC: 13 call_transmit (status 0)
RPC: 13 xprt_prepare_transmit
RPC: 13 rpc_xdr_encode (status 0)
RPC: 13 marshaling UNIX cred c6fb7d60
RPC: 13 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 13 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 13 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 13 xmit complete
RPC: 13 sleep_on(queue "xprt_pending" time 4294927532)
RPC: 13 added to queue c080bdac "xprt_pending"
RPC: 13 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 13 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 602efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 602efd36
RPC: XID 602efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 13 xid 602efd36 complete (20 bytes received)
RPC: 13 __rpc_wake_up_task (now 4294927564)
RPC: 13 disabling timer
RPC: 13 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 13 sync task resuming
RPC: 13 call_status (status 20)
RPC: 13 call_decode (status 20)
RPC: 13 rpc_verify_header: call rejected 1
RPC: 13 rpc_verify_header: call failed with error -13
RPC: 13 return 0, status -13
RPC: 13 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 13 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 13 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: registering (100021, 1, 17, 43037) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 14 __rpc_execute flags=0x680
RPC: 14 call_start rpcbind2 proc SET (sync)
RPC: 14 call_reserve (status 0)
RPC: 14 reserved req c0812000 xid 612efd36
RPC: 14 call_reserveresult (status 0)
RPC: 14 call_refresh (status 0)
RPC: 14 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 14 refreshing UNIX cred c6fb7d60
RPC: 14 call_refreshresult (status 0)
RPC: 14 call_allocate (status 0)
RPC: 14 allocated buffer of size 412 at c0811800
RPC: 14 call_bind (status 0)
RPC: 14 call_connect xprt c080bc00 is connected
RPC: 14 call_transmit (status 0)
RPC: 14 xprt_prepare_transmit
RPC: 14 rpc_xdr_encode (status 0)
RPC: 14 marshaling UNIX cred c6fb7d60
RPC: 14 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 14 encoding PMAP_SET call (100021, 1, 17, 43037)
RPC: 14 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 14 xmit complete
RPC: 14 sleep_on(queue "xprt_pending" time 4294927644)
RPC: 14 added to queue c080bdac "xprt_pending"
RPC: 14 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 14 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 612efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 612efd36
RPC: XID 612efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 14 xid 612efd36 complete (20 bytes received)
RPC: 14 __rpc_wake_up_task (now 4294927675)
RPC: 14 disabling timer
RPC: 14 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 14 sync task resuming
RPC: 14 call_status (status 20)
RPC: 14 call_decode (status 20)
RPC: 14 rpc_verify_header: retry garbled creds
RPC: 14 call_bind (status 0)
RPC: 14 call_connect xprt c080bc00 is connected
RPC: 14 call_transmit (status 0)
RPC: 14 xprt_prepare_transmit
RPC: 14 rpc_xdr_encode (status 0)
RPC: 14 marshaling UNIX cred c6fb7d60
RPC: 14 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 14 encoding PMAP_SET call (100021, 1, 17, 43037)
RPC: 14 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 14 xmit complete
RPC: 14 sleep_on(queue "xprt_pending" time 4294927711)
RPC: 14 added to queue c080bdac "xprt_pending"
RPC: 14 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 14 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 612efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 612efd36
RPC: XID 612efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 14 xid 612efd36 complete (20 bytes received)
RPC: 14 __rpc_wake_up_task (now 4294927742)
RPC: 14 disabling timer
RPC: 14 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 14 sync task resuming
RPC: 14 call_status (status 20)
RPC: 14 call_decode (status 20)
RPC: 14 rpc_verify_header: retry garbled creds
RPC: 14 call_bind (status 0)
RPC: 14 call_connect xprt c080bc00 is connected
RPC: 14 call_transmit (status 0)
RPC: 14 xprt_prepare_transmit
RPC: 14 rpc_xdr_encode (status 0)
RPC: 14 marshaling UNIX cred c6fb7d60
RPC: 14 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 14 encoding PMAP_SET call (100021, 1, 17, 43037)
RPC: 14 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 14 xmit complete
RPC: 14 sleep_on(queue "xprt_pending" time 4294927778)
RPC: 14 added to queue c080bdac "xprt_pending"
RPC: 14 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 14 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 612efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 612efd36
RPC: XID 612efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 14 xid 612efd36 complete (20 bytes received)
RPC: 14 __rpc_wake_up_task (now 4294927809)
RPC: 14 disabling timer
RPC: 14 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 14 sync task resuming
RPC: 14 call_status (status 20)
RPC: 14 call_decode (status 20)
RPC: 14 rpc_verify_header: call rejected 1
RPC: 14 rpc_verify_header: call failed with error -13
RPC: 14 return 0, status -13
RPC: 14 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 14 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 14 freeing task
RPC: failed to contact local rpcbind server (errno 13).
svc: failed to register lockdv1 RPC service (errno 13).
RPC: unregistering (100021, 1, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 15 __rpc_execute flags=0x680
RPC: 15 call_start rpcbind2 proc UNSET (sync)
RPC: 15 call_reserve (status 0)
RPC: 15 reserved req c0812000 xid 622efd36
RPC: 15 call_reserveresult (status 0)
RPC: 15 call_refresh (status 0)
RPC: 15 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 15 refreshing UNIX cred c6fb7d60
RPC: 15 call_refreshresult (status 0)
RPC: 15 call_allocate (status 0)
RPC: 15 allocated buffer of size 412 at c0811800
RPC: 15 call_bind (status 0)
RPC: 15 call_connect xprt c080bc00 is connected
RPC: 15 call_transmit (status 0)
RPC: 15 xprt_prepare_transmit
RPC: 15 rpc_xdr_encode (status 0)
RPC: 15 marshaling UNIX cred c6fb7d60
RPC: 15 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 15 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 15 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 15 xmit complete
RPC: 15 sleep_on(queue "xprt_pending" time 4294927887)
RPC: 15 added to queue c080bdac "xprt_pending"
RPC: 15 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 15 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 622efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 622efd36
RPC: XID 622efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 15 xid 622efd36 complete (20 bytes received)
RPC: 15 __rpc_wake_up_task (now 4294927918)
RPC: 15 disabling timer
RPC: 15 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 15 sync task resuming
RPC: 15 call_status (status 20)
RPC: 15 call_decode (status 20)
RPC: 15 rpc_verify_header: retry garbled creds
RPC: 15 call_bind (status 0)
RPC: 15 call_connect xprt c080bc00 is connected
RPC: 15 call_transmit (status 0)
RPC: 15 xprt_prepare_transmit
RPC: 15 rpc_xdr_encode (status 0)
RPC: 15 marshaling UNIX cred c6fb7d60
RPC: 15 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 15 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 15 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 15 xmit complete
RPC: 15 sleep_on(queue "xprt_pending" time 4294927954)
RPC: 15 added to queue c080bdac "xprt_pending"
RPC: 15 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 15 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 622efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 622efd36
RPC: XID 622efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 15 xid 622efd36 complete (20 bytes received)
RPC: 15 __rpc_wake_up_task (now 4294927985)
RPC: 15 disabling timer
RPC: 15 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 15 sync task resuming
RPC: 15 call_status (status 20)
RPC: 15 call_decode (status 20)
RPC: 15 rpc_verify_header: retry garbled creds
RPC: 15 call_bind (status 0)
RPC: 15 call_connect xprt c080bc00 is connected
RPC: 15 call_transmit (status 0)
RPC: 15 xprt_prepare_transmit
RPC: 15 rpc_xdr_encode (status 0)
RPC: 15 marshaling UNIX cred c6fb7d60
RPC: 15 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 15 encoding PMAP_UNSET call (100021, 1, 0, 0)
RPC: 15 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 15 xmit complete
RPC: 15 sleep_on(queue "xprt_pending" time 4294928021)
RPC: 15 added to queue c080bdac "xprt_pending"
RPC: 15 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 15 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 622efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 622efd36
RPC: XID 622efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 15 xid 622efd36 complete (20 bytes received)
RPC: 15 __rpc_wake_up_task (now 4294928053)
RPC: 15 disabling timer
RPC: 15 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 15 sync task resuming
RPC: 15 call_status (status 20)
RPC: 15 call_decode (status 20)
RPC: 15 rpc_verify_header: call rejected 1
RPC: 15 rpc_verify_header: call failed with error -13
RPC: 15 return 0, status -13
RPC: 15 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 15 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 15 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 3, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 16 __rpc_execute flags=0x680
RPC: 16 call_start rpcbind2 proc UNSET (sync)
RPC: 16 call_reserve (status 0)
RPC: 16 reserved req c0812000 xid 632efd36
RPC: 16 call_reserveresult (status 0)
RPC: 16 call_refresh (status 0)
RPC: 16 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 16 refreshing UNIX cred c6fb7d60
RPC: 16 call_refreshresult (status 0)
RPC: 16 call_allocate (status 0)
RPC: 16 allocated buffer of size 412 at c0811800
RPC: 16 call_bind (status 0)
RPC: 16 call_connect xprt c080bc00 is connected
RPC: 16 call_transmit (status 0)
RPC: 16 xprt_prepare_transmit
RPC: 16 rpc_xdr_encode (status 0)
RPC: 16 marshaling UNIX cred c6fb7d60
RPC: 16 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 16 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 16 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 16 xmit complete
RPC: 16 sleep_on(queue "xprt_pending" time 4294928128)
RPC: 16 added to queue c080bdac "xprt_pending"
RPC: 16 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 16 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 632efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 632efd36
RPC: XID 632efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 16 xid 632efd36 complete (20 bytes received)
RPC: 16 __rpc_wake_up_task (now 4294928159)
RPC: 16 disabling timer
RPC: 16 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 16 sync task resuming
RPC: 16 call_status (status 20)
RPC: 16 call_decode (status 20)
RPC: 16 rpc_verify_header: retry garbled creds
RPC: 16 call_bind (status 0)
RPC: 16 call_connect xprt c080bc00 is connected
RPC: 16 call_transmit (status 0)
RPC: 16 xprt_prepare_transmit
RPC: 16 rpc_xdr_encode (status 0)
RPC: 16 marshaling UNIX cred c6fb7d60
RPC: 16 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 16 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 16 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 16 xmit complete
RPC: 16 sleep_on(queue "xprt_pending" time 4294928196)
RPC: 16 added to queue c080bdac "xprt_pending"
RPC: 16 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 16 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 632efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 632efd36
RPC: XID 632efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 16 xid 632efd36 complete (20 bytes received)
RPC: 16 __rpc_wake_up_task (now 4294928227)
RPC: 16 disabling timer
RPC: 16 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 16 sync task resuming
RPC: 16 call_status (status 20)
RPC: 16 call_decode (status 20)
RPC: 16 rpc_verify_header: retry garbled creds
RPC: 16 call_bind (status 0)
RPC: 16 call_connect xprt c080bc00 is connected
RPC: 16 call_transmit (status 0)
RPC: 16 xprt_prepare_transmit
RPC: 16 rpc_xdr_encode (status 0)
RPC: 16 marshaling UNIX cred c6fb7d60
RPC: 16 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 16 encoding PMAP_UNSET call (100021, 3, 0, 0)
RPC: 16 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 16 xmit complete
RPC: 16 sleep_on(queue "xprt_pending" time 4294928263)
RPC: 16 added to queue c080bdac "xprt_pending"
RPC: 16 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 16 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 632efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 632efd36
RPC: XID 632efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 16 xid 632efd36 complete (20 bytes received)
RPC: 16 __rpc_wake_up_task (now 4294928294)
RPC: 16 disabling timer
RPC: 16 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 16 sync task resuming
RPC: 16 call_status (status 20)
RPC: 16 call_decode (status 20)
RPC: 16 rpc_verify_header: call rejected 1
RPC: 16 rpc_verify_header: call failed with error -13
RPC: 16 return 0, status -13
RPC: 16 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 16 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 16 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: unregistering (100021, 4, 0, 0) with local rpcbind
RPC: new task initialized, procpid 68
RPC: allocated task c786fc20
RPC: 17 __rpc_execute flags=0x680
RPC: 17 call_start rpcbind2 proc UNSET (sync)
RPC: 17 call_reserve (status 0)
RPC: 17 reserved req c0812000 xid 642efd36
RPC: 17 call_reserveresult (status 0)
RPC: 17 call_refresh (status 0)
RPC: 17 looking up UNIX cred
RPC: looking up UNIX cred
RPC: 17 refreshing UNIX cred c6fb7d60
RPC: 17 call_refreshresult (status 0)
RPC: 17 call_allocate (status 0)
RPC: 17 allocated buffer of size 412 at c0811800
RPC: 17 call_bind (status 0)
RPC: 17 call_connect xprt c080bc00 is connected
RPC: 17 call_transmit (status 0)
RPC: 17 xprt_prepare_transmit
RPC: 17 rpc_xdr_encode (status 0)
RPC: 17 marshaling UNIX cred c6fb7d60
RPC: 17 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 17 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 17 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 17 xmit complete
RPC: 17 sleep_on(queue "xprt_pending" time 4294928369)
RPC: 17 added to queue c080bdac "xprt_pending"
RPC: 17 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 17 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 642efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 642efd36
RPC: XID 642efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 17 xid 642efd36 complete (20 bytes received)
RPC: 17 __rpc_wake_up_task (now 4294928400)
RPC: 17 disabling timer
RPC: 17 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 17 sync task resuming
RPC: 17 call_status (status 20)
RPC: 17 call_decode (status 20)
RPC: 17 rpc_verify_header: retry garbled creds
RPC: 17 call_bind (status 0)
RPC: 17 call_connect xprt c080bc00 is connected
RPC: 17 call_transmit (status 0)
RPC: 17 xprt_prepare_transmit
RPC: 17 rpc_xdr_encode (status 0)
RPC: 17 marshaling UNIX cred c6fb7d60
RPC: 17 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 17 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 17 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 17 xmit complete
RPC: 17 sleep_on(queue "xprt_pending" time 4294928437)
RPC: 17 added to queue c080bdac "xprt_pending"
RPC: 17 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 17 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 642efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 642efd36
RPC: XID 642efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 17 xid 642efd36 complete (20 bytes received)
RPC: 17 __rpc_wake_up_task (now 4294928468)
RPC: 17 disabling timer
RPC: 17 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 17 sync task resuming
RPC: 17 call_status (status 20)
RPC: 17 call_decode (status 20)
RPC: 17 rpc_verify_header: retry garbled creds
RPC: 17 call_bind (status 0)
RPC: 17 call_connect xprt c080bc00 is connected
RPC: 17 call_transmit (status 0)
RPC: 17 xprt_prepare_transmit
RPC: 17 rpc_xdr_encode (status 0)
RPC: 17 marshaling UNIX cred c6fb7d60
RPC: 17 using AUTH_UNIX cred c6fb7d60 to wrap rpc data
RPC: 17 encoding PMAP_UNSET call (100021, 4, 0, 0)
RPC: 17 xprt_transmit(108)
RPC: xs_tcp_send_request(108) = 108
RPC: 17 xmit complete
RPC: 17 sleep_on(queue "xprt_pending" time 4294928504)
RPC: 17 added to queue c080bdac "xprt_pending"
RPC: 17 setting alarm for 60000 ms
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
RPC: 17 sync task going to sleep
RPC: xs_tcp_data_ready...0xc6fc6040 0
RPC: xs_tcp_data_recv started
RPC: reading TCP record fragment of length 20
RPC: reading XID (4 bytes)
RPC: reading reply for XID 642efd36
RPC: reading CALL/REPLY flag (4 bytes)
RPC: read reply XID 642efd36
RPC: XID 642efd36 read 12 bytes
RPC: xprt = c080bc00, tcp_copied = 20, tcp_offset = 20, tcp_reclen = 20
RPC: 17 xid 642efd36 complete (20 bytes received)
RPC: 17 __rpc_wake_up_task (now 4294928535)
RPC: 17 disabling timer
RPC: 17 removed from queue c080bdac "xprt_pending"
RPC: __rpc_wake_up_task done
RPC: xs_tcp_data_recv done
RPC: 17 sync task resuming
RPC: 17 call_status (status 20)
RPC: 17 call_decode (status 20)
RPC: 17 rpc_verify_header: call rejected 1
RPC: 17 rpc_verify_header: call failed with error -13
RPC: 17 return 0, status -13
RPC: 17 release task
RPC: freeing buffer of size 412 at c0811800
RPC: 17 release request c0812000
RPC: wake_up_next(c080be04 "xprt_backlog")
RPC: rpc_release_client(c6f323a0)
RPC: 17 freeing task
RPC: failed to contact local rpcbind server (errno 13).
RPC: shutting down nfs client for 192.168.0.101
RPC: rpc_release_client(c6f322a0)
RPC: destroying nfs client for 192.168.0.101
RPC: destroying transport c6fac400
RPC: xs_destroy xprt c6fac400
RPC: xs_close xprt c6fac400
RPC: disconnected transport c6fac400
~ # RPC: xs_close xprt c080bc00
RPC: disconnected transport c080bc00
RPC: wake_up_next(c080bd54 "xprt_resend")
RPC: wake_up_next(c080bcfc "xprt_sending")
--
Michal Simek, Ing. (M.Eng)
w: www.monstr.eu p: +42-0-721842854
Maintainer of Linux kernel 2.6 Microblaze Linux - http://www.monstr.eu/fdt/
Microblaze U-BOOT custodian
next reply other threads:[~2011-03-02 13:04 UTC|newest]
Thread overview: 12+ messages / expand[flat|nested] mbox.gz Atom feed top
2011-03-02 13:04 Michal Simek [this message]
[not found] ` <4D6E4052.7050201-pSz03upnqPeHXe+LvDLADg@public.gmane.org>
2011-03-02 15:49 ` NFS problem on Microblaze LE J. Bruce Fields
2011-03-02 16:11 ` Michal Simek
2011-03-02 17:34 ` J. Bruce Fields
2011-03-02 18:20 ` Michal Simek
2011-03-02 18:24 ` J. Bruce Fields
2011-03-03 9:29 ` Michal Simek
2011-03-03 14:55 ` J. Bruce Fields
2011-03-03 15:01 ` Chuck Lever
2011-03-03 15:08 ` Michal Simek
2011-03-03 15:51 ` Chuck Lever
2011-03-03 16:20 ` Michal Simek
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=4D6E4052.7050201@monstr.eu \
--to=monstr@monstr.eu \
--cc=Trond.Myklebust@netapp.com \
--cc=bfields@fieldses.org \
--cc=chuck.lever@oracle.com \
--cc=linux-nfs@vger.kernel.org \
--cc=neilb@suse.de \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.