[Gluster-users] ERROR in glusterfs 2.0.0 with suse xen creating image

Thu May 14 03:39:25 UTC 2009

 Hello:
      Are there someone  who had met some problems  in using  GFS with  suse xen creating image ?
      Who can help me ???
    The debug log on GFSclient :
     
2009-05-14 11:31:01 D [fuse-bridge.c:461:fuse_lookup] glusterfs-fuse: 131372: LOOKUP /windows/disk0(93847579)
2009-05-14 11:31:01 D [fuse-bridge.c:368:fuse_entry_cbk] glusterfs-fuse: 131372: LOOKUP() /windows/disk0 => 93847579 (93847579)
2009-05-14 11:31:01 D [inode.c:112:__dentry_unhash] fuse/inode: dentry unhashed disk0 (93847579)
2009-05-14 11:31:01 D [inode.c:94:__dentry_hash] fuse/inode: dentry hashed disk0 (93847579)
2009-05-14 11:31:01 D [fuse-bridge.c:1515:fuse_open] glusterfs-fuse: 131373: OPEN /windows/disk0
2009-05-14 11:31:01 D [fuse-bridge.c:652:fuse_fd_cbk] glusterfs-fuse: 131373: OPEN() /windows/disk0 => 0x2aaaaaf00a90
2009-05-14 11:31:01 D [fuse-bridge.c:1669:fuse_flush] glusterfs-fuse: 131374: FLUSH 0x2aaaaaf00a90
2009-05-14 11:31:01 D [fuse-bridge.c:896:fuse_err_cbk] glusterfs-fuse: 131374: FLUSH() ERR => 0
2009-05-14 11:31:01 D [fuse-bridge.c:1689:fuse_release] glusterfs-fuse: 131375: RELEASE 0x2aaaaaf00a90
2009-05-14 11:31:01 D [fuse-bridge.c:1669:fuse_flush] glusterfs-fuse: 131376: FLUSH 0x2aaaaaf009e0
2009-05-14 11:31:01 D [fuse-bridge.c:896:fuse_err_cbk] glusterfs-fuse: 131376: FLUSH() ERR => 0
2009-05-14 11:31:01 D [fuse-bridge.c:1689:fuse_release] glusterfs-fuse: 131378: RELEASE 0x2aaaaaf009e0
2009-05-14 11:31:01 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(93847579) lru=3/0 active=4 purge=0
2009-05-14 11:31:01 D [fuse-bridge.c:1669:fuse_flush] glusterfs-fuse: 131379: FLUSH 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:896:fuse_err_cbk] glusterfs-fuse: 131379: FLUSH() ERR => 0
2009-05-14 11:31:01 D [fuse-bridge.c:1689:fuse_release] glusterfs-fuse: 131381: RELEASE 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(93847567) lru=4/0 active=3 purge=0
2009-05-14 11:31:01 D [inode.c:309:__inode_activate] fuse/inode: activating inode(95682582), lru=3/0 active=4 purge=0
2009-05-14 11:31:01 D [fuse-bridge.c:461:fuse_lookup] glusterfs-fuse: 131382: LOOKUP /.disk-list(95682582)
2009-05-14 11:31:01 D [fuse-bridge.c:368:fuse_entry_cbk] glusterfs-fuse: 131382: LOOKUP() /.disk-list => 95682582 (95682582)
2009-05-14 11:31:01 D [inode.c:112:__dentry_unhash] fuse/inode: dentry unhashed .disk-list (95682582)
2009-05-14 11:31:01 D [inode.c:94:__dentry_hash] fuse/inode: dentry hashed .disk-list (95682582)
2009-05-14 11:31:01 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(95682582) lru=4/0 active=3 purge=0
2009-05-14 11:31:01 D [inode.c:309:__inode_activate] fuse/inode: activating inode(95682582), lru=3/0 active=4 purge=0
2009-05-14 11:31:01 D [fuse-bridge.c:1515:fuse_open] glusterfs-fuse: 131383: OPEN /.disk-list
2009-05-14 11:31:01 D [fuse-bridge.c:652:fuse_fd_cbk] glusterfs-fuse: 131383: OPEN() /.disk-list => 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:1577:fuse_readv] glusterfs-fuse: 131384: READ (0x2aaaaaf00ba0, size=4096, offset=0)
2009-05-14 11:31:01 D [fuse-bridge.c:1542:fuse_readv_cbk] glusterfs-fuse: 131384: READ => 47/4096,0/47
2009-05-14 11:31:01 D [fuse-bridge.c:1669:fuse_flush] glusterfs-fuse: 131385: FLUSH 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:896:fuse_err_cbk] glusterfs-fuse: 131385: FLUSH() ERR => 0
2009-05-14 11:31:01 D [fuse-bridge.c:1689:fuse_release] glusterfs-fuse: 131386: RELEASE 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(95682582) lru=4/0 active=3 purge=0
2009-05-14 11:31:01 D [inode.c:309:__inode_activate] fuse/inode: activating inode(95682582), lru=3/0 active=4 purge=0
2009-05-14 11:31:01 D [fuse-bridge.c:813:do_truncate] glusterfs-fuse: 131387: TRUNCATE /.disk-list/0(95682582)
2009-05-14 11:31:01 D [fuse-bridge.c:523:fuse_attr_cbk] glusterfs-fuse: 131387: TRUNCATE() /.disk-list => 95682582
2009-05-14 11:31:01 D [fuse-bridge.c:1515:fuse_open] glusterfs-fuse: 131388: OPEN /.disk-list
2009-05-14 11:31:01 D [fuse-bridge.c:652:fuse_fd_cbk] glusterfs-fuse: 131388: OPEN() /.disk-list => 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:1642:fuse_write] glusterfs-fuse: 131389: WRITE (0x2aaaaaf00ba0, size=47, offset=0)
2009-05-14 11:31:01 D [fuse-bridge.c:1600:fuse_writev_cbk] glusterfs-fuse: 131389: WRITE => 47/47,0/47
2009-05-14 11:31:01 D [fuse-bridge.c:1669:fuse_flush] glusterfs-fuse: 131390: FLUSH 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:896:fuse_err_cbk] glusterfs-fuse: 131390: FLUSH() ERR => 0
2009-05-14 11:31:01 D [fuse-bridge.c:1689:fuse_release] glusterfs-fuse: 131391: RELEASE 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(95682582) lru=4/0 active=3 purge=0
2009-05-14 11:31:01 D [inode.c:309:__inode_activate] fuse/inode: activating inode(95682582), lru=3/0 active=4 purge=0
2009-05-14 11:31:01 D [fuse-bridge.c:813:do_truncate] glusterfs-fuse: 131392: TRUNCATE /.disk-list/0(95682582)
2009-05-14 11:31:01 D [fuse-bridge.c:523:fuse_attr_cbk] glusterfs-fuse: 131392: TRUNCATE() /.disk-list => 95682582
2009-05-14 11:31:01 D [fuse-bridge.c:1515:fuse_open] glusterfs-fuse: 131393: OPEN /.disk-list
2009-05-14 11:31:01 D [fuse-bridge.c:652:fuse_fd_cbk] glusterfs-fuse: 131393: OPEN() /.disk-list => 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:1642:fuse_write] glusterfs-fuse: 131394: WRITE (0x2aaaaaf00ba0, size=47, offset=0)
2009-05-14 11:31:01 D [fuse-bridge.c:1600:fuse_writev_cbk] glusterfs-fuse: 131394: WRITE => 47/47,0/47
2009-05-14 11:31:01 D [fuse-bridge.c:1669:fuse_flush] glusterfs-fuse: 131395: FLUSH 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [fuse-bridge.c:896:fuse_err_cbk] glusterfs-fuse: 131395: FLUSH() ERR => 0
2009-05-14 11:31:01 D [fuse-bridge.c:1689:fuse_release] glusterfs-fuse: 131396: RELEASE 0x2aaaaaf00ba0
2009-05-14 11:31:01 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(95682582) lru=4/0 active=3 purge=0
2009-05-14 11:31:01 D [inode.c:309:__inode_activate] fuse/inode: activating inode(93847579), lru=3/0 active=4 purge=0
2009-05-14 11:31:01 D [fuse-bridge.c:1165:fuse_unlink] glusterfs-fuse: 131397: UNLINK /windows/disk0
2009-05-14 11:31:13 E [client-protocol.c:434:client_ping_timer_expired] client2: ping timer expired! bailing transport
2009-05-14 11:31:13 D [socket.c:175:__socket_disconnect] client2: shutdown() returned 0. set connection state to -1
2009-05-14 11:31:13 D [socket.c:175:__socket_disconnect] client2: shutdown() returned 0. set connection state to -1
2009-05-14 11:31:13 D [socket.c:90:__socket_rwv] client2: EOF from peer 192.168.69.3:6996
2009-05-14 11:31:13 D [socket.c:562:__socket_proto_state_machine] client2: read (Transport endpoint is not connected) in state 1 (192.168.69.3:6996)
2009-05-14 11:31:13 D [client-protocol.c:6465:protocol_client_cleanup] client2: cleaning up state in transport object 0x513830
2009-05-14 11:31:13 E [saved-frames.c:165:saved_frames_unwind] client2: forced unwinding frame type(1) op(UNLINK)
2009-05-14 11:31:13 E [saved-frames.c:165:saved_frames_unwind] client2: forced unwinding frame type(2) op((null))
2009-05-14 11:31:13 E [client-protocol.c:536:client_ping_cbk] client2: timer must have expired
2009-05-14 11:31:13 D [client-protocol.c:6404:client_protocol_reconnect] client2: attempting reconnect
2009-05-14 11:31:13 D [common-utils.c:85:gf_resolve_ip6] resolver: flushing DNS cache
2009-05-14 11:31:13 D [common-utils.c:92:gf_resolve_ip6] resolver: DNS cache not present, freshly probing hostname: 192.168.69.3
2009-05-14 11:31:13 D [common-utils.c:129:gf_resolve_ip6] resolver: returning ip-192.168.69.3 (port-6996) for hostname: 192.168.69.3 and port: 6996
2009-05-14 11:31:13 D [socket.c:90:__socket_rwv] client2: EOF from peer 192.168.69.3:6996
2009-05-14 11:31:13 D [socket.c:562:__socket_proto_state_machine] client2: read (Transport endpoint is not connected) in state 1 (192.168.69.3:6996)
2009-05-14 11:31:13 D [client-protocol.c:6465:protocol_client_cleanup] client2: cleaning up state in transport object 0x513da0
2009-05-14 11:31:13 D [client-protocol.c:6404:client_protocol_reconnect] client2: attempting reconnect
2009-05-14 11:31:13 D [common-utils.c:85:gf_resolve_ip6] resolver: flushing DNS cache
2009-05-14 11:31:13 D [common-utils.c:92:gf_resolve_ip6] resolver: DNS cache not present, freshly probing hostname: 192.168.69.3
2009-05-14 11:31:13 D [common-utils.c:129:gf_resolve_ip6] resolver: returning ip-192.168.69.3 (port-6996) for hostname: 192.168.69.3 and port: 6996
2009-05-14 11:31:13 N [client-protocol.c:7014:notify] client2: disconnected
2009-05-14 11:31:13 N [afr.c:2142:notify] afr2: subvolume client2 went down
2009-05-14 11:31:13 D [client-protocol.c:7062:notify] client2: got GF_EVENT_CHILD_UP
2009-05-14 11:31:13 D [socket.c:965:socket_connect] client2: connect () called on transport already connected
2009-05-14 11:31:13 D [client-protocol.c:7062:notify] client2: got GF_EVENT_CHILD_UP
2009-05-14 11:31:13 D [socket.c:965:socket_connect] client2: connect () called on transport already connected
2009-05-14 11:31:18 D [inode.c:112:__dentry_unhash] fuse/inode: dentry unhashed disk0 (93847579)
2009-05-14 11:31:18 D [inode.c:125:__dentry_unset] fuse/inode: unset dentry disk0 (93847579)
2009-05-14 11:31:18 D [fuse-bridge.c:955:fuse_unlink_cbk] glusterfs-fuse: 131397: UNLINK() /windows/disk0 => 0
2009-05-14 11:31:18 D [socket.c:965:socket_connect] client2: connect () called on transport already connected
2009-05-14 11:31:18 D [fuse-bridge.c:489:fuse_forget] glusterfs-fuse: got forget on inode (93847579)
2009-05-14 11:31:18 D [socket.c:965:socket_connect] client2: connect () called on transport already connected
2009-05-14 11:31:18 D [inode.c:352:__inode_retire] fuse/inode: retiring inode(93847579) lru=3/0 active=3 purge=1
2009-05-14 11:31:18 D [socket.c:965:socket_connect] client2: connect () called on transport already connected
2009-05-14 11:31:18 D [inode.c:292:__inode_destroy] fuse/inode: destroy inode(93847579) [@0x2aaaaae020b0]
2009-05-14 11:31:18 D [inode.c:328:__inode_passivate] fuse/inode: passivating inode(95682574) lru=4/0 active=2 purge=0
2009-05-14 11:31:18 N [client-protocol.c:6327:client_setvolume_cbk] client2: connection and handshake succeeded
2009-05-14 11:31:18 N [afr.c:2120:notify] afr2: subvolume client2 came up
2009-05-14 11:31:18 N [client-protocol.c:6327:client_setvolume_cbk] client2: connection and handshake succeeded
2009-05-14 11:31:18 N [afr.c:2120:notify] afr2: subvolume client2 came up
2009-05-14 11:31:23 D [client-protocol.c:6413:client_protocol_reconnect] client2: breaking reconnect chain
2009-05-14 11:31:24 D [client-protocol.c:6413:client_protocol_reconnect] client2: breaking reconnect chain

The debug log on server :
2009-05-14 11:30:27 D [common.c:514:pl_setlk] brick1: Lock (pid=-1427108160) 0 - 0 => OK
2009-05-14 11:30:27 D [server-protocol.c:4374:server_flush] brick1: 393329: FLUSH 'fd=1 (22003715)'
2009-05-14 11:30:27 D [server-protocol.c:6171:server_finodelk] brick1: 393330: FINODELK 'fd=1 (22003715)'
2009-05-14 11:30:27 D [common.c:514:pl_setlk] brick1: Unlock (pid=-1427108160) 0 - 0 => OK
2009-05-14 11:30:27 D [inode.c:328:__inode_passivate] brick1/inode: passivating inode(22003715) lru=3/1024 active=3 purge=0
2009-05-14 11:30:27 D [server-protocol.c:4284:server_release] brick1: 393331: RELEASE 'fd=1'
2009-05-14 11:30:28 D [server-protocol.c:6200:server_entrylk_resume] brick1: 154: ENTRYLK '/windows (22003717) '
2009-05-14 11:30:28 D [server-protocol.c:4931:server_xattrop_resume] brick1: 393332: XATTROP '/windows (22003717)'
2009-05-14 11:30:28 D [inode.c:309:__inode_activate] brick1/inode: activating inode(22003718), lru=2/1024 active=4 purge=0
2009-05-14 11:30:28 D [server-protocol.c:4595:server_unlink_resume] brick1: 393333: UNLINK '22003717//windows/disk0 (22003718)'
2009-05-14 11:30:44 D [server-protocol.c:1563:server_unlink_cbk] brick1: 393333: UNLINK_CBK 22003717/disk0 (22003718)
2009-05-14 11:30:44 D [inode.c:112:__dentry_unhash] brick1/inode: dentry unhashed disk0 (22003718)
2009-05-14 11:30:44 D [inode.c:125:__dentry_unset] brick1/inode: unset dentry disk0 (22003718)
2009-05-14 11:30:44 D [inode.c:328:__inode_passivate] brick1/inode: passivating inode(22003718) lru=3/1024 active=3 purge=0
2009-05-14 11:30:44 D [socket.c:90:__socket_rwv] server: EOF from peer 192.168.69.6:1019
2009-05-14 11:30:44 D [socket.c:562:__socket_proto_state_machine] server: read (Transport endpoint is not connected) in state 1 (192.168.69.6:1019)
2009-05-14 11:30:44 N [server-protocol.c:8272:notify] server: 192.168.69.6:1019 disconnected
2009-05-14 11:30:44 D [internal.c:721:pl_entrylk] brick1: releasing locks for transport 0x9d63a10
2009-05-14 11:30:44 D [inode.c:328:__inode_passivate] brick1/inode: passivating inode(22003717) lru=4/1024 active=2 purge=0
2009-05-14 11:30:44 D [socket.c:1332:fini] server: transport 0x9d2bcb0 destroyed
2009-05-14 11:30:44 E [socket.c:102:__socket_rwv] server: writev failed (Broken pipe)
2009-05-14 11:30:44 D [addr.c:174:gf_auth] brick1: allowed = "*", received addr = "192.168.69.6"
2009-05-14 11:30:44 N [server-protocol.c:7502:mop_setvolume] server: accepted client from 192.168.69.6:1015
2009-05-14 11:30:44 D [socket.c:90:__socket_rwv] server: EOF from peer 192.168.69.6:1017
2009-05-14 11:30:44 D [socket.c:562:__socket_proto_state_machine] server: read (Transport endpoint is not connected) in state 1 (192.168.69.6:1017)
2009-05-14 11:30:44 N [server-protocol.c:8272:notify] server: 192.168.69.6:1017 disconnected
2009-05-14 11:30:44 D [socket.c:1332:fini] server: transport 0x9d2bf40 destroyed
2009-05-14 11:30:44 D [addr.c:174:gf_auth] brick1: allowed = "*", received addr = "192.168.69.6"
2009-05-14 11:30:44 N [server-protocol.c:7502:mop_setvolume] server: accepted client from 192.168.69.6:1014


2009-05-14 


eagleeyes 
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://supercolony.gluster.org/pipermail/gluster-users/attachments/20090514/4f0de31d/attachment.html>