2008-07-20 23:58:47 D [glusterfs.c:198:get_spec_fp] glusterfs: loading spec from /etc/glusterfs/glusterfs-server.vol

Version: glusterfs 1.4.0qa29
TLA Repo Revision: glusterfs--mainline--3.0--patch-238
Time : 2008-07-20 23:58:47

glusterfsd -f /etc/glusterfs/glusterfs-server.vol -l glusterfsd.log.20080720.235847.17304 -L DEBUG

volume eon0
  type storage/posix
  option directory /export/eon0
end-volume

volume eon1
  type storage/posix
  option directory /export/eon1
end-volume

volume eon2
  type storage/posix
  option directory /export/eon2
end-volume

volume glfs-ns
  type storage/posix
  option directory /export/glfs-ns
end-volume

volume iothreads-eon0
  type performance/io-threads
  option thread-count 4  # deault is 1
  option cache-size 64MB
  subvolumes eon0
end-volume

volume writebehind-eon0
  type performance/write-behind
  #option aggregate-size 131072 # in bytes
  option aggregate-size 1MB # default is 0bytes
  option flush-behind on    # default is 'off'
  subvolumes iothreads-eon0
end-volume

volume readahead-eon0
  type performance/read-ahead
  # enabled on server and client 57MB/s -> 61MB/s
  option page-size 65536 ### in bytes
  option page-count 16 ### memory cache size is page-count x page-size per file
  subvolumes writebehind-eon0
end-volume

volume server
  type protocol/server
  option transport-type tcp/server
  option auth.ip.eon0.allow 10.2.179.*
  option auth.ip.eon1.allow 10.2.179.*
  option auth.ip.eon2.allow 10.2.179.*
  option auth.ip.glfs-ns.allow 10.2.179.*
  subvolumes eon0 eon1 eon2 glfs-ns
end-volume

2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'eon0'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/storage/posix.so
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:eon0:storage/posix
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:eon0:directory:/export/eon0
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:eon0
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'eon1'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/storage/posix.so
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:eon1:storage/posix
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:eon1:directory:/export/eon1
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:eon1
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'eon2'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/storage/posix.so
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:eon2:storage/posix
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:eon2:directory:/export/eon2
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:eon2
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'glfs-ns'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/storage/posix.so
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:glfs-ns:storage/posix
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:glfs-ns:directory:/export/glfs-ns
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:glfs-ns
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'iothreads-eon0'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/performance/io-threads.so
2008-07-20 23:58:47 D [xlator.c:148:xlator_set_type] xlator: dlsym(notify) on /usr/lib/glusterfs/1.4.0qa29/xlator/performance/io-threads.so: undefined symbol: notify -- neglecting
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:iothreads-eon0:performance/io-threads
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:iothreads-eon0:thread-count:4
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:iothreads-eon0:cache-size:64MB
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:iothreads-eon0->eon0
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:iothreads-eon0
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'writebehind-eon0'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/performance/write-behind.so
2008-07-20 23:58:47 D [xlator.c:148:xlator_set_type] xlator: dlsym(notify) on /usr/lib/glusterfs/1.4.0qa29/xlator/performance/write-behind.so: undefined symbol: notify -- neglecting
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:writebehind-eon0:performance/write-behind
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:writebehind-eon0:aggregate-size:1MB
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:writebehind-eon0:flush-behind:on
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:writebehind-eon0->iothreads-eon0
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:writebehind-eon0
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'readahead-eon0'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/performance/read-ahead.so
2008-07-20 23:58:47 D [xlator.c:148:xlator_set_type] xlator: dlsym(notify) on /usr/lib/glusterfs/1.4.0qa29/xlator/performance/read-ahead.so: undefined symbol: notify -- neglecting
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:readahead-eon0:performance/read-ahead
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:readahead-eon0:page-size:65536
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:readahead-eon0:page-count:16
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:readahead-eon0->writebehind-eon0
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:readahead-eon0
2008-07-20 23:58:47 D [spec.y:113:new_section] parser: New node for 'server'
2008-07-20 23:58:47 D [xlator.c:118:xlator_set_type] xlator: attempt to load file /usr/lib/glusterfs/1.4.0qa29/xlator/protocol/server.so
2008-07-20 23:58:47 D [spec.y:133:section_type] parser: Type:server:protocol/server
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:server:transport-type:tcp/server
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:server:auth.ip.eon0.allow:10.2.179.*
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:server:auth.ip.eon1.allow:10.2.179.*
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:server:auth.ip.eon2.allow:10.2.179.*
2008-07-20 23:58:47 D [spec.y:176:section_option] parser: Option:server:auth.ip.glfs-ns.allow:10.2.179.*
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:server->eon0
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:server->eon1
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:server->eon2
2008-07-20 23:58:47 D [spec.y:231:section_sub] parser: child:server->glfs-ns
2008-07-20 23:58:47 D [spec.y:244:section_end] parser: end:server
2008-07-20 23:58:47 D [server-protocol.c:6141:init] server: protocol/server xlator loaded
2008-07-20 23:58:47 D [transport.c:75:transport_load] transport: attempt to load file /usr/lib/glusterfs/1.4.0qa29/transport/tcp.so
2008-07-20 23:58:47 D [server-protocol.c:6187:init] server: defaulting limits.transaction-size to 4194304
2008-07-20 23:58:47 D [io-threads.c:1167:init] io-threads: Using conf->thread_count = 4
2008-07-20 23:58:47 D [io-threads.c:1199:init] io-threads: Using conf->cache_size = 67108864
2008-07-20 23:58:47 D [write-behind.c:1322:init] writebehind-eon0: using aggregate-size = 1048576
2008-07-20 23:58:47 C [dict.c:1140:data_to_str] dict: @data=(nil)
2008-07-20 23:58:47 W [write-behind.c:1343:init] writebehind-eon0: Setting window-size to be equal to aggregate-size(1048576)
2008-07-20 23:58:47 D [write-behind.c:1366:init] writebehind-eon0: enabling flush-behind
2008-07-20 23:58:47 D [read-ahead.c:879:init] readahead-eon0: Using conf->page_size = 0x10000
2008-07-20 23:58:47 D [read-ahead.c:895:init] readahead-eon0: Using conf->page_count = 0x0
2008-07-20 23:58:59 D [ip.c:120:gf_auth] eon0: allowed = "10.2.179.*", received ip addr = "10.2.179.3"
2008-07-20 23:58:59 D [server-protocol.c:5468:mop_setvolume] server: accepted client from 10.2.179.3:1023
2008-07-20 23:58:59 D [server-protocol.c:5511:mop_setvolume] server: creating inode table with lru_limit=1024, xlator=eon0
2008-07-20 23:58:59 D [inode.c:1182:inode_table_new] eon0: creating new inode table with lru_limit=1024, sizeof(inode_t)=156
2008-07-20 23:58:59 D [inode.c:569:__create_inode] eon0/inode: create inode(1)
2008-07-20 23:58:59 D [inode.c:362:__active_inode] eon0/inode: activating inode(1), lru=0/1024
2008-07-20 23:58:59 D [ip.c:120:gf_auth] glfs-ns: allowed = "10.2.179.*", received ip addr = "10.2.179.3"
2008-07-20 23:58:59 D [server-protocol.c:5468:mop_setvolume] server: accepted client from 10.2.179.3:1022
2008-07-20 23:58:59 D [server-protocol.c:5511:mop_setvolume] server: creating inode table with lru_limit=1024, xlator=glfs-ns
2008-07-20 23:58:59 D [inode.c:1182:inode_table_new] glfs-ns: creating new inode table with lru_limit=1024, sizeof(inode_t)=156
2008-07-20 23:58:59 D [inode.c:569:__create_inode] glfs-ns/inode: create inode(1)
2008-07-20 23:58:59 D [inode.c:362:__active_inode] glfs-ns/inode: activating inode(1), lru=0/1024
2008-07-20 23:58:59 D [ip.c:120:gf_auth] eon2: allowed = "10.2.179.*", received ip addr = "10.2.179.3"
2008-07-20 23:58:59 D [server-protocol.c:5468:mop_setvolume] server: accepted client from 10.2.179.3:1021
2008-07-20 23:58:59 D [server-protocol.c:5511:mop_setvolume] server: creating inode table with lru_limit=1024, xlator=eon2
2008-07-20 23:58:59 D [inode.c:1182:inode_table_new] eon2: creating new inode table with lru_limit=1024, sizeof(inode_t)=156
2008-07-20 23:58:59 D [inode.c:569:__create_inode] eon2/inode: create inode(1)
2008-07-20 23:58:59 D [inode.c:362:__active_inode] eon2/inode: activating inode(1), lru=0/1024
2008-07-20 23:58:59 D [ip.c:120:gf_auth] eon1: allowed = "10.2.179.*", received ip addr = "10.2.179.3"
2008-07-20 23:58:59 D [server-protocol.c:5468:mop_setvolume] server: accepted client from 10.2.179.3:1020
2008-07-20 23:58:59 D [server-protocol.c:5511:mop_setvolume] server: creating inode table with lru_limit=1024, xlator=eon1
2008-07-20 23:58:59 D [inode.c:1182:inode_table_new] eon1: creating new inode table with lru_limit=1024, sizeof(inode_t)=156
2008-07-20 23:58:59 D [inode.c:569:__create_inode] eon1/inode: create inode(1)
2008-07-20 23:58:59 D [inode.c:362:__active_inode] eon1/inode: activating inode(1), lru=0/1024
2008-07-20 23:59:24 D [inode.c:569:__create_inode] eon0/inode: create inode(128)
2008-07-20 23:59:24 D [inode.c:362:__active_inode] eon0/inode: activating inode(128), lru=0/1024
2008-07-20 23:59:24 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(128) lru=1/1024
2008-07-20 23:59:24 D [inode.c:397:__passive_inode] eon0/inode: purging inode(0) lru=1/1024
2008-07-20 23:59:24 D [inode.c:362:__active_inode] eon0/inode: activating inode(128), lru=0/1024
2008-07-20 23:59:24 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(128) lru=1/1024
2008-07-20 23:59:33 D [inode.c:569:__create_inode] eon0/inode: create inode(160)
2008-07-20 23:59:33 D [inode.c:362:__active_inode] eon0/inode: activating inode(160), lru=1/1024
2008-07-20 23:59:33 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(160) lru=2/1024
2008-07-20 23:59:33 D [inode.c:397:__passive_inode] eon0/inode: purging inode(0) lru=2/1024
2008-07-20 23:59:33 D [inode.c:362:__active_inode] eon0/inode: activating inode(128), lru=1/1024
2008-07-20 23:59:33 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(128) lru=2/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(160), lru=1/1024
2008-07-20 23:59:38 D [inode.c:569:__create_inode] eon0/inode: create inode(161)
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(161), lru=1/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(161) lru=2/1024
2008-07-20 23:59:38 D [inode.c:397:__passive_inode] eon0/inode: purging inode(0) lru=2/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(161), lru=1/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(161) lru=2/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(161), lru=1/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(161) lru=2/1024
2008-07-20 23:59:38 D [inode.c:569:__create_inode] eon0/inode: create inode(162)
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(162), lru=2/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(162) lru=3/1024
2008-07-20 23:59:38 D [inode.c:397:__passive_inode] eon0/inode: purging inode(0) lru=3/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(162), lru=2/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(162) lru=3/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(162), lru=2/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(162) lru=3/1024
2008-07-20 23:59:38 D [inode.c:569:__create_inode] eon0/inode: create inode(5620)
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(5620), lru=3/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(5620) lru=4/1024
2008-07-20 23:59:38 D [inode.c:397:__passive_inode] eon0/inode: purging inode(0) lru=4/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(5620), lru=3/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(5620) lru=4/1024
2008-07-20 23:59:38 D [inode.c:362:__active_inode] eon0/inode: activating inode(5620), lru=3/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(5620) lru=4/1024
2008-07-20 23:59:38 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(160) lru=5/1024
2008-07-21 00:00:02 D [inode.c:362:__active_inode] eon0/inode: activating inode(5620), lru=4/1024
2008-07-21 00:00:02 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(5620) lru=5/1024
2008-07-21 00:00:02 D [inode.c:362:__active_inode] eon0/inode: activating inode(5620), lru=4/1024
2008-07-21 00:00:02 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(5620) lru=5/1024
2008-07-21 00:00:02 D [inode.c:362:__active_inode] eon0/inode: activating inode(5620), lru=4/1024
2008-07-21 00:00:02 E [tcp.c:90:__tcp_rwv] server: EOF from peer
2008-07-21 00:00:02 E [tcp.c:625:tcp_proto_state_machine] server: socket read failed (Transport endpoint is not connected) in state 1
2008-07-21 00:00:02 D [tcp.c:180:__tcp_disconnect] server: shutdown() returned 0. setting connection state to -1
2008-07-21 00:00:02 E [tcp.c:90:__tcp_rwv] server: EOF from peer
2008-07-21 00:00:02 E [tcp.c:625:tcp_proto_state_machine] server: socket read failed (Transport endpoint is not connected) in state 1
2008-07-21 00:00:02 D [tcp.c:180:__tcp_disconnect] server: shutdown() returned 0. setting connection state to -1
2008-07-21 00:00:02 E [tcp.c:90:__tcp_rwv] server: EOF from peer
2008-07-21 00:00:02 E [tcp.c:625:tcp_proto_state_machine] server: socket read failed (Transport endpoint is not connected) in state 1
2008-07-21 00:00:02 D [tcp.c:180:__tcp_disconnect] server: shutdown() returned 0. setting connection state to -1
2008-07-21 00:00:02 E [tcp.c:90:__tcp_rwv] server: EOF from peer
2008-07-21 00:00:02 E [tcp.c:625:tcp_proto_state_machine] server: socket read failed (Transport endpoint is not connected) in state 1
2008-07-21 00:00:02 D [tcp.c:180:__tcp_disconnect] server: shutdown() returned 0. setting connection state to -1
2008-07-21 00:00:02 D [inode.c:393:__passive_inode] eon0/inode: passivating inode(5620) lru=5/1024
2008-07-21 00:00:02 D [server-protocol.c:6102:server_protocol_cleanup] server: cleaned up transport state for client 10.2.179.3:1023
2008-07-21 00:00:02 E [tcp.c:1329:fini] server: transport 0x100232b0 destroyed
2008-07-21 00:00:02 D [server-protocol.c:6102:server_protocol_cleanup] server: cleaned up transport state for client 10.2.179.3:1022
2008-07-21 00:00:02 E [tcp.c:1329:fini] server: transport 0x10023400 destroyed
2008-07-21 00:00:02 D [server-protocol.c:6102:server_protocol_cleanup] server: cleaned up transport state for client 10.2.179.3:1021
2008-07-21 00:00:02 E [tcp.c:1329:fini] server: transport 0x10023550 destroyed
2008-07-21 00:00:02 D [server-protocol.c:6102:server_protocol_cleanup] server: cleaned up transport state for client 10.2.179.3:1020
2008-07-21 00:00:02 E [tcp.c:1329:fini] server: transport 0x100236a0 destroyed