07:55:43 New SrvLog started at Fri Mar 31 07:55:43 2000

07:55:43 Resource limit on data size are set to 2147483647

07:55:43 Server etext 0x80ff56a, edata 0x8138214
07:55:43 RvmType is Rvm
07:55:43 Main process doing a LWP_Init()
07:55:43 Main thread just did a RVM_SET_THREAD_DATA

07:55:43 Setting Rvm Truncate threshhold to 5.

Partition /vicepa: inodes in use: 0, total: 262144.
07:55:44 Partition /vicepa: 5764209K available (minfree=5%), 5760048K free.
07:55:44 The server (pid 3912) can be controlled using volutil commands
07:55:44 "volutil -help" will give you a list of these commands
07:55:44 If desperate,
		"kill -SIGWINCH 3912" will increase debugging level
07:55:44 	"kill -SIGUSR2 3912" will set debugging level to zero
07:55:44 	"kill -9 3912" will kill a runaway server
07:55:44 VCheckVLDB:  could not open VLDB
07:55:44 VInitVolPackage: no VLDB! Please create a new one.
07:55:44 Vice file system salvager, version 3.0.
07:55:44 SanityCheckFreeLists: Checking RVM Vnode Free lists.
07:55:44 DestroyBadVolumes: Checking for destroyed volumes.
07:55:44 Salvaging file system partition /vicepa
07:55:44 Force salvage of all volumes on this partition
07:55:44 Scanning inodes in directory /vicepa...
07:55:44 SalvageFileSys completed on /vicepa
07:55:44 Attached 0 volumes; 0 volumes not attached
07:55:44 CheckVRDB: could not open VRDB
lqman: Creating LockQueue Manager.....LockQueue Manager starting .....
07:55:44 LockQueue Manager just did a rvmlib_set_thread_data()

done
07:55:44 CallBackCheckLWP just did a rvmlib_set_thread_data()

07:55:44 CheckLWP just did a rvmlib_set_thread_data()

07:55:44 ServerLWP 0 just did a rvmlib_set_thread_data()

07:55:44 ServerLWP 1 just did a rvmlib_set_thread_data()

07:55:44 ServerLWP 2 just did a rvmlib_set_thread_data()

07:55:44 ServerLWP 3 just did a rvmlib_set_thread_data()

07:55:44 ServerLWP 4 just did a rvmlib_set_thread_data()

07:55:44 ServerLWP 5 just did a rvmlib_set_thread_data()

07:55:44 ResLWP-0 just did a rvmlib_set_thread_data()

07:55:44 ResLWP-1 just did a rvmlib_set_thread_data()

07:55:44 VolUtilLWP 0 just did a rvmlib_set_thread_data()

07:55:44 VolUtilLWP 1 just did a rvmlib_set_thread_data()

07:55:44 Starting SmonDaemon timer
07:55:44 File Server started Fri Mar 31 07:55:44 2000

07:56:24 Setting Volume debug level to 9
07:56:41 VolUtilWorker 0 received request -13
07:56:41 VolUtilWorker 1 received request 2
07:56:41 Entering S_VolCreate: rpcid = 264647843, partition = /vicepa,volname = coda:root.0, volumeid = 0, repvol = 1, grpid = 7f000000
07:56:41 Entering VInitVolUtil
07:56:41 Entering VConnectFS
07:56:41 Entering FSYNC_clientInit()
07:56:41 Entering VAllocateVolumeId()
07:56:41 VolCreate: VAllocateVolumeId returns 2a000001
07:56:41 Creating log for volume

07:56:41 Entering NewVolHeader
07:56:41 NewVolHeader: Going to stamp new header 
07:56:41 NewVolHeader: Going to Allocate VolumeDiskData 
07:56:41 NewVolHeader: Going to allocate vnode arrays
07:56:41 NewVolHeader: Zeroing out small vnode array of size 1024
07:56:41 NewVolHeader: Zeroing out large vnode array of size 512
07:56:41 NewVolHeader: Going to write header in recoverable storage 
07:56:41 Entering NewVolDiskInfo for index 0, volume 2a000001
07:56:41 Entering ReplaceVolDiskInfo for volume index 0
07:56:41 ReplaceVolDiskInfo: about to acquire locks
07:56:41 ReplacevolDiskInfo: got locks!
07:56:41 Entering VAttachVolumeById() for volume 2a000001
07:56:41 Entering ExtractVolHeader for volume 2a000001
07:56:41 Entering VolHeaderByIndex for index 0
07:56:41 Entering attach2(); not running as fileServer
07:56:41 Entering GetVolumeHeader()
07:56:41 Entering VolDiskInfoById for volume 2a000001
07:56:41 Entering ExtractVolDiskInfo for volindex 0
07:56:41 Entering AddVolumeToHashTable for volume 2a000001, hashid 704643073
07:56:41 Entering VolumeHashOffset()
07:56:41 Entering GetBitmap() for volume 2a000001, vclass = 0
07:56:41 Entering ActiveVnodes for index 0, vclass = 0
07:56:41 Entering ActiveVnodes for index 0, vclass = 0
07:56:41 GetBitmap: allocating bitmap of 16 bytes; array size 128
07:56:41 Entering GetBitmap() for volume 2a000001, vclass = 1
07:56:41 Entering ActiveVnodes for index 0, vclass = 1
07:56:41 Entering ActiveVnodes for index 0, vclass = 1
07:56:41 GetBitmap: allocating bitmap of 32 bytes; array size 256
07:56:41 VN_GetDirHandle NEW Vnode 0 Uniq 0 cnt 1

07:56:41 VN_PutDirHandle: Vn 1 Uniq 1: cnt 0, vn_cnt 0

07:56:41 Creating new log for root vnode

07:56:41 Entering recle::InitFromSle()

07:56:41 Opcode is Mkdir

07:56:41 Leaving InitFromSle

Vnode 1.1.1, cloned = 0, length = 2048, inode = 574480940
link count = 2, type = 2, volume index = 0
{[ 0 0 0 0 0 0 0 0 ] [ 0 0 ] [ 0 ]}
07:56:41 Entering ReplaceVnode(0, 0, 0, 354012340)
07:56:41 ReplaceVnode: taking large vnode from freelist
07:56:41 Entering VUpdateVolume() for volume 2a000001
07:56:41 Entering WriteVolumeHeader for volume 2a000001
07:56:41 Entering ReplaceVolDiskInfo for volume index 0
07:56:41 ReplaceVolDiskInfo: about to acquire locks
07:56:41 ReplacevolDiskInfo: got locks!
07:56:41 Entering VDetachVolume() for volume 2a000001
07:56:41 Entering DeleteVolumeFromHashTable
07:56:41 Entering VPutVolume for volume 2a000001
07:56:41 Entering FreeVolume for volume 2a000001
07:56:41 Entering FreeVolumeHeader for volume 2a000001
07:56:41 Entering DeleteVolumeFromHashTable
07:56:41 Entering FSYNC_askfs(2a000001, 1,0)
07:56:41 Entering VAttachVolume() for volume 2a000001
07:56:41 Entering GetVolPartition for volid 2a000001
07:56:41 Entering VAttachVolumeById() for volume 2a000001
07:56:41 Entering VGetVolume for volume 2a000001
07:56:41 Entering FSYNC_CheckRelocationSite(2a000001)
07:56:41 Entering ExtractVolHeader for volume 2a000001
07:56:41 Entering VolHeaderByIndex for index 0
07:56:41 Entering attach2();  running as fileServer
07:56:41 Entering GetVolumeHeader()
07:56:41 Entering VolDiskInfoById for volume 2a000001
07:56:41 Entering ExtractVolDiskInfo for volindex 0
07:56:41 VAttachVolume: setting V_inUse(vp) = 1 for volume 2a000001
07:56:41 Entering AddVolumeToHashTable for volume 2a000001, hashid 704643073
07:56:41 Entering VolumeHashOffset()
07:56:41 Entering GetBitmap() for volume 2a000001, vclass = 0
07:56:41 Entering ActiveVnodes for index 0, vclass = 0
07:56:41 Entering ActiveVnodes for index 0, vclass = 0
07:56:41 GetBitmap: allocating bitmap of 16 bytes; array size 128
07:56:41 Entering GetBitmap() for volume 2a000001, vclass = 1
07:56:41 Entering ActiveVnodes for index 0, vclass = 1
07:56:41 Entering ActiveVnodes for index 0, vclass = 1
07:56:41 GetBitmap: allocating bitmap of 32 bytes; array size 256
07:56:41 Entering VUpdateVolume() for volume 2a000001
07:56:41 Entering WriteVolumeHeader for volume 2a000001
07:56:41 Entering ReplaceVolDiskInfo for volume index 0
07:56:41 ReplaceVolDiskInfo: about to acquire locks
07:56:41 ReplacevolDiskInfo: got locks!
07:56:41 Entering VAddToVolumeUpdateList for volume 2a000001
07:56:41 Entering VUpdateVolume() for volume 2a000001
07:56:41 Entering WriteVolumeHeader for volume 2a000001
07:56:41 Entering ReplaceVolDiskInfo for volume index 0
07:56:41 ReplaceVolDiskInfo: about to acquire locks
07:56:41 ReplacevolDiskInfo: got locks!
07:56:41 VAttachVolumeById: vol 2a000001 (coda:root.0) attached and online
07:56:41 Entering VAppendVolume for volume 2a000001
07:56:41 Entering VListVolume for volume 2a000001
07:56:41 Entering VAdjustVolumeStatistics for volume 2a000001
07:56:41 Entering VPutVolume for volume 2a000001
07:56:41 Entering VDisconnectFS
07:56:41 Entering FSYNC_clientFinis()
07:56:41 create: volume 2a000001 (coda:root.0) created
07:56:41 VolUtilWorker 0 received request -13
07:56:41 VolUtilWorker 1 received request 4
07:56:41 Entering S_VolMakeVLDB; infile /vice/vol/BigVolumeList
VLDB created.  Search lengths: RO 0, RW 0, BK 0.
07:56:41 /vice/vol/AllVolumes written
07:56:41 VolUtilWorker 0 received request -13
07:56:41 VolUtilWorker 1 received request 5
07:56:41 Entering S_VolMakeVRDB; infile /vice/vol/VRList
07:56:41 VRDB created, 1 entries
0x81e1590 : Cop Pending Manager
07:56:44 LockQueue Manager woken up
07:56:44 LockQueue Manager sleeping for 60 seconds