18:51:42 New SrvLog started at Sun Sep 30 18:51:42 2001 18:51:42 Resource limit on data size are set to -1 18:51:42 RvmType is Rvm 18:51:42 Main process doing a LWP_Init() 18:51:42 Main thread just did a RVM_SET_THREAD_DATA 18:51:42 Setting Rvm Truncate threshhold to 5. Partition /vicepa: inodes in use: 11361, total: 262144. Partition /vicepb: inodes in use: 2496, total: 262144. 18:51:46 Partition /vicepa: 727892K available (minfree=5%), 514808K free. 18:51:46 Partition /vicepb: 930512K available (minfree=5%), 643444K free. 18:51:46 The server (pid 30784) can be controlled using volutil commands 18:51:46 "volutil -help" will give you a list of these commands 18:51:46 If desperate, "kill -SIGWINCH 30784" will increase debugging level 18:51:46 "kill -SIGUSR2 30784" will set debugging level to zero 18:51:46 "kill -9 30784" will kill a runaway server 18:51:46 Vice file system salvager, version 3.0. 18:51:46 SanityCheckFreeLists: Checking RVM Vnode Free lists. 18:51:46 DestroyBadVolumes: Checking for destroyed volumes. 18:51:46 Salvaging file system partition /vicepa 18:51:46 Force salvage of all volumes on this partition 18:51:46 Scanning inodes in directory /vicepa... 18:51:53 Entering DCC(0x1000001) 18:51:53 DCC: Salvaging Logs for volume 0x1000001 18:51:53 done: 65 files/dirs, 16600 blocks 18:51:53 Entering DCC(0x1000002) 18:51:53 DCC: Salvaging Logs for volume 0x1000002 18:51:53 done: 1727 files/dirs, 23576 blocks 18:51:53 Entering DCC(0x1000003) 18:51:53 DCC: Salvaging Logs for volume 0x1000003 18:51:53 done: 2860 files/dirs, 40883 blocks 18:51:53 Entering DCC(0x1000004) 18:51:53 DCC: Salvaging Logs for volume 0x1000004 18:51:53 recov_vol_log::SalvageLog: bitmaps are not equal 18:51:53 Log rec at index 276 is unreachable **Server: 0x829e626d StoreId: 0x7c1e77.6ac3 Directory(0x2ec1.16b6) Opcode: Mkdir index is 276, sequence number 11885, var length is 17 . [0x2ec1.16b6] owner 1000 ** End of Record ** 18:51:53 Log rec at index 278 is unreachable **Server: 0x829e626d StoreId: 0x7c1e77.6ac4 Directory(0x2ec1.16b6) Opcode: Mkdir index is 278, sequence number 11886, var length is 19 CVS [0x2ed1.16b7] owner 1000 ** End of Record ** 18:51:53 Log rec at index 280 is unreachable **Server: 0x829e626d StoreId: 0x7c1e77.6ac4 Directory(0x2ed1.16b7) Opcode: Mkdir index is 280, sequence number 11887, var length is 17 . [0x2ed1.16b7] owner 1000 ** End of Record ** 18:51:53 Log rec at index 282 is unreachable **Server: 0x829e626d StoreId: 0x7c1e77.6ac5 Directory(0x2ed1.16b7) Opcode: Create index is 282, sequence number 11888, var length is 20 Root [0xedd2.16c2] owner 0 ** End of Record ** 18:51:53 Log rec at index 351 is unreachable **Server: 0x829e626d StoreId: 0x7c1e77.6dcd Directory(0x2ed1.16b7) Opcode: Remove index is 351, sequence number 11924, var length is 60 Root [0xedd2.16c2][0 0 0 0 0 0 0 0 (7c1e77.6dcd)(0x0)] ** End of Record ** 18:51:53 Log rec at index 352 is unreachable **Server: 0x829e626d StoreId: 0x7c1e77.6dce Directory(0x2ec1.16b6) Opcode: Rmdir index is 352, sequence number 11925, var length is 35 CVS [0x2ed1.16b7] del storeid [0x7c1e77.6dce] ** End of Record ** 18:51:53 done: 1913 files/dirs, 28668 blocks 18:51:53 Entering DCC(0x1000005) 18:51:54 DCC: Salvaging Logs for volume 0x1000005 18:51:54 done: 4818 files/dirs, 62632 blocks 18:51:54 Entering DCC(0x1000006) 18:51:54 DCC: Salvaging Logs for volume 0x1000006 18:51:54 done: 895 files/dirs, 14554 blocks 18:51:54 Entering DCC(0x1000007) 18:51:54 DCC: Salvaging Logs for volume 0x1000007 18:51:54 done: 75 files/dirs, 293 blocks 18:51:54 SalvageFileSys completed on /vicepa 18:51:54 Salvaging file system partition /vicepb 18:51:54 Force salvage of all volumes on this partition 18:51:54 Scanning inodes in directory /vicepb... 18:51:55 SFS: There are some volumes without any inodes in them 18:51:55 SFS:No Inode summary for volume 0x1000008; skipping full salvage 18:51:55 SalvageFileSys: Therefore only resetting inUse flag 18:51:55 Entering DCC(0x1000009) 18:51:55 DCC: Salvaging Logs for volume 0x1000009 18:51:55 done: 1709 files/dirs, 22313 blocks 18:51:55 SFS:No Inode summary for volume 0x100000a; skipping full salvage 18:51:55 SalvageFileSys: Therefore only resetting inUse flag 18:51:55 SFS:No Inode summary for volume 0x100000b; skipping full salvage 18:51:55 SalvageFileSys: Therefore only resetting inUse flag 18:51:55 Entering DCC(0x100000c) 18:51:55 DCC: Salvaging Logs for volume 0x100000c 18:51:55 done: 36 files/dirs, 485 blocks 18:51:55 SFS:No Inode summary for volume 0x100000d; skipping full salvage 18:51:55 SalvageFileSys: Therefore only resetting inUse flag 18:51:55 Entering DCC(0x100000e) 18:51:55 DCC: Salvaging Logs for volume 0x100000e 18:51:55 done: 2 files/dirs, 3 blocks 18:51:55 Entering DCC(0x100000f) 18:51:55 DCC: Salvaging Logs for volume 0x100000f 18:51:55 done: 849 files/dirs, 258021 blocks 18:51:55 Entering DCC(0x1000010) 18:51:55 DCC: Salvaging Logs for volume 0x1000010 18:51:55 done: 3 files/dirs, 8 blocks 18:51:55 SalvageFileSys completed on /vicepb 18:51:55 VAttachVolumeById: vol 1000001 (coda:root.0) attached and online 18:51:55 VAttachVolumeById: vol 1000002 (xe:21-1.0) attached and online 18:51:55 VAttachVolumeById: vol 1000003 (xe:21-4.0) attached and online 18:51:55 VAttachVolumeById: vol 1000004 (xe:21-5.0) attached and online 18:51:56 VAttachVolumeById: vol 1000005 (xe:pkg.0) attached and online 18:51:56 VAttachVolumeById: vol 1000006 (xe:web.0) attached and online 18:51:56 VAttachVolumeById: vol 1000007 (xe:tools.0) attached and online 18:51:56 VAttachVolumeById: vol 1000008 (python.0) attached and online 18:51:56 VAttachVolumeById: vol 1000009 (ghost.0) attached and online 18:51:56 VAttachVolumeById: vol 100000a (coda.0) attached and online 18:51:56 VAttachVolumeById: vol 100000b (ug:micro.0) attached and online 18:51:56 VAttachVolumeById: vol 100000c (ug:dynamics.0) attached and online 18:51:56 VAttachVolumeById: vol 100000d (mba:nyuushi.0) attached and online 18:51:56 VAttachVolumeById: vol 100000e (steve.0) attached and online 18:51:56 VAttachVolumeById: vol 100000f (steve:mail.0) attached and online 18:51:56 VAttachVolumeById: vol 1000010 (zemi.0) attached and online 18:51:56 Attached 16 volumes; 0 volumes not attached lqman: Creating LockQueue Manager.....LockQueue Manager starting ..... 18:51:56 LockQueue Manager just did a rvmlib_set_thread_data() done 18:51:56 CallBackCheckLWP just did a rvmlib_set_thread_data() 18:51:56 CheckLWP just did a rvmlib_set_thread_data() 18:51:56 ServerLWP 0 just did a rvmlib_set_thread_data() 18:51:56 ServerLWP 1 just did a rvmlib_set_thread_data() 18:51:56 ServerLWP 2 just did a rvmlib_set_thread_data() 18:51:56 ServerLWP 3 just did a rvmlib_set_thread_data() 18:51:56 ServerLWP 4 just did a rvmlib_set_thread_data() 18:51:56 ServerLWP 5 just did a rvmlib_set_thread_data() 18:51:56 ResLWP-0 just did a rvmlib_set_thread_data() 18:51:56 ResLWP-1 just did a rvmlib_set_thread_data() 18:51:56 VolUtilLWP 0 just did a rvmlib_set_thread_data() 18:51:56 VolUtilLWP 1 just did a rvmlib_set_thread_data() 18:51:56 Starting SmonDaemon timer 18:51:56 File Server started Sun Sep 30 18:51:56 2001 18:52:07 client_GetVenusId: got new host 130.158.99.4:3056 18:52:07 Building callback conn. 18:52:07 No idle WriteBack conns, building new one 18:52:07 Writeback message to 130.158.99.4 port 3056 on conn 3ead5e63 succeeded 18:52:17 RevokeWBPermit on conn 3ead5e63 returned 0 18:52:43 client_GetVenusId: got new host 130.158.98.109:2699 18:52:43 Building callback conn. 18:52:43 No idle WriteBack conns, building new one 18:52:43 Writeback message to 130.158.98.109 port 2699 on conn 1c174c3d succeeded 18:52:43 RevokeWBPermit on conn 1c174c3d returned 0 18:52:51 RevokeWBPermit on conn 1c174c3d returned 0 18:53:32 RevokeWBPermit on conn 3ead5e63 returned 0 18:53:33 Building callback conn. 18:53:33 RevokeWBPermit on conn 3ead5e63 returned 0 18:53:33 RevokeWBPermit on conn 3ead5e63 returned 0 18:53:33 ViceValidateAttrs: (1000003.361.177) failed ()! 18:53:33 ViceValidateAttrs: (1000003.a1.b) failed ()! 18:53:33 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 18:53:33 ViceValidateAttrs: (1000003.371.178) failed ()! 18:53:33 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 18:53:33 ViceValidateAttrs: (1000003.371.178) failed ()! 19:51:56 SmonDaemon timer expired 19:51:56 Entered CheckRVMResStat 19:51:56 Starting SmonDaemon timer 20:30:21 Total operations for File Server = 4096 : time = Sun Sep 30 20:30:21 2001 20:30:21 Vice was last started at Sun Sep 30 18:51:56 2001 20:30:21 NewConnectFS 8 20:30:21 DisconnectFS 0 20:30:21 GetAttr 3383 20:30:21 GetAcl 0 20:30:21 Fetch 650 20:30:21 SetAttr 0 20:30:21 SetAcl 0 20:30:21 Store 0 20:30:21 ValidateAttrs 22 20:30:21 Remove 0 20:30:21 Create 0 20:30:21 Rename 0 20:30:21 SymLink 0 20:30:21 Link 0 20:30:21 MakeDir 0 20:30:21 RemoveDir 0 20:30:21 GetRootVolume 1 20:30:21 SetRootVolume 0 20:30:21 GetVolumeStatus 7 20:30:21 SetVolumeStatus 0 20:30:21 GetTime 1 20:30:21 GetStatistics 0 20:30:21 GetVolumeInfo 14 20:30:21 AllocFids 0 20:30:21 COP2 0 20:30:21 Resolve 0 20:30:21 Repair 0 20:30:21 SetVV 0 20:30:21 Reintegrate 0 20:30:21 OpenReintHandle 0 20:30:21 QueryReintHandle 0 20:30:21 SendReintFragment 0 20:30:21 CloseReintHandle 0 20:30:21 GetVolVS 8 20:30:21 ValidateVols 2 20:30:21 GetWBPermit 0 20:30:21 TossWBPermit 0 20:30:21 RejectWBPermit 0 20:30:21 Total FetchDatas = 650, bytes transfered = 1110531, transfer rate = 370177 bps 20:30:21 Fetched files <1k = 386; <8k = 250; <64k = 13; <512k = 1; >512k = 0. 20:30:21 Total StoreDatas = 0, bytes transfered = 0, transfer rate = 0 bps 20:30:21 Stored files <1k = 0; <8k = 0; <64k = 0; <512k = 0; >512k = 0. 20:30:21 Large vnode cache, 500 entries, 0 allocs, 4221 gets (321 reads), 0 writes 20:30:21 Small vnode cache,500 entries, 0 allocs, 4254 gets (3796 reads), 0 writes 20:30:21 Volume header cache, 50 entries, 8972 gets, 0 replacements 20:30:21 Partition /vicepa: 727892K available (minfree=5%), 514808K free. 20:30:21 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 20:30:21 RPC Total bytes: sent = 1837063, received = 450550 20:30:21 bytes sent: rpc = 681598, multirpc = 0, sftp = 1155465, sftp multicasted = 0 20:30:21 bytes received: rpc = 450550, multirpc = 0, sftp = 0, sftp multicasted = 0 20:30:21 RPC Total packets: sent = 5266, received = 4497 20:30:21 packets sent: rpc = 4172, multirpc = 0, sftp = 1094, sftp multicasted = 0 20:30:21 packets received: rpc = 4497, multirpc = 0, sftp = 0, sftp multicasted = 0 20:30:21 RPC Packets retried = 0, Invalid packets received = 345, Busies sent = 0 20:30:21 RPC Requests 4115, Good Requests 4096, Replies 56, Busies 0 20:30:21 RPC Counters: CCount 12; Unbinds 1; FConns 1; AConns 13; GCConns 0 20:30:21 RPC Creation counts: Conn 12; SL 17; PB Small 6, Med 8, Large 2; SS 4 20:30:21 RPC2 In Use: Conn 12; SS 4 20:30:21 RPC2 PB: InUse 1, Hold 0, Freeze 0, SFree 5, MFree 8, LFree 2 20:30:21 RPC2 HW: Freeze 0, Hold 0 20:30:21 SFTP: datas 1094, datar 0, acks 0, ackr 324, retries 0, duplicates 0 20:30:21 SFTP: timeouts 0, windowfulls 0, bogus 0, didpiggy 385 20:30:21 Total CB entries= 4155, blocks = 5; and total file entries = 3604, blocks = 17 20:30:21 There are currently 8 connections in use 20:30:21 There are 2 workstations and 1 are active (req in < 15 mins) 20:30:21 Ether Total bytes: sent = 0, received = 0 20:30:21 Ether Packets: sent = 0, received = 0, errors = 0 20:30:21 Printing RVM statistics Transaction statistics current cumulative Started: 77 193189 Aborted: 0 1 Committed, flush: 77 173503 Committed, no_flush: 0 19685 Total committed: 77 193188 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 138 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 77 173497 Total flushes: 77 173515 Average flush time (msec): 12 13 Last flush time (msec): 14 rvm_truncate calls: 2 Automatic truncations: 2897 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2904 Average truncation time (sec): 2 Last truncation time (sec): 1 Last tree build time (sec): 0 Last tree apply time (sec): 1 Transaction records: 77 193326 Bookeeping records: 1 2905 Log wrap-arounds: 0 148 Total records: 78 196379 Log used: 3% 32% Log written (bytes): 79724 312210660 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 75 1 1 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 160921 5566 3998 1892 602 273 161 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2869 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1710 1147 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1643 1205 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 106 0 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 660332 580910 357516 42364 224970 132329 <=256 <=512 <=1024 <=2048 <=4096 > 4096 31650 44122 25356 27429 29 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 77 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 77 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 154 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193097 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193097 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 386194 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 77 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 77 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 154 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193097 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193097 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 386194 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 193174 0 0 0 0 0 20:30:21 done 20:51:56 SmonDaemon timer expired 20:51:56 Entered CheckRVMResStat 20:51:56 Starting SmonDaemon timer 21:30:07 Total operations for File Server = 8192 : time = Sun Sep 30 21:30:07 2001 21:30:07 Vice was last started at Sun Sep 30 18:51:56 2001 21:30:07 NewConnectFS 8 21:30:07 DisconnectFS 0 21:30:07 GetAttr 6671 21:30:07 GetAcl 0 21:30:07 Fetch 1455 21:30:07 SetAttr 0 21:30:07 SetAcl 0 21:30:07 Store 0 21:30:07 ValidateAttrs 22 21:30:07 Remove 0 21:30:07 Create 0 21:30:07 Rename 0 21:30:07 SymLink 0 21:30:07 Link 0 21:30:07 MakeDir 0 21:30:07 RemoveDir 0 21:30:07 GetRootVolume 1 21:30:07 SetRootVolume 0 21:30:07 GetVolumeStatus 7 21:30:07 SetVolumeStatus 0 21:30:07 GetTime 1 21:30:07 GetStatistics 0 21:30:07 GetVolumeInfo 14 21:30:07 AllocFids 0 21:30:07 COP2 0 21:30:07 Resolve 0 21:30:07 Repair 0 21:30:07 SetVV 0 21:30:07 Reintegrate 0 21:30:07 OpenReintHandle 0 21:30:07 QueryReintHandle 0 21:30:07 SendReintFragment 0 21:30:07 CloseReintHandle 0 21:30:07 GetVolVS 11 21:30:07 ValidateVols 2 21:30:07 GetWBPermit 0 21:30:07 TossWBPermit 0 21:30:07 RejectWBPermit 0 21:30:07 Total FetchDatas = 1455, bytes transfered = 3340833, transfer rate = 417604 bps 21:30:07 Fetched files <1k = 840; <8k = 567; <64k = 43; <512k = 5; >512k = 0. 21:30:07 Total StoreDatas = 0, bytes transfered = 0, transfer rate = 0 bps 21:30:07 Stored files <1k = 0; <8k = 0; <64k = 0; <512k = 0; >512k = 0. 21:30:07 Large vnode cache, 500 entries, 0 allocs, 8314 gets (535 reads), 0 writes 21:30:07 Small vnode cache,500 entries, 0 allocs, 7831 gets (6849 reads), 0 writes 21:30:07 Volume header cache, 50 entries, 17161 gets, 0 replacements 21:30:07 Partition /vicepa: 727892K available (minfree=5%), 514808K free. 21:30:07 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 21:30:07 RPC Total bytes: sent = 4855301, received = 865834 21:30:07 bytes sent: rpc = 1365096, multirpc = 0, sftp = 3490205, sftp multicasted = 0 21:30:07 bytes received: rpc = 865834, multirpc = 0, sftp = 0, sftp multicasted = 0 21:30:07 RPC Total packets: sent = 11572, received = 9137 21:30:07 packets sent: rpc = 8289, multirpc = 0, sftp = 3283, sftp multicasted = 0 21:30:07 packets received: rpc = 9137, multirpc = 0, sftp = 0, sftp multicasted = 0 21:30:07 RPC Packets retried = 0, Invalid packets received = 868, Busies sent = 0 21:30:07 RPC Requests 8211, Good Requests 8192, Replies 77, Busies 0 21:30:07 RPC Counters: CCount 12; Unbinds 1; FConns 1; AConns 13; GCConns 0 21:30:07 RPC Creation counts: Conn 12; SL 17; PB Small 6, Med 8, Large 2; SS 4 21:30:07 RPC2 In Use: Conn 12; SS 4 21:30:07 RPC2 PB: InUse 1, Hold 0, Freeze 0, SFree 5, MFree 8, LFree 2 21:30:07 RPC2 HW: Freeze 0, Hold 0 21:30:07 SFTP: datas 3283, datar 0, acks 0, ackr 847, retries 0, duplicates 0 21:30:07 SFTP: timeouts 0, windowfulls 0, bogus 0, didpiggy 834 21:30:07 Total CB entries= 7427, blocks = 8; and total file entries = 6760, blocks = 31 21:30:07 There are currently 8 connections in use 21:30:07 There are 2 workstations and 1 are active (req in < 15 mins) 21:30:07 Ether Total bytes: sent = 0, received = 0 21:30:07 Ether Packets: sent = 0, received = 0, errors = 0 21:30:07 Printing RVM statistics Transaction statistics current cumulative Started: 26 193247 Aborted: 0 1 Committed, flush: 26 173561 Committed, no_flush: 0 19685 Total committed: 26 193246 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 138 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 26 173555 Total flushes: 26 173573 Average flush time (msec): 38 13 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 2898 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2905 Average truncation time (sec): 2 Last truncation time (sec): 0 Last tree build time (sec): 0 Last tree apply time (sec): 0 Transaction records: 26 193384 Bookeeping records: 1 2906 Log wrap-arounds: 0 148 Total records: 27 196438 Log used: 1% 32% Log written (bytes): 20940 312314552 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 25 1 0 1 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 161027 5567 3999 1892 602 273 161 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2870 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1711 1147 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1644 1205 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 27 0 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 660332 580910 357516 42364 224970 132329 <=256 <=512 <=1024 <=2048 <=4096 > 4096 31650 44122 25493 27429 29 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 27 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 27 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 54 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193205 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193205 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 386410 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 27 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 27 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 54 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193205 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193205 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 386410 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 193232 0 0 0 0 0 21:30:07 done 21:52:01 SmonDaemon timer expired 21:52:01 Entered CheckRVMResStat 21:52:01 Starting SmonDaemon timer 22:00:52 Total operations for File Server = 12288 : time = Sun Sep 30 22:00:52 2001 22:00:52 Vice was last started at Sun Sep 30 18:51:56 2001 22:00:52 NewConnectFS 8 22:00:52 DisconnectFS 0 22:00:52 GetAttr 9753 22:00:52 GetAcl 0 22:00:52 Fetch 2468 22:00:52 SetAttr 0 22:00:52 SetAcl 0 22:00:52 Store 0 22:00:52 ValidateAttrs 22 22:00:52 Remove 0 22:00:52 Create 0 22:00:52 Rename 0 22:00:52 SymLink 0 22:00:52 Link 0 22:00:52 MakeDir 0 22:00:52 RemoveDir 0 22:00:52 GetRootVolume 1 22:00:52 SetRootVolume 0 22:00:52 GetVolumeStatus 7 22:00:52 SetVolumeStatus 0 22:00:52 GetTime 1 22:00:52 GetStatistics 0 22:00:52 GetVolumeInfo 14 22:00:52 AllocFids 0 22:00:52 COP2 0 22:00:52 Resolve 0 22:00:52 Repair 0 22:00:52 SetVV 0 22:00:52 Reintegrate 0 22:00:52 OpenReintHandle 0 22:00:52 QueryReintHandle 0 22:00:52 SendReintFragment 0 22:00:52 CloseReintHandle 0 22:00:52 GetVolVS 12 22:00:52 ValidateVols 2 22:00:52 GetWBPermit 0 22:00:52 TossWBPermit 0 22:00:52 RejectWBPermit 0 22:00:52 Total FetchDatas = 2468, bytes transfered = 4615531, transfer rate = 271501 bps 22:00:52 Fetched files <1k = 1403; <8k = 1013; <64k = 46; <512k = 6; >512k = 0. 22:00:52 Total StoreDatas = 0, bytes transfered = 0, transfer rate = 0 bps 22:00:52 Stored files <1k = 0; <8k = 0; <64k = 0; <512k = 0; >512k = 0. 22:00:52 Large vnode cache, 500 entries, 0 allocs, 12409 gets (983 reads), 0 writes 22:00:52 Small vnode cache,500 entries, 0 allocs, 11057 gets (9468 reads), 0 writes 22:00:52 Volume header cache, 50 entries, 25352 gets, 0 replacements 22:00:52 Partition /vicepa: 727892K available (minfree=5%), 514808K free. 22:00:52 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 22:00:52 RPC Total bytes: sent = 6859809, received = 1288718 22:00:52 bytes sent: rpc = 2078613, multirpc = 0, sftp = 4781196, sftp multicasted = 0 22:00:52 bytes received: rpc = 1288718, multirpc = 0, sftp = 0, sftp multicasted = 0 22:00:52 RPC Total packets: sent = 16900, received = 13733 22:00:52 packets sent: rpc = 12400, multirpc = 0, sftp = 4500, sftp multicasted = 0 22:00:52 packets received: rpc = 13733, multirpc = 0, sftp = 0, sftp multicasted = 0 22:00:52 RPC Packets retried = 0, Invalid packets received = 1356, Busies sent = 0 22:00:52 RPC Requests 12310, Good Requests 12288, Replies 89, Busies 0 22:00:52 RPC Counters: CCount 12; Unbinds 1; FConns 1; AConns 13; GCConns 0 22:00:52 RPC Creation counts: Conn 12; SL 17; PB Small 6, Med 8, Large 2; SS 4 22:00:52 RPC2 In Use: Conn 12; SS 4 22:00:52 RPC2 PB: InUse 1, Hold 0, Freeze 0, SFree 5, MFree 8, LFree 2 22:00:52 RPC2 HW: Freeze 0, Hold 0 22:00:52 SFTP: datas 4500, datar 0, acks 0, ackr 1332, retries 0, duplicates 0 22:00:52 SFTP: timeouts 0, windowfulls 0, bogus 0, didpiggy 1392 22:00:52 Total CB entries= 10491, blocks = 11; and total file entries = 9822, blocks = 45 22:00:52 There are currently 8 connections in use 22:00:52 There are 2 workstations and 1 are active (req in < 15 mins) 22:00:52 Ether Total bytes: sent = 0, received = 0 22:00:52 Ether Packets: sent = 0, received = 0, errors = 0 22:00:52 Printing RVM statistics Transaction statistics current cumulative Started: 82 193303 Aborted: 0 1 Committed, flush: 82 173617 Committed, no_flush: 0 19685 Total committed: 82 193302 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 138 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 82 173611 Total flushes: 82 173629 Average flush time (msec): 12 13 Last flush time (msec): 14 rvm_truncate calls: 2 Automatic truncations: 2898 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2905 Average truncation time (sec): 2 Last truncation time (sec): 0 Last tree build time (sec): 0 Last tree apply time (sec): 0 Transaction records: 82 193440 Bookeeping records: 1 2906 Log wrap-arounds: 0 148 Total records: 83 196494 Log used: 3% 32% Log written (bytes): 64172 312314552 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 80 1 0 1 1 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 161027 5567 3999 1892 602 273 161 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2870 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1711 1147 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1644 1205 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 83 0 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 660332 580910 357516 42364 224970 132329 <=256 <=512 <=1024 <=2048 <=4096 > 4096 31650 44122 25493 27429 29 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 83 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 83 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 166 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193205 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193205 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 386410 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 83 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 83 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 166 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193205 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193205 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 386410 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 193288 0 0 0 0 0 22:00:52 done 22:30:05 Total operations for File Server = 16384 : time = Sun Sep 30 22:30:05 2001 22:30:05 Vice was last started at Sun Sep 30 18:51:56 2001 22:30:05 NewConnectFS 8 22:30:05 DisconnectFS 0 22:30:05 GetAttr 13263 22:30:05 GetAcl 0 22:30:05 Fetch 3051 22:30:05 SetAttr 0 22:30:05 SetAcl 0 22:30:05 Store 0 22:30:05 ValidateAttrs 22 22:30:05 Remove 0 22:30:05 Create 0 22:30:05 Rename 0 22:30:05 SymLink 0 22:30:05 Link 0 22:30:05 MakeDir 0 22:30:05 RemoveDir 0 22:30:05 GetRootVolume 1 22:30:05 SetRootVolume 0 22:30:05 GetVolumeStatus 7 22:30:05 SetVolumeStatus 0 22:30:05 GetTime 1 22:30:05 GetStatistics 0 22:30:05 GetVolumeInfo 16 22:30:05 AllocFids 0 22:30:05 COP2 0 22:30:05 Resolve 0 22:30:05 Repair 0 22:30:05 SetVV 0 22:30:05 Reintegrate 0 22:30:05 OpenReintHandle 0 22:30:05 QueryReintHandle 0 22:30:05 SendReintFragment 0 22:30:05 CloseReintHandle 0 22:30:05 GetVolVS 13 22:30:05 ValidateVols 2 22:30:05 GetWBPermit 0 22:30:05 TossWBPermit 0 22:30:05 RejectWBPermit 0 22:30:05 Total FetchDatas = 3051, bytes transfered = 5903114, transfer rate = 281100 bps 22:30:05 Fetched files <1k = 1739; <8k = 1242; <64k = 62; <512k = 8; >512k = 0. 22:30:05 Total StoreDatas = 0, bytes transfered = 0, transfer rate = 0 bps 22:30:05 Stored files <1k = 0; <8k = 0; <64k = 0; <512k = 0; >512k = 0. 22:30:05 Large vnode cache, 500 entries, 0 allocs, 16502 gets (1073 reads), 0 writes 22:30:05 Small vnode cache,500 entries, 0 allocs, 14814 gets (12820 reads), 0 writes 22:30:05 Volume header cache, 50 entries, 33539 gets, 0 replacements 22:30:05 Partition /vicepa: 727892K available (minfree=5%), 514808K free. 22:30:05 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 22:30:05 RPC Total bytes: sent = 8878512, received = 1681246 22:30:05 bytes sent: rpc = 2781012, multirpc = 0, sftp = 6097500, sftp multicasted = 0 22:30:05 bytes received: rpc = 1681246, multirpc = 0, sftp = 0, sftp multicasted = 0 22:30:05 RPC Total packets: sent = 22258, received = 18171 22:30:05 packets sent: rpc = 16507, multirpc = 0, sftp = 5751, sftp multicasted = 0 22:30:05 packets received: rpc = 18171, multirpc = 0, sftp = 0, sftp multicasted = 0 22:30:05 RPC Packets retried = 0, Invalid packets received = 1687, Busies sent = 0 22:30:05 RPC Requests 16406, Good Requests 16384, Replies 100, Busies 0 22:30:05 RPC Counters: CCount 12; Unbinds 1; FConns 1; AConns 13; GCConns 0 22:30:05 RPC Creation counts: Conn 12; SL 17; PB Small 6, Med 8, Large 2; SS 4 22:30:05 RPC2 In Use: Conn 12; SS 4 22:30:05 RPC2 PB: InUse 2, Hold 0, Freeze 0, SFree 4, MFree 8, LFree 2 22:30:05 RPC2 HW: Freeze 0, Hold 0 22:30:05 SFTP: datas 5751, datar 0, acks 0, ackr 1663, retries 0, duplicates 0 22:30:05 SFTP: timeouts 0, windowfulls 0, bogus 0, didpiggy 1722 22:30:05 Total CB entries= 13890, blocks = 14; and total file entries = 13218, blocks = 61 22:30:05 There are currently 8 connections in use 22:30:05 There are 2 workstations and 1 are active (req in < 15 mins) 22:30:05 Ether Total bytes: sent = 0, received = 0 22:30:05 Ether Packets: sent = 0, received = 0, errors = 0 22:30:05 Printing RVM statistics Transaction statistics current cumulative Started: 10 193365 Aborted: 0 1 Committed, flush: 10 173679 Committed, no_flush: 0 19685 Total committed: 10 193364 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 138 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 10 173673 Total flushes: 10 173691 Average flush time (msec): 0 13 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 2899 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2906 Average truncation time (sec): 2 Last truncation time (sec): 0 Last tree build time (sec): 0 Last tree apply time (sec): 0 Transaction records: 10 193502 Bookeeping records: 1 2907 Log wrap-arounds: 0 148 Total records: 11 196557 Log used: 0% 32% Log written (bytes): 8588 312418564 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 11 0 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 161158 5568 3999 1893 603 273 161 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2871 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1712 1147 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1645 1205 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 11 0 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 660332 580910 357516 42364 224970 132329 <=256 <=512 <=1024 <=2048 <=4096 > 4096 31650 44122 25627 27429 29 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 11 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 11 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 22 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193339 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 193339 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 386678 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 11 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 11 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 22 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193339 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 193339 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 386678 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 193350 0 0 0 0 0 22:30:05 done 22:52:13 SmonDaemon timer expired 22:52:13 Entered CheckRVMResStat 22:52:13 Starting SmonDaemon timer 23:52:13 SmonDaemon timer expired 23:52:13 Entered CheckRVMResStat 23:52:13 Starting SmonDaemon timer Date: Mon 10/01/2001 00:52:14 SmonDaemon timer expired 00:52:14 Entered CheckRVMResStat 00:52:14 Starting SmonDaemon timer 01:52:14 SmonDaemon timer expired 01:52:14 Entered CheckRVMResStat 01:52:14 Starting SmonDaemon timer 02:52:15 SmonDaemon timer expired 02:52:15 Entered CheckRVMResStat 02:52:15 Starting SmonDaemon timer 03:52:16 SmonDaemon timer expired 03:52:16 Entered CheckRVMResStat 03:52:16 Starting SmonDaemon timer 04:52:18 SmonDaemon timer expired 04:52:18 Entered CheckRVMResStat 04:52:18 Starting SmonDaemon timer 05:52:18 SmonDaemon timer expired 05:52:18 Entered CheckRVMResStat 05:52:18 Starting SmonDaemon timer 06:52:19 SmonDaemon timer expired 06:52:19 Entered CheckRVMResStat 06:52:19 Starting SmonDaemon timer 07:52:19 SmonDaemon timer expired 07:52:19 Entered CheckRVMResStat 07:52:19 Starting SmonDaemon timer 08:52:20 SmonDaemon timer expired 08:52:20 Entered CheckRVMResStat 08:52:20 Starting SmonDaemon timer 09:17:48 Worker3: Unbinding RPC connection 787418905 09:52:20 SmonDaemon timer expired 09:52:20 Entered CheckRVMResStat 09:52:20 Starting SmonDaemon timer 10:52:21 SmonDaemon timer expired 10:52:21 Entered CheckRVMResStat 10:52:21 Starting SmonDaemon timer 11:52:21 SmonDaemon timer expired 11:52:21 Entered CheckRVMResStat 11:52:21 Starting SmonDaemon timer 12:52:51 SmonDaemon timer expired 12:52:51 Entered CheckRVMResStat 12:52:51 Starting SmonDaemon timer 13:53:21 SmonDaemon timer expired 13:53:21 Entered CheckRVMResStat 13:53:21 Starting SmonDaemon timer 14:53:21 SmonDaemon timer expired 14:53:21 Entered CheckRVMResStat 14:53:21 Starting SmonDaemon timer 15:53:21 SmonDaemon timer expired 15:53:21 Entered CheckRVMResStat 15:53:21 Starting SmonDaemon timer 16:53:21 SmonDaemon timer expired 16:53:21 Entered CheckRVMResStat 16:53:21 Starting SmonDaemon timer 17:53:51 SmonDaemon timer expired 17:53:51 Entered CheckRVMResStat 17:53:51 Starting SmonDaemon timer 18:53:51 SmonDaemon timer expired 18:53:51 Entered CheckRVMResStat 18:53:51 Starting SmonDaemon timer 19:53:51 SmonDaemon timer expired 19:53:51 Entered CheckRVMResStat 19:53:51 Starting SmonDaemon timer 20:00:02 RevokeWBPermit on conn 1c174c3d returned 0 20:00:03 VAllocFid: volume disk uniquifier being extended 20:03:13 RevokeWBPermit on conn 1c174c3d returned 0 20:03:25 PutReintegrateObjects: stale directory fid 0x7f000017.751.1e0b, num 0, max 50 20:03:31 VAllocFid: volume disk uniquifier being extended 20:03:47 RevokeWBPermit on conn 3ead5e63 returned 0 20:04:06 RevokeWBPermit on conn 1c174c3d returned 0 20:04:06 VGetVnode: vnode 1000004.6812 is not allocated 20:04:06 VGetVnode: vnode 1000004.6822 is not allocated 20:04:07 VGetVnode: vnode 1000004.c0f2 is not allocated 20:30:02 VAllocFid: volume disk uniquifier being extended 20:30:30 Total operations for File Server = 20480 : time = Mon Oct 1 20:30:30 2001 20:30:30 Vice was last started at Sun Sep 30 18:51:56 2001 20:30:30 NewConnectFS 12 20:30:30 DisconnectFS 1 20:30:30 GetAttr 16310 20:30:30 GetAcl 0 20:30:30 Fetch 3543 20:30:30 SetAttr 0 20:30:30 SetAcl 0 20:30:30 Store 62 20:30:30 ValidateAttrs 215 20:30:30 Remove 113 20:30:30 Create 33 20:30:30 Rename 23 20:30:30 SymLink 0 20:30:30 Link 0 20:30:30 MakeDir 4 20:30:30 RemoveDir 60 20:30:30 GetRootVolume 1 20:30:30 SetRootVolume 0 20:30:30 GetVolumeStatus 7 20:30:30 SetVolumeStatus 0 20:30:30 GetTime 1 20:30:30 GetStatistics 0 20:30:30 GetVolumeInfo 16 20:30:30 AllocFids 11 20:30:30 COP2 43 20:30:30 Resolve 0 20:30:30 Repair 0 20:30:30 SetVV 0 20:30:30 Reintegrate 4 20:30:30 OpenReintHandle 0 20:30:30 QueryReintHandle 0 20:30:30 SendReintFragment 0 20:30:30 CloseReintHandle 0 20:30:30 GetVolVS 19 20:30:30 ValidateVols 2 20:30:30 GetWBPermit 0 20:30:30 TossWBPermit 0 20:30:30 RejectWBPermit 0 20:30:30 Total FetchDatas = 3542, bytes transfered = 6654796, transfer rate = 246473 bps 20:30:30 Fetched files <1k = 2115; <8k = 1347; <64k = 71; <512k = 9; >512k = 0. 20:30:30 Total StoreDatas = 62, bytes transfered = 52253, transfer rate = 26126 bps 20:30:30 Stored files <1k = 51; <8k = 9; <64k = 2; <512k = 0; >512k = 0. 20:30:30 Large vnode cache, 500 entries, 67 allocs, 21048 gets (1319 reads), 715 writes 20:30:30 Small vnode cache,500 entries, 143 allocs, 18728 gets (15705 reads), 617 writes 20:30:30 Volume header cache, 50 entries, 42208 gets, 0 replacements 20:30:30 Partition /vicepa: 727892K available (minfree=5%), 514816K free. 20:30:30 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 20:30:30 RPC Total bytes: sent = 10414686, received = 2443549 20:30:30 bytes sent: rpc = 3542925, multirpc = 0, sftp = 6871761, sftp multicasted = 0 20:30:30 bytes received: rpc = 2443549, multirpc = 0, sftp = 0, sftp multicasted = 0 20:30:30 RPC Total packets: sent = 27904, received = 23352 20:30:30 packets sent: rpc = 21356, multirpc = 0, sftp = 6548, sftp multicasted = 0 20:30:30 packets received: rpc = 23352, multirpc = 0, sftp = 0, sftp multicasted = 0 20:30:30 RPC Packets retried = 10, Invalid packets received = 2054, Busies sent = 2 20:30:30 RPC Requests 20529, Good Requests 20480, Replies 817, Busies 1 20:30:30 RPC Counters: CCount 15; Unbinds 2; FConns 2; AConns 17; GCConns 0 20:30:30 RPC Creation counts: Conn 15; SL 17; PB Small 7, Med 8, Large 8; SS 4 20:30:30 RPC2 In Use: Conn 15; SS 4 20:30:30 RPC2 PB: InUse 1, Hold 0, Freeze 0, SFree 6, MFree 8, LFree 8 20:30:30 RPC2 HW: Freeze 0, Hold 0 20:30:30 SFTP: datas 6494, datar 165, acks 39, ackr 1835, retries 0, duplicates 0 20:30:30 SFTP: timeouts 5, windowfulls 0, bogus 3, didpiggy 2255 20:30:30 Total CB entries= 14272, blocks = 14; and total file entries = 13603, blocks = 63 20:30:30 There are currently 11 connections in use 20:30:30 There are 2 workstations and 1 are active (req in < 15 mins) 20:30:30 Ether Total bytes: sent = 0, received = 0 20:30:30 Ether Packets: sent = 0, received = 0, errors = 0 20:30:30 Printing RVM statistics Transaction statistics current cumulative Started: 41 195071 Aborted: 0 1 Committed, flush: 33 175007 Committed, no_flush: 8 20063 Total committed: 41 195070 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 1 140 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 33 175001 Total flushes: 33 175019 Average flush time (msec): 0 13 Last flush time (msec): 6 rvm_truncate calls: 2 Automatic truncations: 2924 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2931 Average truncation time (sec): 2 Last truncation time (sec): 2 Last tree build time (sec): 0 Last tree apply time (sec): 2 Transaction records: 42 195210 Bookeeping records: 1 2932 Log wrap-arounds: 1 151 Total records: 44 198293 Log used: 3% 32% Log written (bytes): 64476 315359196 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 34 0 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 162401 5606 4013 1897 606 274 163 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2896 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1727 1157 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1660 1215 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 94 72 32 0 28 26 <=256 <=512 <=1024 <=2048 <=4096 > 4096 7 16 22 8 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 666383 585622 361132 42796 227072 133210 <=256 <=512 <=1024 <=2048 <=4096 > 4096 31763 44889 26012 27808 29 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 42 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 42 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 84 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 195014 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 195014 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 390028 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 42 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 42 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 84 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 195014 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 195014 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 390028 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 195056 0 0 0 0 0 20:30:30 done 20:33:40 ****** WARNING entry at 0x817d5b0 already has deqing set! 20:33:48 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:48 ViceValidateAttrs: (1000003.361.177) failed ()! 20:33:48 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:48 ViceValidateAttrs: (1000003.4d1.20e) failed ()! 20:33:48 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:48 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:33:48 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 20:33:48 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:33:48 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:48 ViceValidateAttrs: (1000003.10b1.6cc) failed ()! 20:33:48 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:48 ViceValidateAttrs: (1000003.361.177) failed ()! 20:33:48 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:48 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:48 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:33:48 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 20:33:48 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:33:48 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:48 ViceValidateAttrs: (1000003.10b1.6cc) failed ()! 20:33:48 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:48 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:48 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:48 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:33:48 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 20:33:48 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:33:48 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:48 ViceValidateAttrs: (1000003.10b1.6cc) failed ()! 20:33:49 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:49 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:49 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:49 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:33:49 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:49 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:49 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:49 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:49 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:33:49 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:49 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:49 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:49 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:49 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:49 ViceValidateAttrs: (1000003.11.2) failed ()! 20:33:49 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:49 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:49 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:49 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:49 ViceValidateAttrs: (1000003.371.178) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:33:49 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 20:43:46 ViceValidateAttrs: (1000003.a1.b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.361.177) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:43:46 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:43:46 ViceValidateAttrs: (1000003.10b1.6cc) failed ()! 20:43:46 ViceValidateAttrs: (1000003.1221.7e3) failed ()! 20:43:46 ViceValidateAttrs: (1000003.a1.b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:43:46 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:43:46 ViceValidateAttrs: (1000003.10b1.6cc) failed ()! 20:43:46 ViceValidateAttrs: (1000003.1221.7e3) failed ()! 20:43:46 ViceValidateAttrs: (1000003.a1.b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:43:46 ViceValidateAttrs: (1000003.f11.5d2) failed ()! 20:43:46 ViceValidateAttrs: (1000003.1221.7e3) failed ()! 20:43:46 ViceValidateAttrs: (1000003.a1.b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:43:46 ViceValidateAttrs: (1000003.1221.7e3) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:43:46 ViceValidateAttrs: (1000003.1221.7e3) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5a1.21b) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:43:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 20:54:16 SmonDaemon timer expired 20:54:16 Entered CheckRVMResStat 20:54:16 Starting SmonDaemon timer 21:00:02 VAllocFid: volume disk uniquifier being extended 21:03:53 VAllocFid: volume disk uniquifier being extended 21:03:57 PutReintegrateObjects: stale directory fid 0x7f000015.781.1757, num 0, max 50 21:04:26 Total operations for File Server = 24576 : time = Mon Oct 1 21:04:26 2001 21:04:26 Vice was last started at Sun Sep 30 18:51:56 2001 21:04:26 NewConnectFS 12 21:04:26 DisconnectFS 1 21:04:26 GetAttr 18840 21:04:26 GetAcl 0 21:04:26 Fetch 4295 21:04:26 SetAttr 180 21:04:26 SetAcl 0 21:04:26 Store 288 21:04:26 ValidateAttrs 362 21:04:26 Remove 134 21:04:26 Create 79 21:04:26 Rename 44 21:04:26 SymLink 0 21:04:26 Link 0 21:04:26 MakeDir 13 21:04:26 RemoveDir 60 21:04:26 GetRootVolume 1 21:04:26 SetRootVolume 0 21:04:26 GetVolumeStatus 7 21:04:26 SetVolumeStatus 0 21:04:26 GetTime 1 21:04:26 GetStatistics 0 21:04:26 GetVolumeInfo 16 21:04:26 AllocFids 30 21:04:26 COP2 168 21:04:26 Resolve 0 21:04:26 Repair 0 21:04:26 SetVV 0 21:04:26 Reintegrate 21 21:04:26 OpenReintHandle 0 21:04:26 QueryReintHandle 0 21:04:26 SendReintFragment 0 21:04:26 CloseReintHandle 0 21:04:26 GetVolVS 22 21:04:26 ValidateVols 2 21:04:26 GetWBPermit 0 21:04:26 TossWBPermit 0 21:04:26 RejectWBPermit 0 21:04:26 Total FetchDatas = 4295, bytes transfered = 7944647, transfer rate = 240746 bps 21:04:26 Fetched files <1k = 2660; <8k = 1522; <64k = 102; <512k = 11; >512k = 0. 21:04:26 Total StoreDatas = 288, bytes transfered = 89520, transfer rate = 44760 bps 21:04:26 Stored files <1k = 270; <8k = 14; <64k = 4; <512k = 0; >512k = 0. 21:04:26 Large vnode cache, 500 entries, 195 allocs, 26362 gets (1519 reads), 1502 writes 21:04:26 Small vnode cache,500 entries, 449 allocs, 23501 gets (18007 reads), 2589 writes 21:04:26 Volume header cache, 50 entries, 51287 gets, 0 replacements 21:04:26 Partition /vicepa: 727892K available (minfree=5%), 515508K free. 21:04:26 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 21:04:26 RPC Total bytes: sent = 12474461, received = 3999126 21:04:26 bytes sent: rpc = 4255845, multirpc = 0, sftp = 8218616, sftp multicasted = 0 21:04:26 bytes received: rpc = 3999126, multirpc = 0, sftp = 0, sftp multicasted = 0 21:04:26 RPC Total packets: sent = 33741, received = 28906 21:04:26 packets sent: rpc = 25771, multirpc = 0, sftp = 7970, sftp multicasted = 0 21:04:26 packets received: rpc = 28906, multirpc = 0, sftp = 0, sftp multicasted = 0 21:04:26 RPC Packets retried = 11, Invalid packets received = 3214, Busies sent = 12 21:04:26 RPC Requests 24646, Good Requests 24576, Replies 1115, Busies 1 21:04:26 RPC Counters: CCount 15; Unbinds 2; FConns 2; AConns 17; GCConns 0 21:04:26 RPC Creation counts: Conn 15; SL 17; PB Small 8, Med 9, Large 21; SS 4 21:04:26 RPC2 In Use: Conn 15; SS 4 21:04:26 RPC2 PB: InUse 6, Hold 0, Freeze 0, SFree 6, MFree 8, LFree 18 21:04:26 RPC2 HW: Freeze 0, Hold 0 21:04:26 SFTP: datas 7767, datar 604, acks 163, ackr 2139, retries 0, duplicates 0 21:04:26 SFTP: timeouts 64, windowfulls 0, bogus 398, didpiggy 3272 21:04:26 Total CB entries= 14577, blocks = 15; and total file entries = 13908, blocks = 64 21:04:26 There are currently 11 connections in use 21:04:26 There are 2 workstations and 2 are active (req in < 15 mins) 21:04:26 Ether Total bytes: sent = 0, received = 0 21:04:26 Ether Packets: sent = 0, received = 0, errors = 0 21:04:26 Printing RVM statistics Transaction statistics current cumulative Started: 220 198631 Aborted: 0 1 Committed, flush: 200 178287 Committed, no_flush: 20 20343 Total committed: 220 198630 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 142 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 200 178281 Total flushes: 200 178299 Average flush time (msec): 5 13 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 2961 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2968 Average truncation time (sec): 2 Last truncation time (sec): 6 Last tree build time (sec): 0 Last tree apply time (sec): 6 Transaction records: 220 198772 Bookeeping records: 1 2969 Log wrap-arounds: 0 152 Total records: 221 201893 Log used: 12% 32% Log written (bytes): 266544 320059440 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 200 0 1 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 165469 5622 4029 1903 610 277 163 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2933 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1738 1183 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1671 1241 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 756 675 360 126 254 74 <=256 <=512 <=1024 <=2048 <=4096 > 4096 18 40 20 23 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 681938 597531 367545 43612 231172 135620 <=256 <=512 <=1024 <=2048 <=4096 > 4096 31928 45541 26379 28175 29 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 221 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 221 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 442 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 198395 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 198395 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 396790 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 221 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 221 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 442 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 198395 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 198395 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 396790 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 198616 0 0 0 0 0 21:04:26 done 21:05:05 RevokeWBPermit on conn 1c174c3d returned 0 21:08:23 VAllocFid: volume disk uniquifier being extended 21:13:46 ViceValidateAttrs: (1000003.11.2) failed ()! 21:13:46 ViceValidateAttrs: (1000003.371.178) failed ()! 21:13:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 21:13:46 ViceValidateAttrs: (1000003.ee1.5cf) failed ()! 21:13:46 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 21:13:46 ViceValidateAttrs: (1000003.11.2) failed ()! 21:13:46 ViceValidateAttrs: (1000003.371.178) failed ()! 21:13:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 21:13:46 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 21:13:46 ViceValidateAttrs: (1000003.371.178) failed ()! 21:13:46 ViceValidateAttrs: (1000003.5b1.21c) failed ()! 21:13:46 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 21:13:46 ViceValidateAttrs: (1000003.371.178) failed ()! 21:13:46 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 21:13:46 ViceValidateAttrs: (1000003.f21.5d3) failed ()! 21:15:04 VAllocFid: volume disk uniquifier being extended 21:16:42 RevokeWBPermit on conn 3ead5e63 returned 0 21:19:34 RevokeWBPermit on conn 1c174c3d returned 0 21:21:16 VAllocFid: volume disk uniquifier being extended 21:29:34 ViceValidateAttrs: (1000003.361.177) failed ()! 21:29:34 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 21:29:34 ViceValidateAttrs: (1000003.78a2.8ec) failed ()! 21:29:34 ViceValidateAttrs: (1000003.7c02.922) failed ()! 21:29:34 ViceValidateAttrs: (1000003.8282.98a) failed ()! 21:29:34 VGetVnode: vnode 1000003.8a72 is not allocated 21:29:34 ViceValidateAttrs: (1000003.8a72.a09) failed (GetFsObj 1)! 21:29:34 ViceValidateAttrs: (1000003.a582.15e7) failed (GetFsObj 1)! 21:29:34 VGetVnode: vnode 1000003.a672 is not allocated 21:29:34 ViceValidateAttrs: (1000003.a672.15f6) failed (GetFsObj 1)! 21:29:34 VGetVnode: vnode 1000003.f0a2 is not allocated 21:29:34 ViceValidateAttrs: (1000003.f0a2.1fae) failed (GetFsObj 1)! 21:29:34 ViceValidateAttrs: (1000003.361.177) failed ()! 21:29:34 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 21:29:34 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 21:30:04 VAllocFid: volume disk uniquifier being extended 21:30:43 GrowVnodes: growing Small list from 1024 to 1280 for volume 0x1000001 21:39:43 ViceValidateAttrs: (1000003.361.177) failed ()! 21:39:43 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 21:39:43 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 21:54:27 SmonDaemon timer expired 21:54:27 Entered CheckRVMResStat 21:54:27 Starting SmonDaemon timer 21:59:48 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 21:59:48 VGetVnode: vnode 1000003.8aa2 is not allocated 21:59:48 ViceValidateAttrs: (1000003.8aa2.a0c) failed (GetFsObj 1)! 21:59:48 VGetVnode: vnode 1000003.8aa2 is not allocated 22:00:05 Total operations for File Server = 28672 : time = Mon Oct 1 22:00:05 2001 22:00:05 Vice was last started at Sun Sep 30 18:51:56 2001 22:00:05 NewConnectFS 15 22:00:05 DisconnectFS 1 22:00:05 GetAttr 21830 22:00:05 GetAcl 0 22:00:05 Fetch 4870 22:00:05 SetAttr 190 22:00:05 SetAcl 0 22:00:05 Store 346 22:00:05 ValidateAttrs 598 22:00:05 Remove 167 22:00:05 Create 133 22:00:05 Rename 72 22:00:05 SymLink 11 22:00:05 Link 0 22:00:05 MakeDir 19 22:00:05 RemoveDir 60 22:00:05 GetRootVolume 1 22:00:05 SetRootVolume 0 22:00:05 GetVolumeStatus 7 22:00:05 SetVolumeStatus 0 22:00:05 GetTime 1 22:00:05 GetStatistics 0 22:00:05 GetVolumeInfo 16 22:00:05 AllocFids 38 22:00:05 COP2 233 22:00:05 Resolve 0 22:00:05 Repair 0 22:00:05 SetVV 0 22:00:05 Reintegrate 29 22:00:05 OpenReintHandle 0 22:00:05 QueryReintHandle 0 22:00:05 SendReintFragment 0 22:00:05 CloseReintHandle 0 22:00:05 GetVolVS 33 22:00:05 ValidateVols 2 22:00:05 GetWBPermit 0 22:00:05 TossWBPermit 0 22:00:05 RejectWBPermit 0 22:00:05 Total FetchDatas = 4870, bytes transfered = 12105117, transfer rate = 302627 bps 22:00:05 Fetched files <1k = 3097; <8k = 1630; <64k = 119; <512k = 23; >512k = 1. 22:00:05 Total StoreDatas = 346, bytes transfered = 5755297, transfer rate = 112848 bps 22:00:05 Stored files <1k = 288; <8k = 23; <64k = 13; <512k = 21; >512k = 1. 22:00:05 Large vnode cache, 500 entries, 219 allocs, 31098 gets (1778 reads), 1988 writes 22:00:05 Small vnode cache,500 entries, 566 allocs, 27518 gets (20753 reads), 3317 writes 22:00:05 Volume header cache, 50 entries, 59755 gets, 0 replacements 22:00:05 Partition /vicepa: 727892K available (minfree=5%), 514223K free. 22:00:05 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 22:00:05 RPC Total bytes: sent = 17626991, received = 11606898 22:00:05 bytes sent: rpc = 4964619, multirpc = 0, sftp = 12662372, sftp multicasted = 0 22:00:05 bytes received: rpc = 11606898, multirpc = 0, sftp = 0, sftp multicasted = 0 22:00:05 RPC Total packets: sent = 43133, received = 40281 22:00:05 packets sent: rpc = 29989, multirpc = 0, sftp = 13144, sftp multicasted = 0 22:00:05 packets received: rpc = 40281, multirpc = 0, sftp = 0, sftp multicasted = 0 22:00:05 RPC Packets retried = 11, Invalid packets received = 10385, Busies sent = 15 22:00:05 RPC Requests 28755, Good Requests 28672, Replies 1223, Busies 1 22:00:05 RPC Counters: CCount 18; Unbinds 3; FConns 3; AConns 21; GCConns 0 22:00:05 RPC Creation counts: Conn 18; SL 17; PB Small 8, Med 16, Large 32; SS 4 22:00:05 RPC2 In Use: Conn 18; SS 4 22:00:05 RPC2 PB: InUse 3, Hold 0, Freeze 0, SFree 5, MFree 16, LFree 32 22:00:05 RPC2 HW: Freeze 0, Hold 0 22:00:05 SFTP: datas 11836, datar 6432, acks 1218, ackr 2741, retries 1, duplicates 0 22:00:05 SFTP: timeouts 230, windowfulls 0, bogus 1126, didpiggy 3770 22:00:05 Total CB entries= 15026, blocks = 15; and total file entries = 14343, blocks = 66 22:00:05 There are currently 14 connections in use 22:00:05 There are 2 workstations and 2 are active (req in < 15 mins) 22:00:05 Ether Total bytes: sent = 0, received = 0 22:00:05 Ether Packets: sent = 0, received = 0, errors = 0 22:00:05 Printing RVM statistics Transaction statistics current cumulative Started: 29 200005 Aborted: 0 1 Committed, flush: 25 179476 Committed, no_flush: 4 20528 Total committed: 29 200004 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 143 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 25 179470 Total flushes: 25 179488 Average flush time (msec): 0 13 Last flush time (msec): 4 rvm_truncate calls: 2 Automatic truncations: 2991 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 2998 Average truncation time (sec): 2 Last truncation time (sec): 1 Last tree build time (sec): 0 Last tree apply time (sec): 1 Transaction records: 29 200147 Bookeeping records: 1 2999 Log wrap-arounds: 0 153 Total records: 30 203299 Log used: 4% 32% Log written (bytes): 95216 323599872 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 26 0 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 166815 5627 4035 1908 612 277 163 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2963 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1758 1193 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1691 1251 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 46 36 16 4 12 16 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 8 16 32 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 690254 603827 370776 43984 233373 136722 <=256 <=512 <=1024 <=2048 <=4096 > 4096 32036 46059 26652 28821 30 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 30 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 30 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 60 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 199960 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 199960 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 399920 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 30 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 30 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 60 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 199960 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 199960 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 399920 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 199990 0 0 0 0 0 22:00:05 done 22:00:59 VAllocFid: volume disk uniquifier being extended 22:02:46 ViceValidateAttrs: (1000003.361.177) failed ()! 22:02:46 ViceValidateAttrs: (1000003.25a2.2bc) failed ()! 22:02:51 Total operations for File Server = 32768 : time = Mon Oct 1 22:02:51 2001 22:02:51 Vice was last started at Sun Sep 30 18:51:56 2001 22:02:51 NewConnectFS 15 22:02:51 DisconnectFS 1 22:02:51 GetAttr 25002 22:02:51 GetAcl 0 22:02:51 Fetch 5571 22:02:51 SetAttr 190 22:02:51 SetAcl 0 22:02:51 Store 352 22:02:51 ValidateAttrs 797 22:02:51 Remove 172 22:02:51 Create 139 22:02:51 Rename 77 22:02:51 SymLink 11 22:02:51 Link 0 22:02:51 MakeDir 19 22:02:51 RemoveDir 60 22:02:51 GetRootVolume 1 22:02:51 SetRootVolume 0 22:02:51 GetVolumeStatus 7 22:02:51 SetVolumeStatus 0 22:02:51 GetTime 1 22:02:51 GetStatistics 0 22:02:51 GetVolumeInfo 16 22:02:51 AllocFids 39 22:02:51 COP2 234 22:02:51 Resolve 0 22:02:51 Repair 0 22:02:51 SetVV 0 22:02:51 Reintegrate 29 22:02:51 OpenReintHandle 0 22:02:51 QueryReintHandle 0 22:02:51 SendReintFragment 0 22:02:51 CloseReintHandle 0 22:02:51 GetVolVS 33 22:02:51 ValidateVols 2 22:02:51 GetWBPermit 0 22:02:51 TossWBPermit 0 22:02:51 RejectWBPermit 0 22:02:51 Total FetchDatas = 5571, bytes transfered = 13317921, transfer rate = 277456 bps 22:02:51 Fetched files <1k = 3623; <8k = 1793; <64k = 128; <512k = 26; >512k = 1. 22:02:51 Total StoreDatas = 352, bytes transfered = 5889310, transfer rate = 105166 bps 22:02:51 Stored files <1k = 291; <8k = 24; <64k = 14; <512k = 22; >512k = 1. 22:02:51 Large vnode cache, 500 entries, 219 allocs, 35208 gets (2054 reads), 2019 writes 22:02:51 Small vnode cache,500 entries, 572 allocs, 31212 gets (23823 reads), 3360 writes 22:02:51 Volume header cache, 50 entries, 67973 gets, 0 replacements 22:02:51 Partition /vicepa: 727892K available (minfree=5%), 514219K free. 22:02:51 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 22:02:51 RPC Total bytes: sent = 19573627, received = 12212007 22:02:51 bytes sent: rpc = 5682740, multirpc = 0, sftp = 13890887, sftp multicasted = 0 22:02:51 bytes received: rpc = 12212007, multirpc = 0, sftp = 0, sftp multicasted = 0 22:02:51 RPC Total packets: sent = 48452, received = 44822 22:02:51 packets sent: rpc = 34093, multirpc = 0, sftp = 14359, sftp multicasted = 0 22:02:51 packets received: rpc = 44822, multirpc = 0, sftp = 0, sftp multicasted = 0 22:02:51 RPC Packets retried = 11, Invalid packets received = 10824, Busies sent = 15 22:02:51 RPC Requests 32853, Good Requests 32768, Replies 1229, Busies 1 22:02:51 RPC Counters: CCount 18; Unbinds 3; FConns 3; AConns 21; GCConns 0 22:02:51 RPC Creation counts: Conn 18; SL 17; PB Small 8, Med 16, Large 32; SS 4 22:02:51 RPC2 In Use: Conn 18; SS 4 22:02:51 RPC2 PB: InUse 1, Hold 0, Freeze 0, SFree 7, MFree 16, LFree 32 22:02:51 RPC2 HW: Freeze 0, Hold 0 22:02:51 SFTP: datas 12997, datar 6564, acks 1269, ackr 3010, retries 1, duplicates 0 22:02:51 SFTP: timeouts 239, windowfulls 0, bogus 1162, didpiggy 4294 22:02:51 Total CB entries= 15027, blocks = 15; and total file entries = 14348, blocks = 66 22:02:51 There are currently 14 connections in use 22:02:51 There are 2 workstations and 2 are active (req in < 15 mins) 22:02:51 Ether Total bytes: sent = 0, received = 0 22:02:51 Ether Packets: sent = 0, received = 0, errors = 0 22:02:51 Printing RVM statistics Transaction statistics current cumulative Started: 1 200126 Aborted: 0 1 Committed, flush: 1 179582 Committed, no_flush: 0 20543 Total committed: 1 200125 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 144 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 1 179576 Total flushes: 1 179594 Average flush time (msec): 0 13 Last flush time (msec): 7 rvm_truncate calls: 2 Automatic truncations: 2994 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 3001 Average truncation time (sec): 2 Last truncation time (sec): 1 Last tree build time (sec): 0 Last tree apply time (sec): 1 Transaction records: 1 200269 Bookeeping records: 1 3002 Log wrap-arounds: 0 154 Total records: 2 203425 Log used: 1% 32% Log written (bytes): 22596 323894644 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 2 0 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 166942 5628 4035 1908 613 277 164 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2966 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1760 1194 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1693 1252 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 8 5 2 0 2 3 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 1 2 9 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 690469 603998 370852 43990 233440 136788 <=256 <=512 <=1024 <=2048 <=4096 > 4096 32046 46099 26744 28892 30 682 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 2 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 2 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 4 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 200109 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 200109 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 400218 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 2 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 2 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 4 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 200109 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 200109 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 400218 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 200111 0 0 0 0 0 22:02:51 done 22:04:16 ViceValidateAttrs: (1000003.371.178) failed ()! 22:04:16 VGetVnode: vnode 1000003.3b42 is not allocated 22:04:16 ViceValidateAttrs: (1000003.3b42.24fd) failed (GetFsObj 1)! 22:04:16 VGetVnode: vnode 1000003.3b42 is not allocated 22:04:16 ViceValidateAttrs: (1000003.371.178) failed ()! 22:09:53 ViceValidateAttrs: (1000003.a992.1648) failed ()! 22:30:04 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 22:30:04 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 22:30:23 VAllocFid: volume disk uniquifier being extended 22:30:23 GrowVnodes: growing Small list from 14592 to 14848 for volume 0x1000009 22:40:12 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 22:40:12 ViceValidateAttrs: (1000003.1491.8aa) failed ()! 22:40:12 VGetVnode: vnode 1000003.50d2 is not allocated 22:40:12 ViceValidateAttrs: (1000003.50d2.60f) failed (GetFsObj 1)! 22:40:12 ViceValidateAttrs: (1000003.ed1.5ce) failed ()! 22:40:12 VGetVnode: vnode 1000003.50d2 is not allocated 22:54:35 SmonDaemon timer expired 22:54:35 Entered CheckRVMResStat 22:54:35 Starting SmonDaemon timer 23:54:35 SmonDaemon timer expired 23:54:35 Entered CheckRVMResStat 23:54:35 Starting SmonDaemon timer Date: Tue 10/02/2001 00:04:33 Worker1: Unbinding RPC connection 121940916 00:04:33 Worker5: Unbinding RPC connection 445360775 00:04:49 Worker2: Unbinding RPC connection 95127814 00:54:35 SmonDaemon timer expired 00:54:35 Entered CheckRVMResStat 00:54:35 Starting SmonDaemon timer 01:54:36 SmonDaemon timer expired 01:54:36 Entered CheckRVMResStat 01:54:36 Starting SmonDaemon timer 02:54:36 SmonDaemon timer expired 02:54:36 Entered CheckRVMResStat 02:54:36 Starting SmonDaemon timer 03:54:36 SmonDaemon timer expired 03:54:36 Entered CheckRVMResStat 03:54:36 Starting SmonDaemon timer 04:55:06 SmonDaemon timer expired 04:55:06 Entered CheckRVMResStat 04:55:06 Starting SmonDaemon timer 05:55:06 SmonDaemon timer expired 05:55:06 Entered CheckRVMResStat 05:55:06 Starting SmonDaemon timer 06:55:07 SmonDaemon timer expired 06:55:07 Entered CheckRVMResStat 06:55:07 Starting SmonDaemon timer 07:55:07 SmonDaemon timer expired 07:55:07 Entered CheckRVMResStat 07:55:07 Starting SmonDaemon timer 08:55:07 SmonDaemon timer expired 08:55:07 Entered CheckRVMResStat 08:55:07 Starting SmonDaemon timer 09:45:35 RevokeWBPermit on conn 1c174c3d returned 0 09:49:28 RevokeWBPermit on conn 3ead5e63 returned 0 09:49:30 RevokeWBPermit on conn 3ead5e63 returned 0 09:55:07 SmonDaemon timer expired 09:55:07 Entered CheckRVMResStat 09:55:07 Starting SmonDaemon timer 10:14:34 ViceValidateAttrs: (1000003.3ac2.24f5) failed ()! 10:14:34 VGetVnode: vnode 1000003.a5b2 is not allocated 10:14:34 ViceValidateAttrs: (1000003.a5b2.15ea) failed (GetFsObj 1)! 10:14:35 ViceValidateAttrs: (1000006.2b1.1ac) failed ()! 10:14:35 VGetVnode: vnode 1000006.3b92 is not allocated 10:14:35 ViceValidateAttrs: (1000006.3b92.772) failed (GetFsObj 1)! 10:14:35 VGetVnode: vnode 1000006.3ba2 is not allocated 10:14:35 ViceValidateAttrs: (1000006.3ba2.773) failed (GetFsObj 1)! 10:14:35 VGetVnode: vnode 1000006.4942 is not allocated 10:14:35 ViceValidateAttrs: (1000006.4942.665) failed (GetFsObj 1)! 10:14:35 VGetVnode: vnode 1000006.4942 is not allocated 10:14:35 ViceValidateAttrs: (1000006.2b1.1ac) failed ()! 10:38:04 Callback failed RPC2_DEAD (F) for ws 130.158.99.4:3056 10:38:04 Unbinding RPC2 connection 702674443 10:38:04 Unbinding RPC2 connection 753930991 10:38:04 Unbinding RPC2 connection 158248630 10:38:04 Unbinding RPC2 connection 871287507 10:38:04 Unbinding RPC2 connection 714420878 10:38:04 Unbinding RPC2 connection 193267852 10:38:04 Unbinding RPC2 connection 1017671975 10:38:04 Unbinding RPC2 connection 400133795 10:45:41 client_GetVenusId: got new host 130.158.99.4:3056 10:45:41 Building callback conn. 10:45:41 No idle WriteBack conns, building new one 10:45:41 Writeback message to 130.158.99.4 port 3056 on conn 31c61224 succeeded 10:45:41 ValidateVolumes: 0x7f000015 failed! 10:45:41 ValidateVolumes: 0x7f000016 failed! 10:45:41 ValidateVolumes: 0x7f000017 failed! 10:45:41 ValidateVolumes: 0x7f000018 failed! 10:45:41 ValidateVolumes: 0x7f000019 failed! 10:45:41 ValidateVolumes: 0x7f000022 failed! 10:45:41 ValidateVolumes: 0x7f000023 failed! 10:54:35 RevokeWBPermit on conn 31c61224 returned 0 10:54:36 RevokeWBPermit on conn 31c61224 returned 0 10:55:07 SmonDaemon timer expired 10:55:07 Entered CheckRVMResStat 10:55:07 Starting SmonDaemon timer 11:13:15 Callback failed RPC2_DEAD (F) for ws 130.158.99.4:3056 11:13:15 Unbinding RPC2 connection 342851578 11:13:15 Unbinding RPC2 connection 304278452 11:13:15 Unbinding RPC2 connection 141225828 11:20:35 client_GetVenusId: got new host 130.158.99.4:3056 11:20:35 Building callback conn. 11:20:35 No idle WriteBack conns, building new one 11:20:35 Writeback message to 130.158.99.4 port 3056 on conn e1371ab succeeded 11:20:40 RevokeWBPermit on conn e1371ab returned 0 11:20:40 ValidateVolumes: 0x7f000015 failed! 11:20:40 ValidateVolumes: 0x7f000016 failed! 11:20:40 ValidateVolumes: 0x7f000017 failed! 11:20:40 ValidateVolumes: 0x7f000018 failed! 11:20:40 ValidateVolumes: 0x7f000019 failed! 11:20:40 ValidateVolumes: 0x7f000022 failed! 11:20:40 ValidateVolumes: 0x7f000023 failed! 11:24:35 RevokeWBPermit on conn e1371ab returned 0 11:24:35 RevokeWBPermit on conn e1371ab returned 0 11:27:53 Unbinding RPC2 connection 742817887 11:27:53 Unbinding RPC2 connection 898059468 11:27:53 Unbinding RPC2 connection 231094428 11:27:53 Unbinding RPC2 connection 1058209443 11:36:35 client_GetVenusId: got new host 130.158.99.4:3056 11:36:35 Building callback conn. 11:36:35 No idle WriteBack conns, building new one 11:36:35 Writeback message to 130.158.99.4 port 3056 on conn 35e03726 succeeded 11:36:35 ValidateVolumes: 0x7f000015 failed! 11:36:35 ValidateVolumes: 0x7f000016 failed! 11:36:35 ValidateVolumes: 0x7f000017 failed! 11:36:35 ValidateVolumes: 0x7f000018 failed! 11:36:35 ValidateVolumes: 0x7f000019 failed! 11:36:35 ValidateVolumes: 0x7f000022 failed! 11:36:35 ValidateVolumes: 0x7f000023 failed! 11:44:35 RevokeWBPermit on conn 35e03726 returned 0 11:44:35 RevokeWBPermit on conn 35e03726 returned 0 11:55:35 SmonDaemon timer expired 11:55:35 Entered CheckRVMResStat 11:55:35 Starting SmonDaemon timer 12:03:50 Callback failed RPC2_DEAD (F) for ws 130.158.99.4:3056 12:03:50 Unbinding RPC2 connection 531222084 12:03:50 Unbinding RPC2 connection 431099305 12:03:50 Unbinding RPC2 connection 692739488 12:11:07 client_GetVenusId: got new host 130.158.99.4:3056 12:11:07 Building callback conn. 12:11:23 Callback message to 130.158.99.4 port 3056 failed RPC2_DEAD (F) 12:11:23 Worker3: Unbinding RPC connection 380599360 12:11:23 client_GetVenusId: got new host 130.158.99.4:3056 12:11:23 Building callback conn. 12:11:23 No idle WriteBack conns, building new one 12:11:23 Writeback message to 130.158.99.4 port 3056 on conn 1c49780 succeeded 12:11:28 RevokeWBPermit on conn 1c49780 returned 0 12:11:29 ValidateVolumes: 0x7f000015 failed! 12:11:29 ValidateVolumes: 0x7f000016 failed! 12:11:29 ValidateVolumes: 0x7f000017 failed! 12:11:29 ValidateVolumes: 0x7f000018 failed! 12:11:29 ValidateVolumes: 0x7f000019 failed! 12:11:29 ValidateVolumes: 0x7f000022 failed! 12:11:29 ValidateVolumes: 0x7f000023 failed! 12:14:35 RevokeWBPermit on conn 1c49780 returned 0 12:14:35 RevokeWBPermit on conn 1c49780 returned 0 12:55:35 SmonDaemon timer expired 12:55:35 Entered CheckRVMResStat 12:55:35 Starting SmonDaemon timer 13:55:41 SmonDaemon timer expired 13:55:41 Entered CheckRVMResStat 13:55:41 Starting SmonDaemon timer 14:02:56 Callback failed RPC2_DEAD (F) for ws 130.158.99.4:3056 14:02:56 Unbinding RPC2 connection 775570378 14:02:56 Unbinding RPC2 connection 732999161 14:02:56 Unbinding RPC2 connection 89790096 14:02:56 Unbinding RPC2 connection 33313814 14:10:30 client_GetVenusId: got new host 130.158.99.4:3056 14:10:30 Building callback conn. 14:10:30 No idle WriteBack conns, building new one 14:10:30 Writeback message to 130.158.99.4 port 3056 on conn 3c8b4909 succeeded 14:10:30 ValidateVolumes: 0x7f000015 failed! 14:10:30 ValidateVolumes: 0x7f000016 failed! 14:10:30 ValidateVolumes: 0x7f000017 failed! 14:10:30 ValidateVolumes: 0x7f000018 failed! 14:10:30 ValidateVolumes: 0x7f000019 failed! 14:10:30 ValidateVolumes: 0x7f000022 failed! 14:10:30 ValidateVolumes: 0x7f000023 failed! 14:14:35 RevokeWBPermit on conn 3c8b4909 returned 0 14:14:35 RevokeWBPermit on conn 3c8b4909 returned 0 14:56:05 SmonDaemon timer expired 14:56:05 Entered CheckRVMResStat 14:56:05 Starting SmonDaemon timer 15:09:50 Callback failed RPC2_DEAD (F) for ws 130.158.99.4:3056 15:09:50 Unbinding RPC2 connection 956591862 15:09:50 Unbinding RPC2 connection 502402496 15:09:50 Unbinding RPC2 connection 772061680 15:17:10 client_GetVenusId: got new host 130.158.99.4:3056 15:17:10 Building callback conn. 15:17:10 No idle WriteBack conns, building new one 15:17:10 Writeback message to 130.158.99.4 port 3056 on conn 36aad42e succeeded 15:17:20 RevokeWBPermit on conn 36aad42e returned 0 15:17:20 ValidateVolumes: 0x7f000015 failed! 15:17:20 ValidateVolumes: 0x7f000016 failed! 15:17:20 ValidateVolumes: 0x7f000017 failed! 15:17:20 ValidateVolumes: 0x7f000018 failed! 15:17:20 ValidateVolumes: 0x7f000019 failed! 15:17:20 ValidateVolumes: 0x7f000022 failed! 15:17:20 ValidateVolumes: 0x7f000023 failed! 15:24:35 RevokeWBPermit on conn 36aad42e returned 0 15:24:35 RevokeWBPermit on conn 36aad42e returned 0 15:56:10 SmonDaemon timer expired 15:56:10 Entered CheckRVMResStat 15:56:10 Starting SmonDaemon timer 16:56:11 SmonDaemon timer expired 16:56:11 Entered CheckRVMResStat 16:56:11 Starting SmonDaemon timer 17:56:11 SmonDaemon timer expired 17:56:11 Entered CheckRVMResStat 17:56:11 Starting SmonDaemon timer 18:52:45 Worker3: Unbinding RPC connection 108946159 18:56:11 SmonDaemon timer expired 18:56:11 Entered CheckRVMResStat 18:56:11 Starting SmonDaemon timer 19:12:41 RevokeWBPermit on conn 1c174c3d returned 0 19:56:23 SmonDaemon timer expired 19:56:23 Entered CheckRVMResStat 19:56:23 Starting SmonDaemon timer 20:00:43 Total operations for File Server = 36864 : time = Tue Oct 2 20:00:43 2001 20:00:43 Vice was last started at Sun Sep 30 18:51:56 2001 20:00:43 NewConnectFS 41 20:00:43 DisconnectFS 5 20:00:43 GetAttr 27793 20:00:43 GetAcl 0 20:00:43 Fetch 6122 20:00:43 SetAttr 206 20:00:43 SetAcl 0 20:00:43 Store 458 20:00:43 ValidateAttrs 1033 20:00:43 Remove 221 20:00:43 Create 233 20:00:43 Rename 125 20:00:43 SymLink 25 20:00:43 Link 0 20:00:43 MakeDir 25 20:00:43 RemoveDir 60 20:00:43 GetRootVolume 1 20:00:43 SetRootVolume 0 20:00:43 GetVolumeStatus 8 20:00:43 SetVolumeStatus 0 20:00:43 GetTime 5 20:00:43 GetStatistics 0 20:00:43 GetVolumeInfo 24 20:00:43 AllocFids 49 20:00:43 COP2 297 20:00:43 Resolve 0 20:00:43 Repair 0 20:00:43 SetVV 0 20:00:43 Reintegrate 35 20:00:43 OpenReintHandle 0 20:00:43 QueryReintHandle 0 20:00:43 SendReintFragment 0 20:00:43 CloseReintHandle 0 20:00:43 GetVolVS 90 20:00:43 ValidateVols 8 20:00:43 GetWBPermit 0 20:00:43 TossWBPermit 0 20:00:43 RejectWBPermit 0 20:00:43 Total FetchDatas = 6122, bytes transfered = 16020637, transfer rate = 291284 bps 20:00:43 Fetched files <1k = 3925; <8k = 2004; <64k = 157; <512k = 35; >512k = 1. 20:00:43 Total StoreDatas = 458, bytes transfered = 11939920, transfer rate = 77031 bps 20:00:43 Stored files <1k = 340; <8k = 47; <64k = 29; <512k = 39; >512k = 3. 20:00:43 Large vnode cache, 500 entries, 237 allocs, 40388 gets (2263 reads), 2584 writes 20:00:43 Small vnode cache,500 entries, 738 allocs, 38924 gets (30397 reads), 4206 writes 20:00:43 Volume header cache, 50 entries, 80997 gets, 0 replacements 20:00:43 Partition /vicepa: 727892K available (minfree=5%), 513903K free. 20:00:43 Partition /vicepb: 930512K available (minfree=5%), 643444K free. Dirstats: get 0, put 0, flush 0 20:00:43 RPC Total bytes: sent = 23269894, received = 21883891 20:00:43 bytes sent: rpc = 6490543, multirpc = 0, sftp = 16779351, sftp multicasted = 0 20:00:43 bytes received: rpc = 21883891, multirpc = 0, sftp = 0, sftp multicasted = 0 20:00:43 RPC Total packets: sent = 57483, received = 58434 20:00:43 packets sent: rpc = 39136, multirpc = 0, sftp = 18347, sftp multicasted = 0 20:00:43 packets received: rpc = 58434, multirpc = 0, sftp = 0, sftp multicasted = 0 20:00:43 RPC Packets retried = 175, Invalid packets received = 19658, Busies sent = 25 20:00:43 RPC Requests 37040, Good Requests 36864, Replies 1911, Busies 1 20:00:43 RPC Counters: CCount 20; Unbinds 40; FConns 40; AConns 60; GCConns 0 20:00:43 RPC Creation counts: Conn 20; SL 17; PB Small 8, Med 16, Large 32; SS 4 20:00:43 RPC2 In Use: Conn 20; SS 4 20:00:43 RPC2 PB: InUse 1, Hold 0, Freeze 0, SFree 7, MFree 16, LFree 32 20:00:43 RPC2 HW: Freeze 0, Hold 0 20:00:43 SFTP: datas 15641, datar 12733, acks 2549, ackr 3511, retries 1, duplicates 0 20:00:43 SFTP: timeouts 561, windowfulls 0, bogus 3232, didpiggy 4681 20:00:43 Total CB entries= 15079, blocks = 15; and total file entries = 14439, blocks = 67 20:00:43 There are currently 10 connections in use 20:00:43 There are 2 workstations and 1 are active (req in < 15 mins) 20:00:43 Ether Total bytes: sent = 0, received = 0 20:00:43 Ether Packets: sent = 0, received = 0, errors = 0 20:00:43 Printing RVM statistics Transaction statistics current cumulative Started: 47 201541 Aborted: 0 1 Committed, flush: 36 180729 Committed, no_flush: 11 20811 Total committed: 47 201540 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 145 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 18 Internal flushes, incl. commit: 36 180723 Total flushes: 36 180741 Average flush time (msec): 27 13 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 3024 Forced synch. truncations: 0 Recovery truncations: 5 Total truncations: 3031 Average truncation time (sec): 2 Last truncation time (sec): 2 Last tree build time (sec): 0 Last tree apply time (sec): 2 Transaction records: 47 201685 Bookeeping records: 1 3032 Log wrap-arounds: 0 155 Total records: 48 204872 Log used: 3% 32% Log written (bytes): 80032 326786516 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 33 1 1 1 1 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 167972 5667 4074 1911 614 277 164 20 4 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 2996 36 1 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 1776 1208 49 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 1709 1266 58 0 0 Transaction Optimization Statistics current cumulative Ranges eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Avg. number eliminated per transaction Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Range length eliminated Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Log savings Range coalesce: 0 0 Trans coalesce: 0 0 Totals: 0 0 Transactions coalesced per flush: 0 0 Tranasction Modification Range Distributions Current Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 129 99 44 0 39 37 <=256 <=512 <=1024 <=2048 <=4096 > 4096 8 22 19 11 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 695072 607761 372632 44222 234812 137820 <=256 <=512 <=1024 <=2048 <=4096 > 4096 32220 46644 27125 29448 30 683 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 48 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 48 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 96 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Cumulative Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 201478 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Trans coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 201478 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Totals: <=0 <=4 <=8 <=16 <=32 <=64 <=128 402956 0 0 0 0 0 0 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 0 0 0 0 0 Current Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 48 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 48 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 96 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 201478 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 201478 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 402956 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 201526 0 0 0 0 0 20:00:43 done 20:03:04 RevokeWBPermit on conn 1c174c3d returned 0 20:03:22 ****** FILE SERVER INTERRUPTED BY SIGNAL 11 ****** 20:03:22 ****** Aborting outstanding transactions, stand by... 20:03:22 Uncommitted transactions: 0 20:03:22 Uncommitted transactions: 0 20:03:22 Becoming a zombie now ........ 20:03:22 You may use gdb to attach to 30784 Date: Wed 10/03/2001 11:35:36 Starting new SrvLog file