as an addendum, the SCM is on a DSL link, so the latency might be relatively

high.  Here's the output from the SCM's SrvLog with -d 10:


0x101a43f0 : Cop Pending Manager
17:03:41 LockQueue Manager woken up
17:03:41 LockQueue Manager sleeping for 60 seconds
17:04:06 Worker 5 received request 3 on cid 994717022 for tommy at 10.0.0.4
17:04:06 ViceFetch: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:06 ValidateParms: 7f000000 --> 64000001
17:04:06 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:06 Entering VGetVolume for volume 64000001
17:04:06 Entering AvailVolumeHeader()
17:04:06 Entering GetVolumeHeader()
17:04:06 CheckWriteBack: Nobody has permit on 64000001
17:04:06 Entering VPutVolume for volume 64000001
17:04:06 Entering VGetVolume for volume 64000001
17:04:06 Entering AvailVolumeHeader()
17:04:06 Entering GetVolumeHeader()
17:04:06 GetVolObj: returns 0
17:04:06 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:06 Entering VBumpVolumeUsage for volume 64000001
17:04:06 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:06 PutObjects: Vid = 64000001, errorCode = 0
17:04:06 Entering VPutVnode for vnode 1
17:04:06 Entering StickOnLruChain for vnode 1
17:04:06 Entering VPutVolume for volume 64000001
17:04:06 Returning from PutVolObj
17:04:06 PutObjects: returning Success
17:04:06 ViceFetch returns Success
17:04:07 Worker 0 received request 38 on cid 994717022 for tommy at 10.0.0.4

17:04:07 ViceValidateAttrs: Fid = (0x7f000000.0x1.0x1), 0 piggy fids
17:04:07 ViceGetAttr: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:07 ValidateParms: 7f000000 --> 64000001
17:04:07 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:07 Entering VGetVolume for volume 64000001
17:04:07 Entering AvailVolumeHeader()
17:04:07 Entering GetVolumeHeader()
17:04:07 CheckWriteBack: Nobody has permit on 64000001
17:04:07 Entering VPutVolume for volume 64000001
17:04:07 Entering VGetVolume for volume 64000001
17:04:07 Entering AvailVolumeHeader()
17:04:07 Entering GetVolumeHeader()
17:04:07 GetVolObj: returns 0
17:04:07 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:07 Entering VBumpVolumeUsage for volume 64000001
17:04:07 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:07 PutObjects: Vid = 64000001, errorCode = 0
17:04:07 Entering VPutVnode for vnode 1
17:04:07 Entering StickOnLruChain for vnode 1
17:04:07 Entering VPutVolume for volume 64000001
17:04:07 Returning from PutVolObj
17:04:07 PutObjects: returning Success
17:04:07 ViceGetAttr returns Success
17:04:07 PutObjects: Vid = 0, errorCode = 0
17:04:07 PutObjects: returning Success
17:04:07 ViceValidateAttrs returns Success, 0 piggy fids checked
17:04:07 Worker 1 received request 3 on cid 994717022 for tommy at 10.0.0.4
17:04:07 ViceFetch: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:07 ValidateParms: 7f000000 --> 64000001
17:04:07 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:07 Entering VGetVolume for volume 64000001
17:04:07 Entering AvailVolumeHeader()
17:04:07 Entering GetVolumeHeader()
17:04:07 CheckWriteBack: Nobody has permit on 64000001
17:04:07 Entering VPutVolume for volume 64000001
17:04:07 Entering VGetVolume for volume 64000001
17:04:07 Entering AvailVolumeHeader()
17:04:07 Entering GetVolumeHeader()
17:04:07 GetVolObj: returns 0
17:04:07 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:07 Entering VBumpVolumeUsage for volume 64000001
17:04:07 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:07 PutObjects: Vid = 64000001, errorCode = 0
17:04:07 Entering VPutVnode for vnode 1
17:04:07 Entering StickOnLruChain for vnode 1
17:04:07 Entering VPutVolume for volume 64000001
17:04:07 Returning from PutVolObj
17:04:07 PutObjects: returning Success
17:04:07 ViceFetch returns Success
17:04:07 Worker 2 received request 38 on cid 994717022 for tommy at 10.0.0.4

17:04:07 ViceValidateAttrs: Fid = (0x7f000000.0x1.0x1), 0 piggy fids
17:04:07 ViceGetAttr: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:07 ValidateParms: 7f000000 --> 64000001
17:04:07 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:07 Entering VGetVolume for volume 64000001
17:04:07 Entering AvailVolumeHeader()
17:04:07 Entering GetVolumeHeader()
17:04:07 CheckWriteBack: Nobody has permit on 64000001
17:04:07 Entering VPutVolume for volume 64000001
17:04:07 Entering VGetVolume for volume 64000001
17:04:07 Entering AvailVolumeHeader()
17:04:07 Entering GetVolumeHeader()
17:04:07 GetVolObj: returns 0
17:04:07 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:07 Entering VBumpVolumeUsage for volume 64000001
17:04:07 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:07 PutObjects: Vid = 64000001, errorCode = 0
17:04:07 Entering VPutVnode for vnode 1
17:04:07 Entering StickOnLruChain for vnode 1
17:04:07 Entering VPutVolume for volume 64000001
17:04:07 Returning from PutVolObj
17:04:07 PutObjects: returning Success
17:04:07 ViceGetAttr returns Success
17:04:07 PutObjects: Vid = 0, errorCode = 0
17:04:07 PutObjects: returning Success
17:04:07 ViceValidateAttrs returns Success, 0 piggy fids checked
17:04:08 Worker 3 received request 3 on cid 994717022 for tommy at 10.0.0.4
17:04:08 ViceFetch: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:08 ValidateParms: 7f000000 --> 64000001
17:04:08 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:08 Entering VGetVolume for volume 64000001
17:04:08 Entering AvailVolumeHeader()
17:04:08 Entering GetVolumeHeader()
17:04:08 CheckWriteBack: Nobody has permit on 64000001
17:04:08 Entering VPutVolume for volume 64000001
17:04:08 Entering VGetVolume for volume 64000001
17:04:08 Entering AvailVolumeHeader()
17:04:08 Entering GetVolumeHeader()
17:04:08 GetVolObj: returns 0
17:04:08 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:08 Entering VBumpVolumeUsage for volume 64000001
17:04:08 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:08 PutObjects: Vid = 64000001, errorCode = 0
17:04:08 Entering VPutVnode for vnode 1
17:04:08 Entering StickOnLruChain for vnode 1
17:04:08 Entering VPutVolume for volume 64000001
17:04:08 Returning from PutVolObj
17:04:08 PutObjects: returning Success
17:04:08 ViceFetch returns Success
17:04:09 Worker 4 received request 38 on cid 994717022 for tommy at 10.0.0.4

17:04:09 ViceValidateAttrs: Fid = (0x7f000000.0x1.0x1), 0 piggy fids
17:04:09 ViceGetAttr: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:09 ValidateParms: 7f000000 --> 64000001
17:04:09 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:09 Entering VGetVolume for volume 64000001
17:04:09 Entering AvailVolumeHeader()
17:04:09 Entering GetVolumeHeader()
17:04:09 CheckWriteBack: Nobody has permit on 64000001
17:04:09 Entering VPutVolume for volume 64000001
17:04:09 Entering VGetVolume for volume 64000001
17:04:09 Entering AvailVolumeHeader()
17:04:09 Entering GetVolumeHeader()
17:04:09 GetVolObj: returns 0
17:04:09 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:09 Entering VBumpVolumeUsage for volume 64000001
17:04:09 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:09 PutObjects: Vid = 64000001, errorCode = 0
17:04:09 Entering VPutVnode for vnode 1
17:04:09 Entering StickOnLruChain for vnode 1
17:04:09 Entering VPutVolume for volume 64000001
17:04:09 Returning from PutVolObj
17:04:09 PutObjects: returning Success
17:04:09 ViceGetAttr returns Success
17:04:09 PutObjects: Vid = 0, errorCode = 0
17:04:09 PutObjects: returning Success
17:04:09 ViceValidateAttrs returns Success, 0 piggy fids checked
17:04:09 Worker 5 received request 3 on cid 994717022 for tommy at 10.0.0.4
17:04:09 ViceFetch: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:09 ValidateParms: 7f000000 --> 64000001
17:04:09 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:09 Entering VGetVolume for volume 64000001
17:04:09 Entering AvailVolumeHeader()
17:04:09 Entering GetVolumeHeader()
17:04:09 CheckWriteBack: Nobody has permit on 64000001
17:04:09 Entering VPutVolume for volume 64000001
17:04:09 Entering VGetVolume for volume 64000001
17:04:09 Entering AvailVolumeHeader()
17:04:09 Entering GetVolumeHeader()
17:04:09 GetVolObj: returns 0
17:04:09 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:09 Entering VBumpVolumeUsage for volume 64000001
17:04:09 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:09 PutObjects: Vid = 64000001, errorCode = 0
17:04:09 Entering VPutVnode for vnode 1
17:04:09 Entering StickOnLruChain for vnode 1
17:04:09 Entering VPutVolume for volume 64000001
17:04:09 Returning from PutVolObj
17:04:09 PutObjects: returning Success
17:04:09 ViceFetch returns Success
17:04:11 Worker 0 received request 38 on cid 994717022 for tommy at 10.0.0.4

17:04:11 ViceValidateAttrs: Fid = (0x7f000000.0x1.0x1), 0 piggy fids
17:04:11 ViceGetAttr: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:11 ValidateParms: 7f000000 --> 64000001
17:04:11 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:11 Entering VGetVolume for volume 64000001
17:04:11 Entering AvailVolumeHeader()
17:04:11 Entering GetVolumeHeader()
17:04:11 CheckWriteBack: Nobody has permit on 64000001
17:04:11 Entering VPutVolume for volume 64000001
17:04:11 Entering VGetVolume for volume 64000001
17:04:11 Entering AvailVolumeHeader()
17:04:11 Entering GetVolumeHeader()
17:04:11 GetVolObj: returns 0
17:04:11 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:11 Entering VBumpVolumeUsage for volume 64000001
17:04:11 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:11 PutObjects: Vid = 64000001, errorCode = 0
17:04:11 Entering VPutVnode for vnode 1
17:04:11 Entering StickOnLruChain for vnode 1
17:04:11 Entering VPutVolume for volume 64000001
17:04:11 Returning from PutVolObj
17:04:11 PutObjects: returning Success
17:04:11 ViceGetAttr returns Success
17:04:11 PutObjects: Vid = 0, errorCode = 0
17:04:11 PutObjects: returning Success
17:04:11 ViceValidateAttrs returns Success, 0 piggy fids checked
17:04:11 Worker 1 received request 3 on cid 994717022 for tommy at 10.0.0.4
17:04:11 ViceFetch: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:11 ValidateParms: 7f000000 --> 64000001
17:04:11 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:11 Entering VGetVolume for volume 64000001
17:04:11 Entering AvailVolumeHeader()
17:04:11 Entering GetVolumeHeader()
17:04:11 CheckWriteBack: Nobody has permit on 64000001
17:04:11 Entering VPutVolume for volume 64000001
17:04:11 Entering VGetVolume for volume 64000001
17:04:11 Entering AvailVolumeHeader()
17:04:11 Entering GetVolumeHeader()
17:04:11 GetVolObj: returns 0
17:04:11 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:11 Entering VBumpVolumeUsage for volume 64000001
17:04:11 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:11 PutObjects: Vid = 64000001, errorCode = 0
17:04:11 Entering VPutVnode for vnode 1
17:04:11 Entering StickOnLruChain for vnode 1
17:04:11 Entering VPutVolume for volume 64000001
17:04:11 Returning from PutVolObj
17:04:11 PutObjects: returning Success
17:04:11 ViceFetch returns Success
17:04:15 Worker 2 received request 38 on cid 994717022 for tommy at 10.0.0.4

17:04:15 ViceValidateAttrs: Fid = (0x7f000000.0x1.0x1), 0 piggy fids
17:04:15 ViceGetAttr: Fid = (0x7f000000.0x1.0x1), Repair = 0
17:04:15 ValidateParms: 7f000000 --> 64000001
17:04:15 CheckWriteBack: Translated vol 64000001 to VSG 7f000000
17:04:15 Entering VGetVolume for volume 64000001
17:04:15 Entering AvailVolumeHeader()
17:04:15 Entering GetVolumeHeader()
17:04:15 CheckWriteBack: Nobody has permit on 64000001
17:04:15 Entering VPutVolume for volume 64000001
17:04:15 Entering VGetVolume for volume 64000001
17:04:15 Entering AvailVolumeHeader()
17:04:15 Entering GetVolumeHeader()
17:04:15 GetVolObj: returns 0
17:04:15 Entering VGetVnode(vol 64000001, vnode 1, lock 1, ignoreIncon 0)
17:04:15 Entering VBumpVolumeUsage for volume 64000001
17:04:15 AddCallBack for Fid 0x7f000000.1.1, Venus 10.0.0.4.2430
17:04:15 PutObjects: Vid = 64000001, errorCode = 0
17:04:15 Entering VPutVnode for vnode 1
17:04:15 Entering StickOnLruChain for vnode 1
17:04:15 Entering VPutVolume for volume 64000001
17:04:15 Returning from PutVolObj
17:04:15 PutObjects: returning Success
17:04:15 ViceFetch returns Success


Tom Tarka wrote:

> Since I couldn't get venus to connect to the SCM server running on my
> MkLinux machine,
> I thought I'd try to set up a replicated server on an x86 Red Hat machine
> and connect to
> the replicated server with venus.
>
> I suceeded in getting venus to connect to the replicated server, but now
> I get:
>
> (tommy_at_sodom) ~ % clog tommy
> username: tommy
> Password:
> (tommy_at_sodom) ~ % ls /coda/
> ls: /coda/: Resource temporarily unavailable
> (tommy_at_sodom) ~ % ls /coda/
>
> and coda/etc/console:
>
> 16:38:46 MaxRetries exceeded...returning EWOULDBLOCK
>
> and on the replicated server:
>
> ==> /vice/srv/SrvLog <==
> 0x81f7d48 : Cop Pending Manager
> 16:37:36 LockQueue Manager woken up
> 16:37:36 LockQueue Manager sleeping for 60 seconds
> 16:38:21 Worker 0 received request -13 on cid 876595215 for NA at NA
> 16:38:21 client_GetVenusId: got new host 10.0.0.4:2430
> 16:38:21 in AL_NameToId(System:AnyUser)
> 16:38:21 in AL_GetInternalCPS(-101, 0x81fc784)
> 16:38:21 New connection received RPCid 876595215, security lvl 98, rem id
> 352841
> 224
> 16:38:21 Worker 1 received request 40 on cid 876595215 for root at
> 10.0.0.4
> 16:38:21 FS_ViceNewConnectFS (version 3) for user root at
> sodom.slackers.net.ven
> us
> 16:38:21 Building callback conn.
> 16:38:21 No idle WriteBack conns, building new one
> 16:38:21 Writeback message to 10.0.0.4 port 2430 on conn 33f1ceda
> succeeded
> 16:38:21 FS_ViceNewConnectFS returns Success
> 16:38:21 Worker 2 received request 24 on cid 876595215 for root at
> 10.0.0.4
> 16:38:21 ViceGetVolumeInfo volume = coda.root
> 16:38:21 ViceGetVolumeInfo returns Success, Volume 2130706432, type 3,
> servers c0a82147 0 0 0...
> 0x81f7d48 : Cop Pending Manager
> 16:38:36 LockQueue Manager woken up
> 16:38:36 LockQueue Manager sleeping for 60 seconds
>
> Ideas?
>
>     -t