gluster-devel
[Top][All Lists]
Advanced

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

Re: [Gluster-devel] Connect taking a loooong time on loopback


From: Sebastien LELIEVRE
Subject: Re: [Gluster-devel] Connect taking a loooong time on loopback
Date: Mon, 16 Jul 2007 16:46:27 +0200
User-agent: Thunderbird 1.5.0.12 (X11/20060911)

Hi everyone,

I'm still experiencing the same problem with patch 313

Does anyone have a clue on what could cause such a behaviour ?

Regards,

Sebastien.

Sebastien LELIEVRE a écrit :
> Hi everyone,
> 
> I'm begining glusterfs integration on our new datacenter, and my really
> first tests show me really odd things, just see :
> 
> Client:
> 
> volume brickc
>   type protocol/client
>   option transport-type tcp/client
>   option remote-host 127.0.0.1
>   option remote-port 6996
>   option remote-subvolume brick
> end-volume
> 
> 
> Server:
> 
> volume brick
>   type storage/posix
>   option directory /home/slelievre/gluster-test/server1
> end-volume
> 
> volume server
>   type protocol/server
>   option transport-type tcp/server
>   option bind-address 127.0.0.1
>   option listen-port 6996
>   subvolumes brick
>   option auth.ip.brick.allow *
> end-volume
> 
> 
> As you can see on the log below, the client took nearly 10 mins to
> connect to the server, and connection was broken a little after.
> 
> We are running on mainline-2.5 patch 266 with fuse 2.6.5 and kernel
> 2.6.16 'customed' to our needs
> 
> Is this a 'bug' ? If so, is it fixed on latest versions ?
> 
> Cheers,
> 
> Sebastien.
> 
> logs are :
> 
> Server-side :
> 
> 2007-07-13 12:46:37 D [spec.y:116:new_section] libglusterfs/parser: New
> node for 'brick'
> 2007-07-13 12:46:37 D [spec.y:132:section_type] libglusterfs/parser:
> Type:brick:storage/posix
> 2007-07-13 12:46:37 D [xlator.c:100:xlator_set_type]
> libglusterfs/xlator: attempt to load type storage/posix
> 2007-07-13 12:46:37 D [xlator.c:107:xlator_set_type]
> libglusterfs/xlator: attempt to load file
> /usr/lib/glusterfs/1.3.0-pre5.2/xlator/storage/posix.so
> 2007-07-13 12:46:37 D [spec.y:152:section_option] libglusterfs/parser:
> Option:brick:directory:/home/slelievre/gluster-test/server1
> 2007-07-13 12:46:37 D [spec.y:209:section_end] libglusterfs/parser:
> end:brick
> 2007-07-13 12:46:37 D [spec.y:116:new_section] libglusterfs/parser: New
> node for 'server'
> 2007-07-13 12:46:37 D [spec.y:132:section_type] libglusterfs/parser:
> Type:server:protocol/server
> 2007-07-13 12:46:37 D [xlator.c:100:xlator_set_type]
> libglusterfs/xlator: attempt to load type protocol/server
> 2007-07-13 12:46:37 D [xlator.c:107:xlator_set_type]
> libglusterfs/xlator: attempt to load file
> /usr/lib/glusterfs/1.3.0-pre5.2/xlator/protocol/server.so
> 2007-07-13 12:46:37 D [spec.y:152:section_option] libglusterfs/parser:
> Option:server:transport-type:tcp/server
> 2007-07-13 12:46:37 D [spec.y:152:section_option] libglusterfs/parser:
> Option:server:bind-address:127.0.0.1
> 2007-07-13 12:46:37 D [spec.y:152:section_option] libglusterfs/parser:
> Option:server:listen-port:6996
> 2007-07-13 12:46:37 D [spec.y:194:section_sub] liglusterfs/parser:
> child:server->brick
> 2007-07-13 12:46:37 D [spec.y:152:section_option] libglusterfs/parser:
> Option:server:auth.ip.brick.allow:*
> 2007-07-13 12:46:37 D [spec.y:209:section_end] libglusterfs/parser:
> end:server
> 2007-07-13 12:46:37 D [posix.c:1776:init] brick: missing
> 'inode-lru-limit'. defaulting to 1000
> 2007-07-13 12:46:37 D [inode.c:481:__create_inode] brick/inode: create
> inode(1)
> 2007-07-13 12:46:37 D [inode.c:279:__active_inode] brick/inode:
> activating inode(1), lru=0/1000
> 2007-07-13 12:46:37 D [server-protocol.c:5670:init] protocol/server:
> protocol/server xlator loaded
> 2007-07-13 12:46:37 D [transport.c:81:transport_load]
> libglusterfs/transport: attempt to load type tcp/server
> 2007-07-13 12:46:37 D [transport.c:86:transport_load]
> libglusterfs/transport: attempt to load file
> /usr/lib/glusterfs/1.3.0-pre5.2/transport/tcp/server.so
> 2007-07-13 12:46:50 D [tcp-server.c:136:tcp_server_notify] server:
> Registering socket (8) for new transport object of 127.0.0.1
> 2007-07-13 12:46:50 D [server-protocol.c:5024:mop_setvolume]
> server-protocol: mop_setvolume: received port = 1023
> 2007-07-13 12:46:50 D [server-protocol.c:5040:mop_setvolume]
> server-protocol: mop_setvolume: IP addr = *, received ip addr = 127.0.0.1
> 2007-07-13 12:46:50 D [server-protocol.c:5051:mop_setvolume]
> server-protocol: mop_setvolume: accepted client from 127.0.0.1
> 2007-07-13 12:49:07 D [server-protocol.c:5649:server_protocol_cleanup]
> protocol/server: cleaned up xl_private of 0x8054330
> 2007-07-13 12:49:07 C [tcp.c:81:tcp_disconnect] server: connection
> disconnected
> 2007-07-13 12:49:07 D [tcp-server.c:230:gf_transport_fini] server:
> destroying transport object for 127.0.0.1:1023 (fd=8)
> 2007-07-13 12:52:15 D [tcp-server.c:136:tcp_server_notify] server:
> Registering socket (5) for new transport object of 127.0.0.1
> 2007-07-13 12:52:15 D [server-protocol.c:5024:mop_setvolume]
> server-protocol: mop_setvolume: received port = 1023
> 2007-07-13 12:52:15 D [server-protocol.c:5040:mop_setvolume]
> server-protocol: mop_setvolume: IP addr = *, received ip addr = 127.0.0.1
> 2007-07-13 12:52:15 D [server-protocol.c:5051:mop_setvolume]
> server-protocol: mop_setvolume: accepted client from 127.0.0.1
> 
> Client-side:
> 
> 2007-07-13 12:52:15 D [glusterfs.c:118:get_spec_fp] glusterfs: loading
> spec from /etc/glusterfs/glusterfs-basic.vol
> 2007-07-13 12:52:15 W [fuse-bridge.c:1887:fuse_transport_notify]
> glusterfs-fuse: Ignoring notify event 4
> 2007-07-13 12:52:15 D [spec.y:116:new_section] libglusterfs/parser: New
> node for 'brickc'
> 2007-07-13 12:52:15 D [spec.y:132:section_type] libglusterfs/parser:
> Type:brickc:protocol/client
> 2007-07-13 12:52:15 D [xlator.c:100:xlator_set_type]
> libglusterfs/xlator: attempt to load type protocol/client
> 2007-07-13 12:52:15 D [xlator.c:107:xlator_set_type]
> libglusterfs/xlator: attempt to load file
> /usr/lib/glusterfs/1.3.0-pre5.2/xlator/protocol/client.so
> 2007-07-13 12:52:15 D [spec.y:152:section_option] libglusterfs/parser:
> Option:brickc:transport-type:tcp/client
> 2007-07-13 12:52:15 D [spec.y:152:section_option] libglusterfs/parser:
> Option:brickc:remote-host:127.0.0.1
> 2007-07-13 12:52:15 D [spec.y:152:section_option] libglusterfs/parser:
> Option:brickc:remote-port:6996
> 2007-07-13 12:52:15 D [spec.y:152:section_option] libglusterfs/parser:
> Option:brickc:remote-subvolume:brick
> 2007-07-13 12:52:15 D [spec.y:209:section_end] libglusterfs/parser:
> end:brickc
> 2007-07-13 12:52:15 D [inode.c:481:__create_inode] fuse/inode: create
> inode(1)
> 2007-07-13 12:52:15 D [inode.c:279:__active_inode] fuse/inode:
> activating inode(1), lru=0/0
> 2007-07-13 12:52:15 D [client-protocol.c:4255:init] brickc: missing
> 'inode-lru-limit'. defaulting to 1000
> 2007-07-13 12:52:15 D [client-protocol.c:4272:init] brickc: defaulting
> transport-timeout to 120
> 2007-07-13 12:52:15 D [transport.c:81:transport_load]
> libglusterfs/transport: attempt to load type tcp/client
> 2007-07-13 12:52:15 D [transport.c:86:transport_load]
> libglusterfs/transport: attempt to load file
> /usr/lib/glusterfs/1.3.0-pre5.2/transport/tcp/client.so
> 2007-07-13 12:52:15 D [inode.c:481:__create_inode] brickc/inode: create
> inode(1)
> 2007-07-13 12:52:15 D [inode.c:279:__active_inode] brickc/inode:
> activating inode(1), lru=0/1000
> 2007-07-13 12:52:15 D [client-protocol.c:4550:notify] brickc: got
> GF_EVENT_PARENT_UP, attempting connect on transport
> 2007-07-13 12:52:15 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:52:15 D [tcp-client.c:71:tcp_connect] brickc: socket fd = 4
> 2007-07-13 12:52:15 D [tcp-client.c:89:tcp_connect] brickc: finalized on
> port `1023'
> 2007-07-13 12:52:15 D [tcp-client.c:142:tcp_connect] brickc: connect on
> 4 in progress (non-blocking)
> 2007-07-13 12:52:15 D [tcp-client.c:186:tcp_connect] brickc: connection
> on 4 success
> 2007-07-13 12:52:15 D [client-protocol.c:4571:notify] brickc: got
> GF_EVENT_CHILD_UP
> 2007-07-13 12:52:16 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:52:18 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:52:21 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:52:26 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:52:34 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:52:47 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:53:08 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:53:42 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:54:37 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:56:06 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 12:58:30 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 13:02:24 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 13:08:41 W [client-protocol.c:4008:client_protocol_reconnect]
> brickc: attempting reconnect
> 2007-07-13 13:11:01 D
> [client-protocol.c:4335:client_protocol_handshake_reply] brickc: reply
> frame has callid: 424242
> 2007-07-13 13:11:01 D
> [client-protocol.c:4369:client_protocol_handshake_reply] brickc:
> SETVOLUME on remote-host succeeded
> 2007-07-13 13:18:51 W [client-protocol.c:4016:client_protocol_reconnect]
> brickc: breaking reconnect chain
> 
> 
> ------------------------------------------------------------------------
> 
> _______________________________________________
> Gluster-devel mailing list
> address@hidden
> http://lists.nongnu.org/mailman/listinfo/gluster-devel



reply via email to

[Prev in Thread] Current Thread [Next in Thread]