[2009-12-21 08:12:50] W [socket.c:1407:socket_init] trans: disabling non-blocking IO
[2009-12-21 08:12:50] W [socket.c:1407:socket_init] trans: disabling non-blocking IO
================================================================================
Version      : glusterfs 3.0.0 built on Dec  8 2009 03:09:12
git: 2.0.1-886-g8379edd
Starting Time: 2009-12-21 08:12:50
Command line : /usr/sbin/glusterfs --log-level=NORMAL --volfile-server-port=6996 --volfile-server=localhost /mnt/export/ 
PID          : 1357
System name  : Linux
Nodename     : factory2.staging.shazamteam.net
Kernel Release : 2.6.18-164.9.1.el5
Hardware Identifier: x86_64

Given volfile:
+------------------------------------------------------------------------------+
  1: ## file auto generated by /usr/bin/glusterfs-volgen (mount.vol)
  2: # Cmd line:
  3: # $ /usr/bin/glusterfs-volgen --name dataexp --raid 1 factory1:/data/export factory2:/data/export
  4: 
  5: # RAID 1
  6: # TRANSPORT-TYPE tcp
  7: volume factory2-1
  8:     type protocol/client
  9:     option transport-type tcp
 10:     option remote-host factory2
 11:     option transport.socket.nodelay on
 12:     option transport.remote-port 6996
 13:     option remote-subvolume brick1
 14: end-volume
 15: 
 16: volume factory1-1
 17:     type protocol/client
 18:     option transport-type tcp
 19:     option remote-host factory1
 20:     option transport.socket.nodelay on
 21:     option transport.remote-port 6996
 22:     option remote-subvolume brick1
 23: end-volume
 24: 
 25: volume mirror-0
 26:     type cluster/replicate
 27:     subvolumes factory1-1 factory2-1
 28: end-volume
 29: 
 30: volume writebehind
 31:     type performance/write-behind
 32:     option cache-size 4MB
 33:     subvolumes mirror-0
 34: end-volume
 35: 
 36: volume readahead
 37:     type performance/read-ahead
 38:     option page-count 4
 39:     subvolumes writebehind
 40: end-volume
 41: 
 42: volume iocache
 43:     type performance/io-cache
 44:     option cache-size 1GB
 45:     option cache-timeout 1
 46:     subvolumes readahead
 47: end-volume
 48: 
 49: volume quickread
 50:     type performance/quick-read
 51:     option cache-timeout 1
 52:     option max-file-size 64kB
 53:     subvolumes iocache
 54: end-volume
 55: 
 56: volume statprefetch
 57:     type performance/stat-prefetch
 58:     subvolumes quickread
 59: end-volume
 60: 
 61: volume trace
 62:      type debug/trace
 63:      subvolumes statprefetch
 64:    end-volume
 65: 

+------------------------------------------------------------------------------+
[2009-12-21 08:12:50] C [dict.c:1142:data_to_str] dict: @data=(nil)
[2009-12-21 08:12:50] C [dict.c:1142:data_to_str] dict: @data=(nil)
[2009-12-21 08:12:50] W [xlator.c:655:validate_xlator_volume_options] factory1-1: option 'transport.remote-port' is deprecated, preferred is 'remote-port', continuing with correction
[2009-12-21 08:12:50] W [xlator.c:655:validate_xlator_volume_options] factory2-1: option 'transport.remote-port' is deprecated, preferred is 'remote-port', continuing with correction
[2009-12-21 08:12:50] N [glusterfsd.c:1361:main] glusterfs: Successfully started
[2009-12-21 08:12:50] E [socket.c:760:socket_connect_finish] factory1-1: connection to  failed (Connection refused)
[2009-12-21 08:12:50] E [socket.c:760:socket_connect_finish] factory1-1: connection to  failed (Connection refused)
[2009-12-21 08:12:50] N [fuse-bridge.c:2931:fuse_init] glusterfs-fuse: FUSE inited with protocol versions: glusterfs 7.13 kernel 7.10
[2009-12-21 08:12:50] N [client-protocol.c:6224:client_setvolume_cbk] factory2-1: Connected to 192.168.40.118:6996, attached to remote volume 'brick1'.
[2009-12-21 08:12:50] N [afr.c:2625:notify] mirror-0: Subvolume 'factory2-1' came back up; going online.
[2009-12-21 08:12:50] N [client-protocol.c:6224:client_setvolume_cbk] factory2-1: Connected to 192.168.40.118:6996, attached to remote volume 'brick1'.
[2009-12-21 08:12:50] N [afr.c:2625:notify] mirror-0: Subvolume 'factory2-1' came back up; going online.
[2009-12-21 08:15:24] N [trace.c:1469:trace_lookup] trace: 0: (loc {path=/, ino=1})
[2009-12-21 08:15:24] N [trace.c:648:trace_lookup_cbk] trace: 0: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:12:39]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:12:39]}
[2009-12-21 08:15:24] N [trace.c:1469:trace_lookup] trace: 2: (loc {path=/, ino=1})
[2009-12-21 08:15:24] N [trace.c:648:trace_lookup_cbk] trace: 2: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:12:39]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:12:39]}
[2009-12-21 08:15:33] N [trace.c:1469:trace_lookup] trace: 3: (loc {path=/, ino=1})
[2009-12-21 08:15:33] N [trace.c:648:trace_lookup_cbk] trace: 3: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:12:39]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:12:39]}
[2009-12-21 08:15:33] N [trace.c:1469:trace_lookup] trace: 4: (loc {path=/t0, ino=0})
[2009-12-21 08:15:33] N [trace.c:657:trace_lookup_cbk] trace: 4: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:33] N [trace.c:1469:trace_lookup] trace: 5: (loc {path=/t0, ino=0})
[2009-12-21 08:15:33] N [trace.c:657:trace_lookup_cbk] trace: 5: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:33] N [trace.c:1469:trace_lookup] trace: 6: (loc {path=/t0, ino=0})
[2009-12-21 08:15:33] N [trace.c:657:trace_lookup_cbk] trace: 6: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:33] N [trace.c:1863:trace_create] trace: 7: (loc {path=/t0, ino=0}, flags=0100101 mode=0100644)
[2009-12-21 08:15:33] N [trace.c:115:trace_create_cbk] trace: 7: (op_ret=0, fd=0xe4a9b50, ino=0 *stbuf {st_ino=32440325, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=0, st_atime=[Dec 21 08:15:33], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]}, *preparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:11:49], st_ctime=[Dec 21 08:15:33]}, *postparent = {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]})
[2009-12-21 08:15:33] N [trace.c:1916:trace_writev] trace: 8: (*fd=0xe4a9b50, *vector=0x415d5000, count=1, offset=0)
[2009-12-21 08:15:33] N [trace.c:257:trace_writev_cbk] trace: 8: (op_ret=4096, ino = 32440325, *prebuf = {st_ino=32440325, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:33], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]}, *postbuf = {(null)})
[2009-12-21 08:15:33] N [trace.c:1916:trace_writev] trace: 9: (*fd=0xe4a9b50, *vector=0x415d5000, count=1, offset=4096)
[2009-12-21 08:15:33] N [trace.c:257:trace_writev_cbk] trace: 9: (op_ret=1150, ino = 0, *prebuf = {st_ino=0, st_mode=0, st_nlink=0, st_uid=0, st_gid=0, st_size=0, st_blocks=0, st_atime=[Jan 01 01:00:00], st_mtime=[Jan 01 01:00:00], st_ctime=[Jan 01 01:00:00]}, *postbuf = {(null)})
[2009-12-21 08:15:33] N [trace.c:1962:trace_flush] trace: 10: (*fd=0xe4a9b50)
[2009-12-21 08:15:33] N [trace.c:873:trace_flush_cbk] trace: 10: (op_ret=0, op_errno=0)
[2009-12-21 08:15:35] N [trace.c:1469:trace_lookup] trace: 12: (loc {path=/, ino=1})
[2009-12-21 08:15:35] N [trace.c:648:trace_lookup_cbk] trace: 12: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]}
[2009-12-21 08:15:35] N [trace.c:1469:trace_lookup] trace: 13: (loc {path=/t1, ino=0})
[2009-12-21 08:15:35] N [trace.c:657:trace_lookup_cbk] trace: 13: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:35] N [trace.c:1469:trace_lookup] trace: 14: (loc {path=/t1, ino=0})
[2009-12-21 08:15:35] N [trace.c:657:trace_lookup_cbk] trace: 14: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:35] N [trace.c:1469:trace_lookup] trace: 15: (loc {path=/t1, ino=0})
[2009-12-21 08:15:35] N [trace.c:657:trace_lookup_cbk] trace: 15: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:35] N [trace.c:1863:trace_create] trace: 16: (loc {path=/t1, ino=0}, flags=0100101 mode=0100644)
[2009-12-21 08:15:35] N [trace.c:115:trace_create_cbk] trace: 16: (op_ret=0, fd=0x2aaaac0008c0, ino=0 *stbuf {st_ino=32440327, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=0, st_atime=[Dec 21 08:15:35], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]}, *preparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:35]}, *postparent = {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]})
[2009-12-21 08:15:35] N [trace.c:1916:trace_writev] trace: 17: (*fd=0x2aaaac0008c0, *vector=0x415d5000, count=1, offset=0)
[2009-12-21 08:15:35] N [trace.c:257:trace_writev_cbk] trace: 17: (op_ret=4096, ino = 32440327, *prebuf = {st_ino=32440327, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:35], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]}, *postbuf = {(null)})
[2009-12-21 08:15:35] N [trace.c:1916:trace_writev] trace: 18: (*fd=0x2aaaac0008c0, *vector=0x415d5000, count=1, offset=4096)
[2009-12-21 08:15:35] N [trace.c:257:trace_writev_cbk] trace: 18: (op_ret=1150, ino = 0, *prebuf = {st_ino=0, st_mode=0, st_nlink=0, st_uid=0, st_gid=0, st_size=0, st_blocks=0, st_atime=[Jan 01 01:00:00], st_mtime=[Jan 01 01:00:00], st_ctime=[Jan 01 01:00:00]}, *postbuf = {(null)})
[2009-12-21 08:15:35] N [trace.c:1962:trace_flush] trace: 19: (*fd=0x2aaaac0008c0)
[2009-12-21 08:15:35] N [trace.c:873:trace_flush_cbk] trace: 19: (op_ret=0, op_errno=0)
[2009-12-21 08:15:36] N [trace.c:1469:trace_lookup] trace: 21: (loc {path=/, ino=1})
[2009-12-21 08:15:36] N [trace.c:648:trace_lookup_cbk] trace: 21: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]}
[2009-12-21 08:15:36] N [trace.c:1469:trace_lookup] trace: 22: (loc {path=/t2, ino=0})
[2009-12-21 08:15:36] N [trace.c:657:trace_lookup_cbk] trace: 22: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:36] N [trace.c:1469:trace_lookup] trace: 23: (loc {path=/t2, ino=0})
[2009-12-21 08:15:36] N [trace.c:657:trace_lookup_cbk] trace: 23: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:36] N [trace.c:1469:trace_lookup] trace: 24: (loc {path=/t2, ino=0})
[2009-12-21 08:15:36] N [trace.c:657:trace_lookup_cbk] trace: 24: (op_ret=-1, op_errno=2)
[2009-12-21 08:15:36] N [trace.c:1863:trace_create] trace: 25: (loc {path=/t2, ino=0}, flags=0100101 mode=0100644)
[2009-12-21 08:15:36] N [trace.c:115:trace_create_cbk] trace: 25: (op_ret=0, fd=0x2aaaac001020, ino=0 *stbuf {st_ino=32440329, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=0, st_atime=[Dec 21 08:15:36], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}, *preparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:36]}, *postparent = {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]})
[2009-12-21 08:15:36] N [trace.c:1916:trace_writev] trace: 26: (*fd=0x2aaaac001020, *vector=0x415d5000, count=1, offset=0)
[2009-12-21 08:15:36] N [trace.c:257:trace_writev_cbk] trace: 26: (op_ret=4096, ino = 32440329, *prebuf = {st_ino=32440329, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:36], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}, *postbuf = {(null)})
[2009-12-21 08:15:36] N [trace.c:1916:trace_writev] trace: 27: (*fd=0x2aaaac001020, *vector=0x415d5000, count=1, offset=4096)
[2009-12-21 08:15:36] N [trace.c:257:trace_writev_cbk] trace: 27: (op_ret=1150, ino = 0, *prebuf = {st_ino=0, st_mode=0, st_nlink=0, st_uid=0, st_gid=0, st_size=0, st_blocks=0, st_atime=[Jan 01 01:00:00], st_mtime=[Jan 01 01:00:00], st_ctime=[Jan 01 01:00:00]}, *postbuf = {(null)})
[2009-12-21 08:15:36] N [trace.c:1962:trace_flush] trace: 28: (*fd=0x2aaaac001020)
[2009-12-21 08:15:36] N [trace.c:873:trace_flush_cbk] trace: 28: (op_ret=0, op_errno=0)
[2009-12-21 08:15:47] N [trace.c:1469:trace_lookup] trace: 30: (loc {path=/, ino=1})
[2009-12-21 08:15:47] N [trace.c:648:trace_lookup_cbk] trace: 30: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:13:45], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}
[2009-12-21 08:15:47] N [trace.c:2083:trace_opendir] trace: 33:( loc {path=/, ino=1}, fd=0x2aaaac001020)
[2009-12-21 08:15:47] N [trace.c:894:trace_opendir_cbk] trace: 33: (op_ret=0, op_errno=22, fd=0x2aaaac001020)
[2009-12-21 08:15:47] N [trace.c:2131:trace_readdirp] trace: 34: (fd=0x2aaaac001020, size=4096, offset=0)
[2009-12-21 08:15:47] N [trace.c:326:trace_readdirp_cbk] trace: 34 :(op_ret=5, op_errno=0)
[2009-12-21 08:15:47] N [trace.c:1469:trace_lookup] trace: 35: (loc {path=/, ino=1})
[2009-12-21 08:15:47] N [trace.c:648:trace_lookup_cbk] trace: 35: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:47], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:47], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}
[2009-12-21 08:15:47] N [trace.c:1469:trace_lookup] trace: 36: (loc {path=/t1, ino=32440327})
[2009-12-21 08:15:47] N [trace.c:648:trace_lookup_cbk] trace: 36: (op_ret=0, ino=32440327, *buf {st_ino=32440327, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=5246, st_blocks=24, st_atime=[Dec 21 08:15:35], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]}, *postparent {st_ino=32440327, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=5246, st_blocks=24, st_atime=[Dec 21 08:15:35], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:35]}
[2009-12-21 08:15:47] N [trace.c:1469:trace_lookup] trace: 39: (loc {path=/t2, ino=32440329})
[2009-12-21 08:15:47] N [trace.c:648:trace_lookup_cbk] trace: 39: (op_ret=0, ino=32440329, *buf {st_ino=32440329, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=5246, st_blocks=24, st_atime=[Dec 21 08:15:36], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}, *postparent {st_ino=32440329, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=5246, st_blocks=24, st_atime=[Dec 21 08:15:36], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}
[2009-12-21 08:15:47] N [trace.c:1469:trace_lookup] trace: 42: (loc {path=/t0, ino=32440325})
[2009-12-21 08:15:47] N [trace.c:648:trace_lookup_cbk] trace: 42: (op_ret=0, ino=32440325, *buf {st_ino=32440325, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=5246, st_blocks=24, st_atime=[Dec 21 08:15:33], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]}, *postparent {st_ino=32440325, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=5246, st_blocks=24, st_atime=[Dec 21 08:15:33], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:33]}
[2009-12-21 08:15:47] N [trace.c:2131:trace_readdirp] trace: 47: (fd=0x2aaaac001020, size=4096, offset=2147483647)
[2009-12-21 08:15:47] N [trace.c:326:trace_readdirp_cbk] trace: 47 :(op_ret=0, op_errno=0)
[2009-12-21 08:15:51] N [client-protocol.c:6224:client_setvolume_cbk] factory1-1: Connected to 192.168.40.116:6996, attached to remote volume 'brick1'.
[2009-12-21 08:15:51] N [client-protocol.c:6224:client_setvolume_cbk] factory1-1: Connected to 192.168.40.116:6996, attached to remote volume 'brick1'.
[2009-12-21 08:15:54] N [trace.c:1469:trace_lookup] trace: 49: (loc {path=/, ino=1})
[2009-12-21 08:15:54] N [trace.c:648:trace_lookup_cbk] trace: 49: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:47], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:47], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:36]}
[2009-12-21 08:15:54] N [trace.c:2083:trace_opendir] trace: 52:( loc {path=/, ino=1}, fd=0x2aaaac000b40)
[2009-12-21 08:15:54] N [trace.c:894:trace_opendir_cbk] trace: 52: (op_ret=0, op_errno=22, fd=0x2aaaac000b40)
[2009-12-21 08:15:54] N [trace.c:2131:trace_readdirp] trace: 53: (fd=0x2aaaac000b40, size=4096, offset=0)
[2009-12-21 08:15:54] N [trace.c:326:trace_readdirp_cbk] trace: 53 :(op_ret=6, op_errno=0)
[2009-12-21 08:15:54] N [trace.c:1469:trace_lookup] trace: 54: (loc {path=/, ino=1})
[2009-12-21 08:15:54] N [trace.c:648:trace_lookup_cbk] trace: 54: (op_ret=0, ino=1, *buf {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:54], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:54]}, *postparent {st_ino=1, st_mode=40755, st_nlink=2, st_uid=0, st_gid=0, st_size=4096, st_blocks=16, st_atime=[Dec 21 08:15:54], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:54]}
[2009-12-21 08:15:54] N [trace.c:1469:trace_lookup] trace: 55: (loc {path=/t2, ino=32440329})
[2009-12-21 08:15:54] N [trace.c:648:trace_lookup_cbk] trace: 55: (op_ret=0, ino=32440329, *buf {st_ino=98500614, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=8, st_atime=[Dec 21 08:15:36], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:54]}, *postparent {st_ino=98500614, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=8, st_atime=[Dec 21 08:15:36], st_mtime=[Dec 21 08:15:36], st_ctime=[Dec 21 08:15:54]}
[2009-12-21 08:15:54] N [trace.c:1469:trace_lookup] trace: 58: (loc {path=/t1, ino=32440327})
[2009-12-21 08:15:54] N [trace.c:648:trace_lookup_cbk] trace: 58: (op_ret=0, ino=32440327, *buf {st_ino=98500612, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=8, st_atime=[Dec 21 08:15:35], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:54]}, *postparent {st_ino=98500612, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=8, st_atime=[Dec 21 08:15:35], st_mtime=[Dec 21 08:15:35], st_ctime=[Dec 21 08:15:54]}
[2009-12-21 08:15:54] N [trace.c:1469:trace_lookup] trace: 63: (loc {path=/t0, ino=32440325})
[2009-12-21 08:15:54] N [trace.c:648:trace_lookup_cbk] trace: 63: (op_ret=0, ino=32440325, *buf {st_ino=98500618, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=8, st_atime=[Dec 21 08:15:33], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:54]}, *postparent {st_ino=98500618, st_mode=100644, st_nlink=1, st_uid=0, st_gid=0, st_size=0, st_blocks=8, st_atime=[Dec 21 08:15:33], st_mtime=[Dec 21 08:15:33], st_ctime=[Dec 21 08:15:54]}
[2009-12-21 08:15:54] N [trace.c:2131:trace_readdirp] trace: 66: (fd=0x2aaaac000b40, size=4096, offset=2147483647)
[2009-12-21 08:15:54] N [trace.c:326:trace_readdirp_cbk] trace: 66 :(op_ret=0, op_errno=0)