Hi All. I am having problems with coda. Please forgive me for the
length of this e-mail. These are the logs of what I am doing, debug
level set to 1000.

The problem is that I have two servers that I want to "share" one
filesystem. If one server goes down, the other can continue using the
same data, and when the other one comes up, they both sync their data.

My problem is when I run cfs mkmount, I can view the volume from the
SCM, but not from the other server. Yet when I add a file to the root
volume, on either server, I can see and access it from the other. Both
servers are clog'd properly.

Logs on the SCM (Note that about half-way down, I swap logs,
commented):

bash-2.05# createvol_rep u.hills E0000104 /vicepa
Servers are (tweedledee tweedledum )
HexGroupId is 7f000004
creating volume u.hills.0 on tweedledee (partition /vicepa)
V_BindToServer: binding to host tweedledee.hillsong.com
creating volume u.hills.1 on tweedledum (partition /vicepa)
V_BindToServer: binding to host tweedledum.hillsong.com
Fetching volume lists from servers:
V_BindToServer: binding to host tweedledum.hillsong.com
GetVolumeList finished successfully
 tweedledum - success
V_BindToServer: binding to host tweedledee.hillsong.com
GetVolumeList finished successfully
 tweedledee - success
V_BindToServer: binding to host tweedledee.hillsong.com
VLDB completed.
<echo u.hills 7f000004        2 1000005 2000004 0 0 0 0 0 0 E0000104 >> /vice/db/VRList>
V_BindToServer: binding to host tweedledee.hillsong.com
VRDB completed.
Do you wish this volume to be Backed Up (y/n)? [n] n


And the logs:


[SrvLog] PrintVolumesInHashTable: Lookint at index 125
[SrvLog] PrintVolumesInHashTable: Lookint at index 126
[SrvLog] PrintVolumesInHashTable: Lookint at index 127
[SrvLog] 16:55:19 Entering VDisconnectFS
[SrvLog] 16:55:19 Entering FSYNC_clientFinis()
[SrvLog] 16:55:19 purge: volume 1000004 (u.hills.0) purged
[SrvLog] New Connection 0x816bc00......
[SrvLog] 16:55:19 VolUtilWorker 0 received request -13
[SrvLog] 16:55:19 VolUtilWorker 1 received request 5
[SrvLog] 16:55:19 Entering S_VolMakeVRDB; infile /vice/db/VRList
[SrvLog] 16:55:19 vrent:: canonicalize volumeids are : 0x1000001 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
[SrvLog] 
[SrvLog] 16:55:19 vrent:: canonicalize volumeids are : 0x2000001 0x1000002 0x0 0x0 0x0 0x0 0x0 0x0 
[SrvLog] 
[SrvLog] 16:55:19 VRDB created, 2 entries
[SrvLog] New Connection 0x816bd00......
[SrvLog] 16:55:20 VolUtilWorker 0 received request -13
[SrvLog] 16:55:20 VolUtilWorker 1 received request 41
[SrvLog] 16:55:20 Entering S_GetVolumeList
[SrvLog] 
[SrvLog] 16:55:20 Entering VListVolumes()
[SrvLog] 16:55:20 Entering VGetVolume for volume 1000001
[SrvLog] 16:55:20 VGetVolume: nUsers == 0
[SrvLog] 16:55:20 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:55:20 Entering AvailVolumeHeader()
[SrvLog] 16:55:20 AvailVolumeHeader returns 1
[SrvLog] 16:55:20 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:55:20 Entering GetVolumeHeader()
[SrvLog] 16:55:20 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:55:20 Entering VListVolume for volume 1000001
[SrvLog] 16:55:20 Entering VAdjustVolumeStatistics for volume 1000001
[SrvLog] 16:55:20 Entering VPutVolume for volume 1000001
[SrvLog] 16:55:20 Entering ReleaseVolumeHeader
[SrvLog] 16:55:20 Entering VGetVolume for volume 1000002
[SrvLog] 16:55:20 VGetVolume: nUsers == 0
[SrvLog] 16:55:20 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:55:20 Entering AvailVolumeHeader()
[SrvLog] 16:55:20 AvailVolumeHeader returns 1
[SrvLog] 16:55:20 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:55:20 Entering GetVolumeHeader()
[SrvLog] 16:55:20 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:55:20 Entering VListVolume for volume 1000002
[SrvLog] 16:55:20 Entering VAdjustVolumeStatistics for volume 1000002
[SrvLog] 16:55:20 Entering VPutVolume for volume 1000002
[SrvLog] 16:55:20 Entering ReleaseVolumeHeader
[SrvLog] 16:55:20 GetVolumeList returns 0
[SrvLog] 
[SrvLog] New Connection 0x816be00......
[SrvLog] 16:55:20 VolUtilWorker 0 received request -13
[SrvLog] 16:55:20 VolUtilWorker 1 received request 4
[SrvLog] 16:55:20 Entering S_VolMakeVLDB; infile /vice/vol/BigVolumeList
[SrvLog] 16:55:20 Entering Pass P
[SrvLog] 16:55:20 Entering Pass W
[SrvLog] 16:55:20 Adding VLDB Entry for vol with key = 16777217
[SrvLog] 16:55:20 Add: index = 3, hashsize = 15
[SrvLog] 16:55:20 Adding VLDB Entry for vol with key = coda.root.0
[SrvLog] 16:55:20 Add: index = 4, hashsize = 15
[SrvLog] 16:55:20 Adding VLDB Entry for vol with key = 16777218
[SrvLog] 16:55:20 Add: index = 13, hashsize = 15
[SrvLog] 16:55:20 Adding VLDB Entry for vol with key = coda.hills.0
[SrvLog] 16:55:20 Add: index = 8, hashsize = 15
[SrvLog] 16:55:20 Adding VLDB Entry for vol with key = 33554433
[SrvLog] 16:55:20 Add: index = 5, hashsize = 15
[SrvLog] 16:55:20 Adding VLDB Entry for vol with key = coda.hills.1
[SrvLog] 16:55:20 Add: index = 2, hashsize = 15
[SrvLog] 16:55:20 Entering Pass R
[SrvLog] 16:55:20 Entering Pass B
[SrvLog] VLDB created.  Search lengths: RO 0, RW 0, BK 0.
[SrvLog] 16:55:20 Checking VLDB...
[SrvLog] New Connection 0x816bf00......
[SrvLog] 16:55:34 VolUtilWorker 0 received request -13
[SrvLog] 16:55:34 VolUtilWorker 1 received request 10
[SrvLog] 16:55:34 Checking VLDB...
[SrvLog] 16:55:34 New Data Base received
[SrvLog] New Connection 0x817e000......
[SrvLog] 16:55:47 VolUtilWorker 0 received request -13
[SrvLog] 16:55:47 VolUtilWorker 1 received request 2
[SrvLog] 16:55:47 Entering S_VolCreate: rpcid = 122838130, partition = /vicepa,volname = u.hills.0, volumeid = 0, repvol = 1, grpid = 7f000004
[SrvLog] 16:55:47 Entering VInitVolUtil
[SrvLog] 16:55:47 Entering VConnectFS
[SrvLog] 16:55:47 Entering FSYNC_clientInit()
[SrvLog] 16:55:47 Entering VAllocateVolumeId()
[SrvLog] 16:55:47 VolCreate: VAllocateVolumeId returns 1000005
[SrvLog] 16:55:47 Creating log for volume
[SrvLog] 
[SrvLog] 16:55:47 Entering NewVolHeader
[SrvLog] 16:55:47 NewVolHeader: found empty slot 2
[SrvLog] 16:55:47 NewVolHeader: Going to stamp new header 
[SrvLog] 16:55:47 NewVolHeader: Going to Allocate VolumeDiskData 
[SrvLog] 16:55:47 NewVolHeader: Going to allocate vnode arrays
[SrvLog] 16:55:47 NewVolHeader: Zeroing out small vnode array of size 1024
[SrvLog] 16:55:47 NewVolHeader: Zeroing out large vnode array of size 512
[SrvLog] 16:55:47 NewVolHeader: Going to write header in recoverable storage 
[SrvLog] 16:55:47 NewVolHeader: adding new entry 1000005 to slot 2
[SrvLog] Printing volume at index 2:
[SrvLog]     VolHead VolumeList[2]:
[SrvLog] 		version stamp = 88a1bb3c, 1
[SrvLog] 		id = 1000005
[SrvLog] 		 parentid = 1000005
[SrvLog] 		type = 0
[SrvLog]     VolumeData:
[SrvLog] 		VolumeDiskData *volumeInfo = 0x56fdab48
[SrvLog] 			version stamp = 0, 0
[SrvLog] 			id = 0
[SrvLog] 			partition = 
[SrvLog] 			name = 
[SrvLog] 			inUse = 0
[SrvLog] 			inService = 0
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 0
[SrvLog] 			diskused = 0
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, [SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, [SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 0
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 0
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 		smallvnodes = 0
[SrvLog] 		smallListSize = 256
[SrvLog] 		rec_smolist *smallVnodeList = 0x56fda728
[SrvLog] 
[SrvLog] 		largevnodes = 0
[SrvLog] 		largeListSize = 128
[SrvLog] 		VnodeDiskObject **largeVnodeList = 0x56fd86a8
[SrvLog] 
[SrvLog] 
[SrvLog] 16:55:47 Entering NewVolDiskInfo for index 2, volume 1000005
[SrvLog] 16:55:47 Entering ReplaceVolDiskInfo for volume index 2
[SrvLog] 16:55:47 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:55:47 ReplacevolDiskInfo: got locks!
[SrvLog] 16:55:47 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 2
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 1000005
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = 
[SrvLog] 			inUse = 0
[SrvLog] 			inService = 0
[SrvLog] 			blessed = 0
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 0
[SrvLog] 			type = 0
[SrvLog] 			parentId = 1000005
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 211
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 0
[SrvLog] 			diskused = 0
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, [SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, [SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 0
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031347
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:55:47 Entering VAttachVolumeById() for volume 1000005
[SrvLog] 16:55:47 running as volume utility
[SrvLog] 16:55:47 Entering ExtractVolHeader for volume 1000005
[SrvLog] 16:55:47 Entering VolHeaderByIndex for index 2
[SrvLog] 16:55:47 Entering attach2(); not running as fileServer
[SrvLog] 16:55:47 Entering GetVolumeHeader()
[SrvLog] 16:55:47 Entering VolDiskInfoById for volume 1000005
[SrvLog] 16:55:47 Entering ExtractVolDiskInfo for volindex 2
[SrvLog] 16:55:47 VolDiskInfoById: vol->stamp.magic = 2023862981, vol->stamp.version = 1
[SrvLog] 16:55:47 Entering AddVolumeToHashTable for volume 1000005, hashid 16777221
[SrvLog] 16:55:47 Entering VolumeHashOffset()
[SrvLog] 16:55:47 Entering GetBitmap() for volume 1000005, vclass = 0
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 0
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 0
[SrvLog] 16:55:47 GetBitmap: allocating bitmap of 16 bytes; array size 128
[SrvLog] 16:55:47 Entering GetBitmap() for volume 1000005, vclass = 1
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 1
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 1
[SrvLog] 16:55:47 GetBitmap: allocating bitmap of 32 bytes; array size 256
[SrvLog] 16:55:47 Leaving attach2()
[SrvLog] 16:55:47 returning from VAttachVolumeById()
[SrvLog] 16:55:47 in AL_NameToId(System:Administrators)
[SrvLog] 16:55:47 in AL_NameToId(System:AnyUser)
[SrvLog] 16:55:47 VN_GetDirHandle NEW Vnode 0 Uniq 0 cnt 1
[SrvLog] 
[SrvLog] 16:55:47 ViceCreateRoot: did.Vnode = 1
[SrvLog] 16:55:47 VN_DCommit: Commiting pages for dir vnode = 1
[SrvLog] 16:55:47 Entering DI_New
[SrvLog] 16:55:47 VN_PutDirHandle: Vn 1 Uniq 1: cnt 0, vn_cnt 0
[SrvLog] 
[SrvLog] 16:55:47 Creating new log for root vnode
[SrvLog] 
[SrvLog] 16:55:47 AllocRecord: returning index 0 seqno 1
[SrvLog] 
[SrvLog] 16:55:47 RecovPutRecord: Growing Log
[SrvLog] 
[SrvLog] 16:55:47 Entering recle::InitFromSle()
[SrvLog] 
[SrvLog] 16:55:47 Opcode is Mkdir
[SrvLog] 
[SrvLog] 16:55:47 Leaving InitFromSle
[SrvLog] 
[SrvLog] Vnode 1.1.1, cloned = 0, length = 2048, inode = 1459455112
[SrvLog] link count = 2, type = 2, volume index = 2
[SrvLog] {[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 16:55:47 Entering ReplaceVnode(2, 0, 0, 1159335012)
[SrvLog] 16:55:47 ReplaceVnode: no large vnode at index 0; allocating
[SrvLog] 16:55:47 ReplaceVnode: taking large vnode from freelist
[SrvLog] 16:55:47 Taking vnode off of largefreelist[125] for index 0
[SrvLog] 16:55:47 Replace vnode - VnodeDiskObject passed to rtn:
[SrvLog] 			type = 2
[SrvLog] 			cloned = 0
[SrvLog] 			mode = 777
[SrvLog] 			links = 2
[SrvLog] 			length = 2048
[SrvLog] 			unique = 1
[SrvLog] 			version = 1
[SrvLog] 			inode = 1459455112
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			volindex = 2
[SrvLog] 			modtime = 998031347
[SrvLog] 			author = 0
[SrvLog] 			owner = 0
[SrvLog] 			parent = 0.0
[SrvLog] 			magic = ad8765fe
[SrvLog] 			servermodtime = 998031347
[SrvLog] 16:55:47 Entering ExtractVnode(volindex = 2, vclass = 0, vnodeindex = 0, Unique = 1 vnode = 0x451a0070)
[SrvLog] 16:55:47 ExtractVnode: vnode->type = 2
[SrvLog] Printing Large vnode 1, (index 0) from volume 2
[SrvLog] 			type = 2
[SrvLog] 			cloned = 0
[SrvLog] 			mode = 777
[SrvLog] 			links = 2
[SrvLog] 			length = 2048
[SrvLog] 			unique = 1
[SrvLog] 			version = 1
[SrvLog] 			inode = 1459455112
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			volindex = 2
[SrvLog] 			modtime = 998031347
[SrvLog] 			author = 0
[SrvLog] 			owner = 0
[SrvLog] 			parent = 0.0
[SrvLog] 			magic = ad8765fe
[SrvLog] 			servermodtime = 998031347
[SrvLog] 16:55:47 Entering VUpdateVolume() for volume 1000005
[SrvLog] 16:55:47 Entering WriteVolumeHeader for volume 1000005
[SrvLog] 16:55:47 Entering ReplaceVolDiskInfo for volume index 2
[SrvLog] 16:55:47 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:55:47 ReplacevolDiskInfo: got locks!
[SrvLog] 16:55:47 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 2
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 1000005
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = u.hills.0
[SrvLog] 			inUse = 0
[SrvLog] 			inService = 1
[SrvLog] 			blessed = 1
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 2
[SrvLog] 			type = 0
[SrvLog] 			parentId = 1000005
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 0
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 1
[SrvLog] 			diskused = 2
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, [SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, [SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 998031347
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031347
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:55:47 Entering VDetachVolume() for volume 1000005
[SrvLog] 16:55:47 Entering DeleteVolumeFromHashTable
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 DeleteVolumeFromHashTable: Deleting volume 1000005 from hash table
[SrvLog] 16:55:47 Entering VPutVolume for volume 1000005
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 Entering FreeVolume for volume 1000005
[SrvLog] 16:55:47 Entering FreeVolumeHeader for volume 1000005
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 Entering DeleteVolumeFromHashTable
[SrvLog] 16:55:47 Entering FSYNC_askfs(1000005, 1,0)
[SrvLog] 16:55:47 Entering VAttachVolume() for volume 1000005
[SrvLog] 16:55:47 Entering GetVolPartition for volid 1000005
[SrvLog] 16:55:47 Entering VAttachVolumeById() for volume 1000005
[SrvLog] 16:55:47 running as fileserver
[SrvLog] 16:55:47 Entering VGetVolume for volume 1000005
[SrvLog] 16:55:47 VGetVolume: Didnt find id 1000005 in hashtable
[SrvLog] 16:55:47 Entering FSYNC_CheckRelocationSite(1000005)
[SrvLog] 16:55:47 Entering ExtractVolHeader for volume 1000005
[SrvLog] 16:55:47 Entering VolHeaderByIndex for index 2
[SrvLog] 16:55:47 Entering attach2();  running as fileServer
[SrvLog] 16:55:47 Entering GetVolumeHeader()
[SrvLog] 16:55:47 Entering VolDiskInfoById for volume 1000005
[SrvLog] 16:55:47 Entering ExtractVolDiskInfo for volindex 2
[SrvLog] 16:55:47 VolDiskInfoById: vol->stamp.magic = 2023862981, vol->stamp.version = 1
[SrvLog] 16:55:47 VAttachVolume: setting V_inUse(vp) = 1 for volume 1000005
[SrvLog] 16:55:47 Entering AddVolumeToHashTable for volume 1000005, hashid 16777221
[SrvLog] 16:55:47 Entering VolumeHashOffset()
[SrvLog] 16:55:47 Entering GetBitmap() for volume 1000005, vclass = 0
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 0
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 0
[SrvLog] 16:55:47 GetBitmap: allocating bitmap of 16 bytes; array size 128
[SrvLog] 16:55:47 GetBitmap: found vnode at index 0
[SrvLog] 16:55:47 results of or: *(vip->bitmap + bitNumber >> 3) = 1
[SrvLog] 16:55:47 Entering GetBitmap() for volume 1000005, vclass = 1
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 1
[SrvLog] 16:55:47 Entering ActiveVnodes for index 2, vclass = 1
[SrvLog] 16:55:47 GetBitmap: allocating bitmap of 32 bytes; array size 256
[SrvLog] 16:55:47 Leaving attach2()
[SrvLog] 16:55:47 Entering VUpdateVolume() for volume 1000005
[SrvLog] 16:55:47 Entering WriteVolumeHeader for volume 1000005
[SrvLog] 16:55:47 Entering ReplaceVolDiskInfo for volume index 2
[SrvLog] 16:55:47 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:55:47 ReplacevolDiskInfo: got locks!
[SrvLog] 16:55:47 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 2
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 1000005
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = u.hills.0
[SrvLog] 			inUse = 1
[SrvLog] 			inService = 1
[SrvLog] 			blessed = 1
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 2
[SrvLog] 			type = 0
[SrvLog] 			parentId = 1000005
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 0
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 1
[SrvLog] 			diskused = 2
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, [SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, [SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 998031347
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031347
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:55:47 Entering VAddToVolumeUpdateList for volume 1000005
[SrvLog] 16:55:47 Entering VUpdateVolume() for volume 1000005
[SrvLog] 16:55:47 Entering WriteVolumeHeader for volume 1000005
[SrvLog] 16:55:47 Entering ReplaceVolDiskInfo for volume index 2
[SrvLog] 16:55:47 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:55:47 ReplacevolDiskInfo: got locks!
[SrvLog] 16:55:47 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 2
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 1000005
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = u.hills.0
[SrvLog] 			inUse = 1
[SrvLog] 			inService = 1
[SrvLog] 			blessed = 1
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 2
[SrvLog] 			type = 0
[SrvLog] 			parentId = 1000005
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 0
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 1
[SrvLog] 			diskused = 2
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, [SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, [SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 998031347
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031347
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:55:47 VAddToVolumeUpdateList: Adding volume 1000005 to update list
[SrvLog] 16:55:47 Leaving VAddToVolumeUpdateList()
[SrvLog] 16:55:47 VAttachVolumeById: vol 1000005 (u.hills.0) attached and online[SrvLog] 16:55:47 returning from VAttachVolumeById()
[SrvLog] 16:55:47 Entering VPutVolume for volume 1000005
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 Entering VDisconnectFS
[SrvLog] 16:55:47 Entering FSYNC_clientFinis()
[SrvLog] 16:55:47 create: volume 1000005 (u.hills.0) created
[SrvLog] New Connection 0x817e100......
[SrvLog] 16:55:47 VolUtilWorker 0 received request -13
[SrvLog] 16:55:47 VolUtilWorker 1 received request 41
[SrvLog] 16:55:47 Entering S_GetVolumeList
[SrvLog] 
[SrvLog] 16:55:47 Entering VListVolumes()
[SrvLog] 16:55:47 Entering VGetVolume for volume 1000001
[SrvLog] 16:55:47 VGetVolume: nUsers == 0
[SrvLog] 16:55:47 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:55:47 Entering AvailVolumeHeader()
[SrvLog] 16:55:47 AvailVolumeHeader returns 1
[SrvLog] 16:55:47 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:55:47 Entering GetVolumeHeader()
[SrvLog] 16:55:47 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:55:47 Entering VListVolume for volume 1000001
[SrvLog] 16:55:47 Entering VAdjustVolumeStatistics for volume 1000001
[SrvLog] 16:55:47 Entering VPutVolume for volume 1000001
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 Entering VGetVolume for volume 1000002
[SrvLog] 16:55:47 VGetVolume: nUsers == 0
[SrvLog] 16:55:47 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:55:47 Entering AvailVolumeHeader()
[SrvLog] 16:55:47 AvailVolumeHeader returns 1
[SrvLog] 16:55:47 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:55:47 Entering GetVolumeHeader()
[SrvLog] 16:55:47 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:55:47 Entering VListVolume for volume 1000002
[SrvLog] 16:55:47 Entering VAdjustVolumeStatistics for volume 1000002
[SrvLog] 16:55:47 Entering VPutVolume for volume 1000002
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 Entering VGetVolume for volume 1000005
[SrvLog] 16:55:47 VGetVolume: nUsers == 0
[SrvLog] 16:55:47 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:55:47 Entering AvailVolumeHeader()
[SrvLog] 16:55:47 AvailVolumeHeader returns 1
[SrvLog] 16:55:47 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:55:47 Entering GetVolumeHeader()
[SrvLog] 16:55:47 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:55:47 Entering VListVolume for volume 1000005
[SrvLog] 16:55:47 Entering VAdjustVolumeStatistics for volume 1000005
[SrvLog] 16:55:47 Entering VPutVolume for volume 1000005
[SrvLog] 16:55:47 Entering ReleaseVolumeHeader
[SrvLog] 16:55:47 GetVolumeList returns 0
[SrvLog] 
[SrvLog] New Connection 0x817e200......
[SrvLog] 16:55:47 VolUtilWorker 0 received request -13
[SrvLog] 16:55:47 VolUtilWorker 1 received request 4
[SrvLog] 16:55:47 Entering S_VolMakeVLDB; infile /vice/vol/BigVolumeList
[SrvLog] 16:55:47 Entering Pass P
[SrvLog] 16:55:47 Entering Pass W
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = 16777217
[SrvLog] 16:55:47 Add: index = 3, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = coda.root.0
[SrvLog] 16:55:47 Add: index = 4, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = 16777218
[SrvLog] 16:55:47 Add: index = 10, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = coda.hills.0
[SrvLog] 16:55:47 Add: index = 8, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = 16777221
[SrvLog] 16:55:47 Add: index = 12, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = u.hills.0
[SrvLog] 16:55:47 Add: index = 12, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = 33554433
[SrvLog] 16:55:47 Add: index = 5, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = coda.hills.1
[SrvLog] 16:55:47 Add: index = 11, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = 33554436
[SrvLog] 16:55:47 Add: index = 1, hashsize = 21
[SrvLog] 16:55:47 Adding VLDB Entry for vol with key = u.hills.1
[SrvLog] 16:55:47 Add: index = 9, hashsize = 21
[SrvLog] 16:55:47 Entering Pass R
[SrvLog] 16:55:47 Entering Pass B
[SrvLog] VLDB created.  Search lengths: RO 0, RW 1, BK 0.
[SrvLog] 16:55:47 Checking VLDB...
[SrvLog] New Connection 0x817e300......
[SrvLog] 16:55:47 VolUtilWorker 0 received request -13
[SrvLog] 16:55:47 VolUtilWorker 1 received request 5
[SrvLog] 16:55:47 Entering S_VolMakeVRDB; infile /vice/db/VRList
[SrvLog] 16:55:47 vrent:: canonicalize volumeids are : 0x1000001 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
[SrvLog] 
[SrvLog] 16:55:47 vrent:: canonicalize volumeids are : 0x2000001 0x1000002 0x0 0x0 0x0 0x0 0x0 0x0 
[SrvLog] 
[SrvLog] 16:55:47 vrent:: canonicalize volumeids are : 0x2000004 0x1000005 0x0 0x0 0x0 0x0 0x0 0x0 
[SrvLog] 
[SrvLog] 16:55:48 VRDB created, 3 entries
[SrvLog] 0x812b540 : Cop Pending Manager


And at the same time, the logs on the non-scm:


[SrvLog] 16:52:54 Entering VDisconnectFS
[SrvLog] 16:52:54 Entering FSYNC_clientFinis()
[SrvLog] 16:52:54 purge: volume 2000003 (u.hills.1) purged
[SrvLog] New Connection 0x81ead00......
[SrvLog] 16:52:54 VolUtilWorker 0 received request -13
[SrvLog] 16:52:54 VolUtilWorker 1 received request 41
[SrvLog] 16:52:54 Entering S_GetVolumeList
[SrvLog] 
[SrvLog] 16:52:54 Entering VListVolumes()
[SrvLog] 16:52:54 Entering VGetVolume for volume 2000001
[SrvLog] 16:52:54 VGetVolume: nUsers == 0
[SrvLog] 16:52:54 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:52:54 Entering AvailVolumeHeader()
[SrvLog] 16:52:54 AvailVolumeHeader returns 1
[SrvLog] 16:52:54 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:52:54 Entering GetVolumeHeader()
[SrvLog] 16:52:54 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:52:54 Entering VListVolume for volume 2000001
[SrvLog] 16:52:54 Entering VAdjustVolumeStatistics for volume 2000001
[SrvLog] 16:52:54 Entering VPutVolume for volume 2000001
[SrvLog] 16:52:54 Entering ReleaseVolumeHeader
[SrvLog] 16:52:54 GetVolumeList returns 0
[SrvLog] 
[SrvLog] New Connection 0x81eae00......
[SrvLog] 16:53:12 VolUtilWorker 0 received request -13
[SrvLog] 16:53:12 VolUtilWorker 1 received request 10
[SrvLog] 16:53:12 Checking VLDB...
[SrvLog] 16:53:12 New Data Base received
[SrvLog] New Connection 0x81eaf00......
[SrvLog] 16:53:21 VolUtilWorker 0 received request -13
[SrvLog] 16:53:21 VolUtilWorker 1 received request 2
[SrvLog] 16:53:21 Entering S_VolCreate: rpcid = 23849617, partition = /vicepa,volname = u.hills.1, volumeid = 0, repvol = 1, grpid = 7f000004
[SrvLog] 16:53:21 Entering VInitVolUtil
[SrvLog] 16:53:21 Entering VConnectFS
[SrvLog] 16:53:21 Entering FSYNC_clientInit()
[SrvLog] 16:53:21 Entering VAllocateVolumeId()
[SrvLog] 16:53:21 VolCreate: VAllocateVolumeId returns 2000004
[SrvLog] 16:53:21 Creating log for volume
[SrvLog] 
[SrvLog] 16:53:21 Entering NewVolHeader
[SrvLog] 16:53:21 NewVolHeader: found empty slot 1
[SrvLog] 16:53:21 NewVolHeader: Going to stamp new header 
[SrvLog] 16:53:21 NewVolHeader: Going to Allocate VolumeDiskData 
[SrvLog] 16:53:21 NewVolHeader: Going to allocate vnode arrays
[SrvLog] 16:53:21 NewVolHeader: Zeroing out small vnode array of size 1024
[SrvLog] 16:53:21 NewVolHeader: Zeroing out large vnode array of size 512
[SrvLog] 16:53:21 NewVolHeader: Going to write header in recoverable storage 
[SrvLog] 16:53:21 NewVolHeader: adding new entry 2000004 to slot 1
[SrvLog] Printing volume at index 1:
[SrvLog]     VolHead VolumeList[1]:
[SrvLog] 		version stamp = 88a1bb3c, 1
[SrvLog] 		id = 2000004
[SrvLog] 		 parentid = 2000004
[SrvLog] 		type = 0
[SrvLog]     VolumeData:
[SrvLog] 		VolumeDiskData *volumeInfo = 0x56fdca48
[SrvLog] 			version stamp = 0, 0
[SrvLog] 			id = 0
[SrvLog] 			partition = 
[SrvLog] 			name = 
[SrvLog] 			inUse = 0
[SrvLog] 			inService = 0
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 0
[SrvLog] 			diskused = 0
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, 
[SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, 
[SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 0
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 0
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 		smallvnodes = 0
[SrvLog] 		smallListSize = 256
[SrvLog] 		rec_smolist *smallVnodeList = 0x56fdc628
[SrvLog] 
[SrvLog] 		largevnodes = 0
[SrvLog] 		largeListSize = 128
[SrvLog] 		VnodeDiskObject **largeVnodeList = 0x56fda568
[SrvLog] 
[SrvLog] 
[SrvLog] 16:53:21 Entering NewVolDiskInfo for index 1, volume 2000004
[SrvLog] 16:53:21 Entering ReplaceVolDiskInfo for volume index 1
[SrvLog] 16:53:21 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:53:21 ReplacevolDiskInfo: got locks!
[SrvLog] 16:53:21 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 1
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 2000004
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = 
[SrvLog] 			inUse = 0
[SrvLog] 			inService = 0
[SrvLog] 			blessed = 0
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 0
[SrvLog] 			type = 0
[SrvLog] 			parentId = 2000004
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 211
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 0
[SrvLog] 			diskused = 0
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, 
[SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, 
[SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 0
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031201
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:53:21 Entering VAttachVolumeById() for volume 2000004
[SrvLog] 16:53:21 running as volume utility
[SrvLog] 16:53:21 Entering ExtractVolHeader for volume 2000004
[SrvLog] 16:53:21 Entering VolHeaderByIndex for index 1
[SrvLog] 16:53:21 Entering attach2(); not running as fileServer
[SrvLog] 16:53:21 Entering GetVolumeHeader()
[SrvLog] 16:53:21 Entering VolDiskInfoById for volume 2000004
[SrvLog] 16:53:21 Entering ExtractVolDiskInfo for volindex 1
[SrvLog] 16:53:21 VolDiskInfoById: vol->stamp.magic = 2023862981, vol->stamp.version = 1
[SrvLog] 16:53:21 Entering AddVolumeToHashTable for volume 2000004, hashid 33554436
[SrvLog] 16:53:21 Entering VolumeHashOffset()
[SrvLog] 16:53:21 Entering GetBitmap() for volume 2000004, vclass = 0
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 0
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 0
[SrvLog] 16:53:21 GetBitmap: allocating bitmap of 16 bytes; array size 128
[SrvLog] 16:53:21 Entering GetBitmap() for volume 2000004, vclass = 1
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 1
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 1
[SrvLog] 16:53:21 GetBitmap: allocating bitmap of 32 bytes; array size 256
[SrvLog] 16:53:21 Leaving attach2()
[SrvLog] 16:53:21 returning from VAttachVolumeById()
[SrvLog] 16:53:21 in AL_NameToId(System:Administrators)
[SrvLog] 16:53:21 in AL_NameToId(System:AnyUser)
[SrvLog] 16:53:21 VN_GetDirHandle NEW Vnode 0 Uniq 0 cnt 1
[SrvLog] 
[SrvLog] 16:53:21 ViceCreateRoot: did.Vnode = 1
[SrvLog] 16:53:21 VN_DCommit: Commiting pages for dir vnode = 1
[SrvLog] 16:53:21 Entering DI_New
[SrvLog] 16:53:21 VN_PutDirHandle: Vn 1 Uniq 1: cnt 0, vn_cnt 0
[SrvLog] 
[SrvLog] 16:53:21 Creating new log for root vnode
[SrvLog] 
[SrvLog] 16:53:21 AllocRecord: returning index 0 seqno 1
[SrvLog] 
[SrvLog] 16:53:21 RecovPutRecord: Growing Log
[SrvLog] 
[SrvLog] 16:53:21 Entering recle::InitFromSle()
[SrvLog] 
[SrvLog] 16:53:21 Opcode is Mkdir
[SrvLog] 
[SrvLog] 16:53:21 Leaving InitFromSle
[SrvLog] 
[SrvLog] Vnode 1.1.1, cloned = 0, length = 2048, inode = 1459462984
[SrvLog] link count = 2, type = 2, volume index = 1
[SrvLog] {[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 16:53:21 Entering ReplaceVnode(1, 0, 0, 1159335012)
[SrvLog] 16:53:21 ReplaceVnode: no large vnode at index 0; allocating
[SrvLog] 16:53:21 ReplaceVnode: taking large vnode from freelist
[SrvLog] 16:53:21 Taking vnode off of largefreelist[126] for index 0
[SrvLog] 16:53:21 Replace vnode - VnodeDiskObject passed to rtn:
[SrvLog] 			type = 2
[SrvLog] 			cloned = 0
[SrvLog] 			mode = 777
[SrvLog] 			links = 2
[SrvLog] 			length = 2048
[SrvLog] 			unique = 1
[SrvLog] 			version = 1
[SrvLog] 			inode = 1459462984
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			volindex = 1
[SrvLog] 			modtime = 998031201
[SrvLog] 			author = 0
[SrvLog] 			owner = 0
[SrvLog] 			parent = 0.0
[SrvLog] 			magic = ad8765fe
[SrvLog] 			servermodtime = 998031201
[SrvLog] 16:53:21 Entering ExtractVnode(volindex = 1, vclass = 0, vnodeindex = 0, Unique = 1 vnode = 0x451a0070)
[SrvLog] 16:53:21 ExtractVnode: vnode->type = 2
[SrvLog] Printing Large vnode 1, (index 0) from volume 1
[SrvLog] 			type = 2
[SrvLog] 			cloned = 0
[SrvLog] 			mode = 777
[SrvLog] 			links = 2
[SrvLog] 			length = 2048
[SrvLog] 			unique = 1
[SrvLog] 			version = 1
[SrvLog] 			inode = 1459462984
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			volindex = 1
[SrvLog] 			modtime = 998031201
[SrvLog] 			author = 0
[SrvLog] 			owner = 0
[SrvLog] 			parent = 0.0
[SrvLog] 			magic = ad8765fe
[SrvLog] 			servermodtime = 998031201
[SrvLog] 16:53:21 Entering VUpdateVolume() for volume 2000004
[SrvLog] 16:53:21 Entering WriteVolumeHeader for volume 2000004
[SrvLog] 16:53:21 Entering ReplaceVolDiskInfo for volume index 1
[SrvLog] 16:53:21 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:53:21 ReplacevolDiskInfo: got locks!
[SrvLog] 16:53:21 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 1
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 2000004
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = u.hills.1
[SrvLog] 			inUse = 0
[SrvLog] 			inService = 1
[SrvLog] 			blessed = 1
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 2
[SrvLog] 			type = 0
[SrvLog] 			parentId = 2000004
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 0
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 1
[SrvLog] 			diskused = 2
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, 
[SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, 
[SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 998031201
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031201
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:53:21 Entering VDetachVolume() for volume 2000004
[SrvLog] 16:53:21 Entering DeleteVolumeFromHashTable
[SrvLog] 16:53:21 Entering ReleaseVolumeHeader
[SrvLog] 16:53:21 DeleteVolumeFromHashTable: Deleting volume 2000004 from hash table
[SrvLog] 16:53:21 Entering VPutVolume for volume 2000004
[SrvLog] 16:53:21 Entering ReleaseVolumeHeader
[SrvLog] 16:53:21 Entering FreeVolume for volume 2000004
[SrvLog] 16:53:21 Entering FreeVolumeHeader for volume 2000004
[SrvLog] 16:53:21 Entering ReleaseVolumeHeader
[SrvLog] 16:53:21 Entering DeleteVolumeFromHashTable
[SrvLog] 16:53:21 Entering FSYNC_askfs(2000004, 1,0)
[SrvLog] 16:53:21 Entering VAttachVolume() for volume 2000004
[SrvLog] 16:53:21 Entering GetVolPartition for volid 2000004
[SrvLog] 16:53:21 Entering VAttachVolumeById() for volume 2000004
[SrvLog] 16:53:21 running as fileserver
[SrvLog] 16:53:21 Entering VGetVolume for volume 2000004
[SrvLog] 16:53:21 VGetVolume: Didnt find id 2000004 in hashtable
[SrvLog] 16:53:21 Entering FSYNC_CheckRelocationSite(2000004)
[SrvLog] 16:53:21 Entering ExtractVolHeader for volume 2000004
[SrvLog] 16:53:21 Entering VolHeaderByIndex for index 1
[SrvLog] 16:53:21 Entering attach2();  running as fileServer
[SrvLog] 16:53:21 Entering GetVolumeHeader()
[SrvLog] 16:53:21 Entering VolDiskInfoById for volume 2000004
[SrvLog] 16:53:21 Entering ExtractVolDiskInfo for volindex 1
[SrvLog] 16:53:21 VolDiskInfoById: vol->stamp.magic = 2023862981, vol->stamp.version = 1
[SrvLog] 16:53:21 VAttachVolume: setting V_inUse(vp) = 1 for volume 2000004
[SrvLog] 16:53:21 Entering AddVolumeToHashTable for volume 2000004, hashid 33554436
[SrvLog] 16:53:21 Entering VolumeHashOffset()
[SrvLog] 16:53:21 Entering GetBitmap() for volume 2000004, vclass = 0
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 0
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 0
[SrvLog] 16:53:21 GetBitmap: allocating bitmap of 16 bytes; array size 128
[SrvLog] 16:53:21 GetBitmap: found vnode at index 0
[SrvLog] 16:53:21 results of or: *(vip->bitmap + bitNumber >> 3) = 1
[SrvLog] 16:53:21 Entering GetBitmap() for volume 2000004, vclass = 1
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 1
[SrvLog] 16:53:21 Entering ActiveVnodes for index 1, vclass = 1
[SrvLog] 16:53:21 GetBitmap: allocating bitmap of 32 bytes; array size 256
[SrvLog] 16:53:21 Leaving attach2()
[SrvLog] 16:53:21 Entering VUpdateVolume() for volume 2000004
[SrvLog] 16:53:21 Entering WriteVolumeHeader for volume 2000004
[SrvLog] 16:53:21 Entering ReplaceVolDiskInfo for volume index 1
[SrvLog] 16:53:21 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:53:21 ReplacevolDiskInfo: got locks!
[SrvLog] 16:53:21 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 1
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 2000004
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = u.hills.1
[SrvLog] 			inUse = 1
[SrvLog] 			inService = 1
[SrvLog] 			blessed = 1
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 2
[SrvLog] 			type = 0
[SrvLog] 			parentId = 2000004
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 0
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 1
[SrvLog] 			diskused = 2
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, 
[SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, 
[SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 998031201
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031201
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:53:21 Entering VAddToVolumeUpdateList for volume 2000004
[SrvLog] 16:53:21 Entering VUpdateVolume() for volume 2000004
[SrvLog] 16:53:21 Entering WriteVolumeHeader for volume 2000004
[SrvLog] 16:53:21 Entering ReplaceVolDiskInfo for volume index 1
[SrvLog] 16:53:21 ReplaceVolDiskInfo: about to acquire locks
[SrvLog] 16:53:21 ReplacevolDiskInfo: got locks!
[SrvLog] 16:53:21 ReplaceVolDiskInfo: recoverable stamp = 2023862981, 1
[SrvLog] Printing VolumeDiskObject for volume index 1
[SrvLog] 			version stamp = 78a1b2c5, 1
[SrvLog] 			id = 2000004
[SrvLog] 			partition = /vicepa
[SrvLog] 			name = u.hills.1
[SrvLog] 			inUse = 1
[SrvLog] 			inService = 1
[SrvLog] 			blessed = 1
[SrvLog] 			needsSalvaged = 0
[SrvLog] 			uniquifier= 2
[SrvLog] 			type = 0
[SrvLog] 			parentId = 2000004
[SrvLog] 			grpId = 7f000004
[SrvLog] 			cloneId = 0
[SrvLog] 			backupId = 0n			restoreFromId = 0
[SrvLog] 			needsCallback = 0
[SrvLog] 			destroyMe = 0
[SrvLog] 			dontSalvage = 0
[SrvLog] 			reserveb3 = 0
[SrvLog] 			{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
[SrvLog] 			reserved1[0] = 0, reserved1[1] = 0, reserved1[2] = 0, 
[SrvLog] 			reserved1[3] = 0, reserved1[4] = 0, reserved1[5] = 0, 
[SrvLog] 			maxquota = 0
[SrvLog] 			minquota = 0
[SrvLog] 			maxfiles = 0
[SrvLog] 			acctNum = 0
[SrvLog] 			owner = 0
[SrvLog] 			reserved2[0] = 0, reserved2[1] = 0, reserved2[2] = 0, 
[SrvLog] 			reserved2[3] = 0, reserved2[4] = 0, reserved2[5] = 0, 
[SrvLog] 			reserved2[6] = 0, reserved2[7] = 0, 
[SrvLog] 			filecount = 0
[SrvLog] 			linkcount = 1
[SrvLog] 			diskused = 2
[SrvLog] 			dayUse = 0
[SrvLog] 			dayUseDate = 0
[SrvLog] 			weekUse[0] = 0, weekUse[1] = 0, weekUse[2] = 0, 
[SrvLog] 			weekUse[3] = 0, weekUse[4] = 0, weekUse[5] = 0, 
[SrvLog] 			weekUse[6] = 0, 
[SrvLog] 			reserved3[0] = 0, reserved3[1] = 0, reserved3[2] = 0, 
[SrvLog] 			reserved3[3] = 0, reserved3[4] = 0, reserved3[5] = 0, 
[SrvLog] 			reserved3[6] = 0, reserved3[7] = 0, reserved3[8] = 0, 
[SrvLog] 			reserved3[9] = 0, reserved3[10] = 0, 
[SrvLog] 			creationDate = 998031201
[SrvLog] 			accessDate = 0
[SrvLog] 			updateDate = 0
[SrvLog] 			expirationDate = 0
[SrvLog] 			backupDate = 0
[SrvLog] 			copyDate = 998031201
[SrvLog] 			reserved4[0] = 0, reserved4[1] = 0, reserved4[2] = 0, 
[SrvLog] 			reserved4[3] = 0, reserved4[4] = 0, reserved4[5] = 0, 
[SrvLog] 			reserved4[6] = 0, reserved4[7] = 0, 
[SrvLog] 			offlineMessage = 
[SrvLog] 			motd = 
[SrvLog] 
[SrvLog] 16:53:21 VAddToVolumeUpdateList: Adding volume 2000004 to update list
[SrvLog] 16:53:21 Leaving VAddToVolumeUpdateList()
[SrvLog] 16:53:21 VAttachVolumeById: vol 2000004 (u.hills.1) attached and online
[SrvLog] 16:53:21 returning from VAttachVolumeById()
[SrvLog] 16:53:21 Entering VPutVolume for volume 2000004
[SrvLog] 16:53:21 Entering ReleaseVolumeHeader
[SrvLog] 16:53:21 Entering VDisconnectFS
[SrvLog] 16:53:21 Entering FSYNC_clientFinis()
[SrvLog] 16:53:21 create: volume 2000004 (u.hills.1) created
[SrvLog] New Connection 0x81f2000......
[SrvLog] 16:53:22 VolUtilWorker 0 received request -13
[SrvLog] 16:53:22 VolUtilWorker 1 received request 41
[SrvLog] 16:53:22 Entering S_GetVolumeList
[SrvLog] 
[SrvLog] 16:53:22 Entering VListVolumes()
[SrvLog] 16:53:22 Entering VGetVolume for volume 2000001
[SrvLog] 16:53:22 VGetVolume: nUsers == 0
[SrvLog] 16:53:22 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:53:22 Entering AvailVolumeHeader()
[SrvLog] 16:53:22 AvailVolumeHeader returns 1
[SrvLog] 16:53:22 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:53:22 Entering GetVolumeHeader()
[SrvLog] 16:53:22 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:53:22 Entering VListVolume for volume 2000001
[SrvLog] 16:53:22 Entering VAdjustVolumeStatistics for volume 2000001
[SrvLog] 16:53:22 Entering VPutVolume for volume 2000001
[SrvLog] 16:53:22 Entering ReleaseVolumeHeader
[SrvLog] 16:53:22 Entering VGetVolume for volume 2000004
[SrvLog] 16:53:22 VGetVolume: nUsers == 0
[SrvLog] 16:53:22 VGetVolume: Calling AvailVolumeHeader()
[SrvLog] 16:53:22 Entering AvailVolumeHeader()
[SrvLog] 16:53:22 AvailVolumeHeader returns 1
[SrvLog] 16:53:22 VGetVolume: Calling GetVolumeHeader()
[SrvLog] 16:53:22 Entering GetVolumeHeader()
[SrvLog] 16:53:22 VGetVolume: Finished GetVolumeHeader()
[SrvLog] 16:53:22 Entering VListVolume for volume 2000004
[SrvLog] 16:53:22 Entering VAdjustVolumeStatistics for volume 2000004
[SrvLog] 16:53:22 Entering VPutVolume for volume 2000004
[SrvLog] 16:53:22 Entering ReleaseVolumeHeader
[SrvLog] 16:53:22 GetVolumeList returns 0
[SrvLog] 
[SrvLog] 0x8125180 : Cop Pending Manager

On the SCM, this is then followed by:

bash-2.05# cfs mkmount hills u.hills
bash-2.05# cd hills
bash-2.05# ls
bash-2.05# 

All cool. On the other server:

bash-2.05# ls
hills
bash-2.05# cd hills
bash: cd: hills: No such file or directory
bash-2.05# ls -l
total 0
lrw-r--r--  1 brett  nobody  9 Aug 17 17:00 hills -> #u.hills
bash-2.05# 

I have been playing with coda for a few weeks now, and this one gets
me stumped. I know it means that I can't access the volume for some
reason, but I can't work out why.

Are there any files I can send to the list to help you figure out why
this is happening?

TIA and apologies again for the log size
-- 
"Give no sleep to your eyes,
Nor slumber to your eyelids."

- Proverbs 6:4, NKJV