From: Lukas Razik <linux@razik.name>
To: Trond Myklebust <Trond.Myklebust@netapp.com>
Cc: Chuck Lever <chuck.lever@oracle.com>, Jim Rees <rees@umich.edu>,
Linux NFS Mailing List <linux-nfs@vger.kernel.org>
Subject: Re: [BUG?] Maybe NFS bug since 2.6.37 on SPARC64
Date: Sat, 5 Nov 2011 02:14:12 +0000 (GMT) [thread overview]
Message-ID: <1320459252.59518.YahooMailNeo@web24716.mail.ird.yahoo.com> (raw)
In-Reply-To: <1320455965.2750.9.camel@lade.trondhjem.org>
***IMPORTANT HINT***
In the test belowI've tried to ping the NFS client (cluster2) as soon as I've got the
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
message.
And at first I've got no answers from the client. But 10-20sec later I've got pongs back.
So the NIC comes up some seconds later than the message "link becomes ready" is shown...
In the case of 'mountproto=tcp' it comes up after the kernel panic. So that's much too late.
[ 126.134520] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 128.282991] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx
[ 128.305364] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 128.326199] IP-Config: Complete:
[ 128.326269] device=eth0, addr=137.226.167.242, mask=255.255.255.224, gw=255.255.255.255,
[ 128.326471] host=137.226.167.242, domain=, nis-domain=(none),
[ 128.326586] bootserver=255.255.255.255, rootserver=137.226.167.241, rootpath=
[ 128.327055] Root-NFS: nfsroot=/srv/nfs/cluster2,mountproto=tcp
[ 128.327404] NFS: nfs mount opts='vers=2,udp,rsize=4096,wsize=4096,mountproto=tcp,nolock,addr=137.226.167.241'
[ 128.327477] NFS: parsing nfs mount option 'vers=2'
[ 128.327538] NFS: parsing nfs mount option 'udp'
[ 128.327573] NFS: parsing nfs mount option 'rsize=4096'
[ 128.327623] NFS: parsing nfs mount option 'wsize=4096'
[ 128.327673] NFS: parsing nfs mount option 'mountproto=tcp'
[ 128.327728] NFS: parsing nfs mount option 'nolock'
[ 128.327774] NFS: parsing nfs mount option 'addr=137.226.167.241'
[ 128.327838] NFS: MNTPATH: '/srv/nfs/cluster2'
[ 128.327873] NFS: sending MNT request for 137.226.167.241:/srv/nfs/cluster2
[ 128.327942] RPC: set up xprt to 137.226.167.241 (autobind) via tcp
[ 128.327992] RPC: created transport fffff807fdec0800 with 128 slots
[ 128.328042] RPC: creating mount client for 137.226.167.241 (xprt fffff807fdec0800)
[ 128.328103] RPC: creating UNIX authenticator for client fffff807fdea0600
[ 128.328168] RPC: new task initialized, procpid 1
[ 128.328213] RPC: allocated task fffff807fe38e000
[ 128.328260] RPC: 1 __rpc_execute flags=0x680
[ 128.328295] RPC: 1 call_start mount1 proc NULL (sync)
[ 128.328341] RPC: 1 call_reserve (status 0)
[ 128.328376] RPC: 1 reserved req fffff807fdea0400 xid 39411b74
[ 128.328425] RPC: wake_up_next(fffff807fdec0978 "xprt_sending")
[ 128.328474] RPC: 1 call_reserveresult (status 0)
[ 128.328518] RPC: 1 call_refresh (status 0)
[ 128.328551] RPC: 1 holding NULL cred 00000000008b8cf0
[ 128.328597] RPC: 1 refreshing NULL cred 00000000008b8cf0
[ 128.328643] RPC: 1 call_refreshresult (status 0)
[ 128.328687] RPC: 1 call_allocate (status 0)
[ 128.328722] RPC: 1 allocated buffer of size 96 at fffff807fdec1000
[ 128.328770] RPC: 1 call_bind (status 0)
[ 128.328804] RPC: 1 rpcb_getport_async(137.226.167.241, 100005, 1, 6)
[ 128.328854] RPC: 1 sleep_on(queue "xprt_binding" time 4294893514)
[ 128.328902] RPC: 1 added to queue fffff807fdec08c8 "xprt_binding"
[ 128.328949] RPC: 1 setting alarm for 60000 ms
[ 128.328986] RPC: 1 rpcb_getport_async: trying rpcbind version 2
[ 128.329046] RPC: set up xprt to 137.226.167.241 (port 111) via tcp
[ 128.329096] RPC: created transport fffff807fdec1800 with 128 slots
[ 128.329146] RPC: creating rpcbind client for 137.226.167.241 (xprt fffff807fdec1800)
[ 128.329204] RPC: creating UNIX authenticator for client fffff807fdea0c00
[ 128.329262] RPC: new task initialized, procpid 1
[ 128.329306] RPC: allocated task fffff807fe38e0e0
[ 128.329358] RPC: rpc_release_client(fffff807fdea0c00)
[ 128.329406] RPC: 1 sync task going to sleep
[ 128.329446] RPC: 2 __rpc_execute flags=0x681
[ 128.329482] RPC: 2 call_start rpcbind2 proc GETPORT (async)
[ 128.329529] RPC: 2 call_reserve (status 0)
[ 128.329563] RPC: 2 reserved req fffff807fdea0a00 xid 54cf8ddb
[ 128.329610] RPC: wake_up_next(fffff807fdec1978 "xprt_sending")
[ 128.329659] RPC: 2 call_reserveresult (status 0)
[ 128.329703] RPC: 2 call_refresh (status 0)
[ 128.329735] RPC: 2 looking up UNIX cred
[ 128.329765] RPC: looking up UNIX cred
[ 128.329809] RPC: allocating UNIX cred for uid 0 gid 0
[ 128.329859] RPC: 2 refreshing UNIX cred fffff807fe37c900
[ 128.329906] RPC: 2 call_refreshresult (status 0)
[ 128.329950] RPC: 2 call_allocate (status 0)
[ 128.329984] RPC: 2 allocated buffer of size 416 at fffff807fdec2000
[ 128.330032] RPC: 2 call_bind (status 0)
[ 128.330064] RPC: 2 call_connect xprt fffff807fdec1800 is not connected
[ 128.330114] RPC: 2 xprt_connect xprt fffff807fdec1800 is not connected
[ 128.330165] RPC: 2 sleep_on(queue "xprt_pending" time 4294893515)
[ 128.330213] RPC: 2 added to queue fffff807fdec1a28 "xprt_pending"
[ 128.330260] RPC: 2 setting alarm for 60000 ms
[ 128.330294] RPC: xs_connect scheduled xprt fffff807fdec1800
[ 128.330453] RPC: xs_bind 0.0.0.0:0: ok (0)
[ 128.330490] RPC: worker connecting xprt fffff807fdec1800 via tcp to 137.226.167.241 (port 111)
[ 128.330650] RPC: fffff807fdec1800 connect status 36 connected 0 sock state 2
[ 131.335657] RPC: xs_error_report client fffff807fdec1800...
[ 131.335665] RPC: error 65
[ 131.335822] RPC: 2 __rpc_wake_up_task (now 4294894266)
[ 131.335924] RPC: 2 disabling timer
[ 131.335985] RPC: 2 removed from queue fffff807fdec1a28 "xprt_pending"
[ 131.336061] RPC: __rpc_wake_up_task done
[ 131.336097] RPC: xs_tcp_state_change client fffff807fdec1800...
[ 131.336146] RPC: state 7 conn 0 dead 0 zapped 1 sk_shutdown 3
[ 131.336194] RPC: disconnected transport fffff807fdec1800
[ 131.336291] RPC: 2 __rpc_execute flags=0x681
[ 131.336355] RPC: 2 xprt_connect_status: retrying
[ 131.336399] RPC: 2 call_connect_status (status -11)
[ 131.336473] RPC: 2 call_transmit (status 0)
[ 131.336504] RPC: 2 xprt_prepare_transmit
[ 131.336565] RPC: 2 rpc_xdr_encode (status 0)
[ 131.336599] RPC: 2 marshaling UNIX cred fffff807fe37c900
[ 131.336676] RPC: 2 using AUTH_UNIX cred fffff807fe37c900 to wrap rpc data
[ 131.336757] RPC: 2 encoding PMAP_GETPORT call (100005, 1, 6, 0)
[ 131.336805] RPC: 2 xprt_transmit(96)
[ 131.336873] RPC: xs_tcp_send_request(96) = -65
[ 131.336946] RPC: sendmsg returned unrecognized error 65
[ 131.336993] RPC: xs_tcp_state_change client fffff807fdec1800...
[ 131.337071] RPC: state 7 conn 0 dead 0 zapped 1 sk_shutdown 3
[ 131.337146] RPC: disconnected transport fffff807fdec1800
[ 131.337195] RPC: wake_up_next(fffff807fdec1978 "xprt_sending")
[ 131.337273] RPC: setting port for xprt fffff807fdec0800 to 0
[ 131.337352] RPC: 2 rpcb_getport_done(status -65, port 0)
[ 131.337398] RPC: 2 return 0, status -65
[ 131.337456] RPC: 2 release task
[ 131.337490] RPC: freeing buffer of size 416 at fffff807fdec2000
[ 131.337568] RPC: 2 release request fffff807fdea0a00
[ 131.337642] RPC: wake_up_next(fffff807fdec1ad8 "xprt_backlog")
[ 131.337690] RPC: rpc_release_client(fffff807fdea0c00)
[ 131.337764] RPC: destroying rpcbind client for 137.226.167.241
[ 131.337841] RPC: destroying transport fffff807fdec1800
[ 131.337921] RPC: xs_destroy xprt fffff807fdec1800
[ 131.338045] RPC: xs_close xprt fffff807fdec1800
[ 131.338134] RPC: disconnected transport fffff807fdec1800
[ 131.338184] RPC: 2 freeing task
[ 131.338245] RPC: 1 __rpc_wake_up_task (now 4294894266)
[ 131.338317] RPC: 1 disabling timer
[ 131.338348] RPC: 1 removed from queue fffff807fdec08c8 "xprt_binding"
[ 131.338429] RPC: __rpc_wake_up_task done
[ 131.338443] RPC: 1 sync task resuming
[ 131.338454] RPC: 1 remote rpcbind unreachable: -65
[ 131.338466] RPC: 1 return 0, status -65
[ 131.338473] RPC: 1 release task
[ 131.338483] RPC: freeing buffer of size 96 at fffff807fdec1000
[ 131.338495] RPC: 1 release request fffff807fdea0400
[ 131.338505] RPC: wake_up_next(fffff807fdec0ad8 "xprt_backlog")
[ 131.338517] RPC: rpc_release_client(fffff807fdea0600)
[ 131.338527] RPC: 1 freeing task
[ 131.338537] RPC: shutting down mount client for 137.226.167.241
[ 131.338548] RPC: rpc_release_client(fffff807fdea0600)
[ 131.338559] RPC: destroying UNIX authenticator 00000000008b8d78
[ 131.338572] RPC: destroying mount client for 137.226.167.241
[ 131.338584] NFS: failed to create MNT RPC client, status=-65
[ 131.338593] NFS: unable to mount server 137.226.167.241, error -65
[ 131.338627] VFS: Unable to mount root fs via NFS, trying floppy.
[ 131.338687] List of all partitions:
[ 131.338697] No filesystem could mount root, tried:
[ 131.338713] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(2,0)
[ 131.338723] Call Trace:
[ 131.338752] [00000000008c6d18] mount_block_root+0x210/0x228
[ 131.338767] [00000000008c6df4] mount_root+0xc4/0xd4
[ 131.338781] [00000000008c6f80] prepare_namespace+0x17c/0x1c0
[ 131.338795] [00000000008c6250] kernel_init+0x140/0x158
[ 131.338816] [000000000042ac68] kernel_thread+0x30/0x48
[ 131.338835] [0000000000767840] rest_init+0x18/0x74
[ 131.338941] Press Stop-A (L1-A) to return to the boot prom
[ 131.339844] RPC: destroying transport fffff807fdec0800
[ 131.339948] RPC: xs_destroy xprt fffff807fdec0800
[ 131.340039] RPC: xs_close xprt fffff807fdec0800
[ 131.340114] RPC: disconnected transport fffff807fdec0800
[ 138.467087] eth0: no IPv6 routers present
next prev parent reply other threads:[~2011-11-05 2:14 UTC|newest]
Thread overview: 53+ messages / expand[flat|nested] mbox.gz Atom feed top
2011-11-03 19:43 [BUG?] Maybe NFS bug since 2.6.37 on SPARC64 Lukas Razik
2011-11-03 20:54 ` Trond Myklebust
2011-11-03 21:10 ` Chuck Lever
2011-11-03 21:11 ` Jim Rees
2011-11-03 21:16 ` Chuck Lever
2011-11-03 21:37 ` Lukas Razik
2011-11-03 21:51 ` Chuck Lever
2011-11-03 23:09 ` Lukas Razik
2011-11-03 23:59 ` Jim Rees
2011-11-04 0:59 ` Lukas Razik
2011-11-04 1:06 ` Chuck Lever
2011-11-04 1:33 ` Lukas Razik
2011-11-04 9:44 ` Lukas Razik
2011-11-04 13:20 ` Jim Rees
2011-11-04 14:01 ` Chuck Lever
2011-11-04 14:09 ` Myklebust, Trond
2011-11-04 14:24 ` J. Bruce Fields
2011-11-04 14:46 ` Jim Rees
2011-11-04 15:02 ` Lukas Razik
2011-11-04 15:18 ` Myklebust, Trond
2011-11-04 15:46 ` Lukas Razik
2011-11-04 22:55 ` Chuck Lever
2011-11-04 23:17 ` Lukas Razik
2011-11-04 13:54 ` Chuck Lever
2011-11-04 14:57 ` Lukas Razik
2011-11-04 16:56 ` Lukas Razik
2011-11-04 17:55 ` Lukas Razik
2011-11-04 23:15 ` NFSROOT mount fails on SPARC after 2.6.37 Chuck Lever
2011-11-04 23:15 ` Chuck Lever
2011-11-05 2:03 ` David Miller
2011-11-05 2:03 ` David Miller
2011-11-05 2:38 ` Trond Myklebust
2011-11-05 2:38 ` Trond Myklebust
2011-11-04 23:40 ` [BUG?] Maybe NFS bug since 2.6.37 on SPARC64 Lukas Razik
2011-11-05 1:19 ` Trond Myklebust
2011-11-05 1:52 ` Lukas Razik
2011-11-05 2:14 ` Lukas Razik [this message]
2011-11-05 2:30 ` Trond Myklebust
2011-11-05 2:31 ` Trond Myklebust
2011-11-05 2:31 ` Trond Myklebust
2011-11-05 3:51 ` Lukas Razik
2011-11-05 13:05 ` Jim Rees
2011-11-12 11:35 ` Lukas Razik
2011-11-12 18:49 ` Jim Rees
2011-11-12 21:06 ` Chuck Lever
2011-11-13 1:03 ` Lukas Razik
2011-11-13 19:32 ` Chuck Lever
2011-11-13 21:28 ` Lukas Razik
2011-11-13 22:19 ` Lukas Razik
2011-11-14 15:31 ` Chuck Lever
2011-11-03 21:18 ` Lukas Razik
2011-11-03 21:38 ` Jim Rees
2011-11-03 21:58 ` Lukas Razik
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=1320459252.59518.YahooMailNeo@web24716.mail.ird.yahoo.com \
--to=linux@razik.name \
--cc=Trond.Myklebust@netapp.com \
--cc=chuck.lever@oracle.com \
--cc=linux-nfs@vger.kernel.org \
--cc=rees@umich.edu \
/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.