Sabastien, can you check if regular telnet or ssh to localhost is slow too? if you have any kind of firewalls setup, can you temporarily disable them and try as well? connect() being slow doesnt seem to have its root cause within glusterfs as such. thanks, avati 2007/7/16, Sebastien LELIEVRE <slelievre@xxxxxxxxxxxxxxxx>:
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 > Gluster-devel@xxxxxxxxxx > http://lists.nongnu.org/mailman/listinfo/gluster-devel _______________________________________________ Gluster-devel mailing list Gluster-devel@xxxxxxxxxx http://lists.nongnu.org/mailman/listinfo/gluster-devel
-- Anand V. Avati
