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
--
To unsubscribe from this list: send the line "unsubscribe linux-nfs" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
