Date: Mon 05/31/2004 15:28:01 New SrvLog started at Mon May 31 15:28:01 2004 15:28:01 Resource limit on data size are set to -1 15:28:01 RvmType is Rvm 15:28:01 Main process doing a LWP_Init() 15:28:01 Main thread just did a RVM_SET_THREAD_DATA 15:28:01 Setting Rvm Truncate threshhold to 5. Partition /vicepa: inodes in use: 7, total: 16777216. 15:28:15 Partition /vicepa: 32298296K available (minfree=5%), 29797016K free. 15:28:15 The server (pid 588) can be controlled using volutil commands 15:28:15 "volutil -help" will give you a list of these commands 15:28:15 If desperate, "kill -SIGWINCH 588" will increase debugging level 15:28:15 "kill -SIGUSR2 588" will set debugging level to zero 15:28:15 "kill -9 588" will kill a runaway server 15:28:15 Vice file system salvager, version 3.0. 15:28:15 SanityCheckFreeLists: Checking RVM Vnode Free lists. 15:28:15 DestroyBadVolumes: Checking for destroyed volumes. 15:28:15 Salvaging file system partition /vicepa 15:28:15 Force salvage of all volumes on this partition 15:28:15 Scanning inodes in directory /vicepa... 15:28:15 SFS: There are some volumes without any inodes in them 15:28:15 Entering DCC(0x1000003) 15:28:15 done: 14 files/dirs, 7584 blocks 15:28:15 SalvageFileSys: unclaimed volume header file or no Inodes in volume 1000004 15:28:15 SalvageFileSys: Therefore only resetting inUse flag 15:28:15 SalvageFileSys completed on /vicepa 15:28:15 VAttachVolumeById: vol 1000003 (coda.root.0) attached and online 15:28:15 VAttachVolumeById: vol 1000004 (mta.pending.0) attached and online 15:28:15 Attached 2 volumes; 0 volumes not attached lqman: Creating LockQueue Manager.....LockQueue Manager starting ..... 15:28:15 LockQueue Manager just did a rvmlib_set_thread_data() done 15:28:15 CallBackCheckLWP just did a rvmlib_set_thread_data() 15:28:15 CheckLWP just did a rvmlib_set_thread_data() 15:28:15 ServerLWP 0 just did a rvmlib_set_thread_data() 15:28:15 ServerLWP 1 just did a rvmlib_set_thread_data() 15:28:15 ServerLWP 2 just did a rvmlib_set_thread_data() 15:28:15 ServerLWP 3 just did a rvmlib_set_thread_data() 15:28:15 ServerLWP 4 just did a rvmlib_set_thread_data() 15:28:15 ServerLWP 5 just did a rvmlib_set_thread_data() 15:28:15 ResLWP-0 just did a rvmlib_set_thread_data() 15:28:15 ResLWP-1 just did a rvmlib_set_thread_data() 15:28:15 VolUtilLWP 0 just did a rvmlib_set_thread_data() 15:28:15 VolUtilLWP 1 just did a rvmlib_set_thread_data() 15:28:15 Starting SmonDaemon timer 15:28:15 File Server started Mon May 31 15:28:15 2004 15:28:15 client_GetVenusId: got new host 212.85.15.251:32769 15:28:15 Building callback conn. 15:28:26 Worker4: Unbinding RPC connection 10550 15:28:31 New Data Base received 15:28:37 client_GetVenusId: got new host 212.85.15.253:32770 15:28:37 Building callback conn. 15:38:16 VGetVnode: vnode 1000003.10 is not allocated 15:47:01 Worker3: Unbinding RPC connection 211 15:47:48 Worker4: Unbinding RPC connection 13814 16:28:15 SmonDaemon timer expired 16:28:15 Entered CheckRVMResStat 16:28:15 Starting SmonDaemon timer 16:35:27 VAllocFid: volume disk uniquifier being extended 16:38:43 GetAttrPlusSHA: Computing SHA 1000003.74.2bb, disk.inode=8 16:47:01 Worker5: Unbinding RPC connection 15784 16:47:01 Worker0: Unbinding RPC connection 13282 16:47:49 Worker4: Unbinding RPC connection 16154 16:47:49 Worker5: Unbinding RPC connection 9039 17:09:12 GetAttrPlusSHA: Computing SHA 1000003.78.2bd, disk.inode=a 17:28:21 SmonDaemon timer expired 17:28:21 Entered CheckRVMResStat 17:28:21 Starting SmonDaemon timer 17:38:42 GetAttrPlusSHA: Computing SHA 1000003.7c.2bf, disk.inode=b 17:47:01 Worker4: Unbinding RPC connection 2028 17:47:01 Worker5: Unbinding RPC connection 14040 17:47:48 Worker1: Unbinding RPC connection 6539 17:47:48 Worker2: Unbinding RPC connection 10493 18:09:12 GetAttrPlusSHA: Computing SHA 1000003.80.2c1, disk.inode=c 18:09:28 GetAttrPlusSHA: Computing SHA 1000003.b2.2da, disk.inode=3 18:11:58 GetAttrPlusSHA: Computing SHA 1000003.b4.2db, disk.inode=3 18:13:02 GrowVnodes: growing Small list from 256 to 512 for volume 0x1000003 18:13:04 VAllocFid: volume disk uniquifier being extended 18:13:21 VAllocFid: volume disk uniquifier being extended 18:13:21 GrowVnodes: growing Small list from 512 to 768 for volume 0x1000003 18:13:38 VAllocFid: volume disk uniquifier being extended 18:13:45 GrowVnodes: growing Small list from 768 to 1024 for volume 0x1000003 18:14:24 GetAttrPlusSHA: Computing SHA 1000003.82.2c2, disk.inode=15d 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2b2.3da, disk.inode=a8 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2b8.3dd, disk.inode=aa 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2bc.3df, disk.inode=6f 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2c2.3e2, disk.inode=ad 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2c6.3e4, disk.inode=71 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2cc.3e7, disk.inode=b0 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2d0.3e9, disk.inode=21 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2d6.3ec, disk.inode=b3 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2da.3ee, disk.inode=75 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2e0.3f1, disk.inode=b6 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2e4.3f3, disk.inode=77 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2ea.3f6, disk.inode=b9 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2ee.3f8, disk.inode=4f 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2f4.3fb, disk.inode=bc 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2f8.3fd, disk.inode=7b 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.2fe.400, disk.inode=bf 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.302.402, disk.inode=7d 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.308.405, disk.inode=c2 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.30c.407, disk.inode=53 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.312.40a, disk.inode=c5 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.316.40c, disk.inode=81 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.31c.40f, disk.inode=c8 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.31e.410, disk.inode=56 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.320.411, disk.inode=84 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.322.412, disk.inode=85 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.324.413, disk.inode=58 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.326.414, disk.inode=87 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.328.415, disk.inode=88 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.32c.417, disk.inode=c9 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.332.41a, disk.inode=cb 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.338.41d, disk.inode=cd 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.33c.41f, disk.inode=8c 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.342.422, disk.inode=d0 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.348.425, disk.inode=d2 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.34c.427, disk.inode=8f 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.352.42a, disk.inode=d5 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.358.42d, disk.inode=d7 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.35e.430, disk.inode=d9 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.362.432, disk.inode=60 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.366.434, disk.inode=94 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.368.435, disk.inode=95 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.36a.436, disk.inode=40 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.36c.437, disk.inode=97 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.36e.438, disk.inode=98 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.370.439, disk.inode=64 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.372.43a, disk.inode=9a 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.374.43b, disk.inode=9b 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.376.43c, disk.inode=66 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.378.43d, disk.inode=9d 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.37a.43e, disk.inode=9e 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.37c.43f, disk.inode=44 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.37e.440, disk.inode=a0 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.380.441, disk.inode=a1 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.382.442, disk.inode=6a 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.384.443, disk.inode=a3 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.386.444, disk.inode=a4 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.388.445, disk.inode=6c 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.38c.447, disk.inode=dc 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.392.44a, disk.inode=de 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.398.44d, disk.inode=e0 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.39c.44f, disk.inode=a9 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3a2.452, disk.inode=e3 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3a6.454, disk.inode=ab 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ac.457, disk.inode=e6 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3b0.459, disk.inode=30 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3b4.45b, disk.inode=ae 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ba.45e, disk.inode=ea 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3be.460, disk.inode=72 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3c4.463, disk.inode=ed 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3c6.464, disk.inode=b2 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3c8.465, disk.inode=74 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ca.466, disk.inode=b4 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3cc.467, disk.inode=b5 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ce.468, disk.inode=4d 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3d0.469, disk.inode=b7 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3d2.46a, disk.inode=b8 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3d4.46b, disk.inode=78 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3d6.46c, disk.inode=ba 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3d8.46d, disk.inode=bb 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3da.46e, disk.inode=7a 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3dc.46f, disk.inode=bd 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3de.470, disk.inode=be 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3e0.471, disk.inode=35 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3e2.472, disk.inode=c0 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3e4.473, disk.inode=c1 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3e6.474, disk.inode=7e 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3e8.475, disk.inode=c3 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ea.476, disk.inode=c4 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ec.477, disk.inode=80 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3ee.478, disk.inode=c6 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3f0.479, disk.inode=c7 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3f4.47b, disk.inode=ee 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3f8.47d, disk.inode=89 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.3fe.480, disk.inode=f1 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.402.482, disk.inode=5b 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.408.485, disk.inode=f4 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.40e.488, disk.inode=f6 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.412.48a, disk.inode=ce 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.418.48d, disk.inode=f9 18:15:18 GetAttrPlusSHA: Computing SHA 1000003.41c.48f, disk.inode=3c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.8a.2c6, disk.inode=12 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.90.2c9, disk.inode=14 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.96.2cc, disk.inode=16 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.9a.2ce, disk.inode=11 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.a0.2d1, disk.inode=19 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.a6.2d4, disk.inode=1b 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.ac.2d7, disk.inode=1d 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.f2.2fa, disk.inode=1f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.f6.2fc, disk.inode=17 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.fc.2ff, disk.inode=22 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.102.302, disk.inode=24 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.106.304, disk.inode=1a 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.10c.307, disk.inode=27 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.112.30a, disk.inode=29 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.118.30d, disk.inode=2b 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.11c.30f, disk.inode=1e 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.122.312, disk.inode=2e 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.126.314, disk.inode=20 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.12c.317, disk.inode=31 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.132.31a, disk.inode=33 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.136.31c, disk.inode=23 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.13c.31f, disk.inode=36 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.140.321, disk.inode=25 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.146.324, disk.inode=39 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.14c.327, disk.inode=3b 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.152.32a, disk.inode=3d 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.158.32d, disk.inode=3f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.15c.32f, disk.inode=2a 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.162.332, disk.inode=42 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.166.334, disk.inode=2c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.16c.337, disk.inode=45 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.170.339, disk.inode=10 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.174.33b, disk.inode=2f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.17a.33e, disk.inode=49 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.180.341, disk.inode=4b 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.184.343, disk.inode=32 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.18a.346, disk.inode=4e 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.18e.348, disk.inode=34 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.194.34b, disk.inode=51 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.198.34d, disk.inode=13 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.19e.350, disk.inode=54 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1a2.352, disk.inode=38 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1a8.355, disk.inode=57 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1ac.357, disk.inode=3a 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1b2.35a, disk.inode=5a 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1b8.35d, disk.inode=5c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1bc.35f, disk.inode=28 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1c2.362, disk.inode=5f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1c6.364, disk.inode=1c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1cc.367, disk.inode=62 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1d0.369, disk.inode=41 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1d6.36c, disk.inode=65 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1da.36e, disk.inode=43 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1e0.371, disk.inode=68 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1e4.373, disk.inode=2d 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1ea.376, disk.inode=6b 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1ee.378, disk.inode=47 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1f4.37b, disk.inode=6e 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1fa.37e, disk.inode=70 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.1fe.380, disk.inode=4a 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.204.383, disk.inode=73 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.208.385, disk.inode=4c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.20e.388, disk.inode=76 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.212.38a, disk.inode=18 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.218.38d, disk.inode=79 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.21c.38f, disk.inode=50 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.222.392, disk.inode=7c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.226.394, disk.inode=52 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.22c.397, disk.inode=7f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.230.399, disk.inode=37 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.234.39b, disk.inode=55 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.23a.39e, disk.inode=83 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.23e.3a0, disk.inode=26 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.244.3a3, disk.inode=86 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.248.3a5, disk.inode=59 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.24c.3a7, disk.inode=f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.254.3ab, disk.inode=8b 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.25a.3ae, disk.inode=8d 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.25e.3b0, disk.inode=5d 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.264.3b3, disk.inode=90 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.268.3b5, disk.inode=3e 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.26e.3b8, disk.inode=93 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.272.3ba, disk.inode=61 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.278.3bd, disk.inode=96 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.27c.3bf, disk.inode=63 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.282.3c2, disk.inode=99 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.286.3c4, disk.inode=15 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.28c.3c7, disk.inode=9c 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.290.3c9, disk.inode=67 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.296.3cc, disk.inode=9f 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.29a.3ce, disk.inode=69 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.2a0.3d1, disk.inode=a2 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.2a4.3d3, disk.inode=46 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.2aa.3d6, disk.inode=a5 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.5ac.557, disk.inode=135 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.5ae.558, disk.inode=136 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.5b0.559, disk.inode=8a 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.5b2.55a, disk.inode=138 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.5b4.55b, disk.inode=139 18:16:19 GetAttrPlusSHA: Computing SHA 1000003.5b6.55c, disk.inode=f8 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.422.492, disk.inode=fc 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.426.494, disk.inode=8e 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.42c.497, disk.inode=ff 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.432.49a, disk.inode=101 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.438.49d, disk.inode=103 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.43e.4a0, disk.inode=105 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.442.4a2, disk.inode=91 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.448.4a5, disk.inode=108 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.44e.4a8, disk.inode=10a 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.450.4a9, disk.inode=da 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.454.4ab, disk.inode=10b 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.45a.4ae, disk.inode=10d 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.460.4b1, disk.inode=10f 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.466.4b4, disk.inode=111 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.46c.4b7, disk.inode=113 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.470.4b9, disk.inode=a7 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.474.4bb, disk.inode=e1 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.47a.4be, disk.inode=117 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.47e.4c0, disk.inode=48 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.484.4c3, disk.inode=11a 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.488.4c5, disk.inode=e5 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.48e.4c8, disk.inode=11d 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.492.4ca, disk.inode=e7 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.498.4cd, disk.inode=120 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.49c.4cf, disk.inode=e9 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4a2.4d2, disk.inode=123 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4a6.4d4, disk.inode=eb 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4ac.4d7, disk.inode=126 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4b4.4db, disk.inode=129 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4ba.4de, disk.inode=12b 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4be.4e0, disk.inode=ef 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4c4.4e3, disk.inode=12e 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4c8.4e5, disk.inode=ca 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4ce.4e8, disk.inode=131 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4d2.4ea, disk.inode=f3 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4d8.4ed, disk.inode=134 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4dc.4ef, disk.inode=f5 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4e2.4f2, disk.inode=137 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4e6.4f4, disk.inode=f7 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4ec.4f7, disk.inode=13a 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4f0.4f9, disk.inode=cf 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4f4.4fb, disk.inode=fa 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4fa.4fe, disk.inode=13e 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.4fe.500, disk.inode=d1 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.504.503, disk.inode=141 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.508.505, disk.inode=fe 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.50e.508, disk.inode=144 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.512.50a, disk.inode=100 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.518.50d, disk.inode=147 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.51c.50f, disk.inode=102 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.522.512, disk.inode=14a 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.524.513, disk.inode=104 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.526.514, disk.inode=d6 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.528.515, disk.inode=106 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.52a.516, disk.inode=107 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.52c.517, disk.inode=d8 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.52e.518, disk.inode=109 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.532.51a, disk.inode=14b 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.538.51d, disk.inode=14d 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.53c.51f, disk.inode=10c 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.542.522, disk.inode=150 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.54a.526, disk.inode=153 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.550.529, disk.inode=155 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.554.52b, disk.inode=110 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.55a.52e, disk.inode=158 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.560.531, disk.inode=15a 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.566.534, disk.inode=15c 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.56a.536, disk.inode=114 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.56c.537, disk.inode=115 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.56e.538, disk.inode=116 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.570.539, disk.inode=e2 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.572.53a, disk.inode=118 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.574.53b, disk.inode=119 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.576.53c, disk.inode=e4 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.578.53d, disk.inode=11b 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.57a.53e, disk.inode=11c 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.57c.53f, disk.inode=ac 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.57e.540, disk.inode=11e 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.580.541, disk.inode=11f 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.582.542, disk.inode=e8 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.584.543, disk.inode=121 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.586.544, disk.inode=122 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.588.545, disk.inode=af 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.58a.546, disk.inode=124 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.58c.547, disk.inode=125 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.58e.548, disk.inode=ec 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.590.549, disk.inode=127 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.592.54a, disk.inode=b1 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.594.54b, disk.inode=128 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.596.54c, disk.inode=12a 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.598.54d, disk.inode=82 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.59a.54e, disk.inode=12c 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.59c.54f, disk.inode=12d 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.59e.550, disk.inode=f0 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.5a0.551, disk.inode=12f 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.5a2.552, disk.inode=130 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.5a4.553, disk.inode=f2 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.5a6.554, disk.inode=132 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.5a8.555, disk.inode=133 18:17:20 GetAttrPlusSHA: Computing SHA 1000003.5aa.556, disk.inode=cc 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5b8.55d, disk.inode=13b 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5ba.55e, disk.inode=13c 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5bc.55f, disk.inode=13d 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5be.560, disk.inode=fb 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5c0.561, disk.inode=13f 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5c2.562, disk.inode=140 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5c4.563, disk.inode=fd 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5c6.564, disk.inode=142 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5c8.565, disk.inode=143 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5ca.566, disk.inode=d3 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5cc.567, disk.inode=145 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5ce.568, disk.inode=146 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5d0.569, disk.inode=d4 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5d2.56a, disk.inode=148 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5d4.56b, disk.inode=149 18:19:21 GetAttrPlusSHA: Computing SHA 1000003.5d6.56c, disk.inode=5e 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5d8.56d, disk.inode=92 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5da.56e, disk.inode=14c 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5dc.56f, disk.inode=db 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5de.570, disk.inode=14e 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5e0.571, disk.inode=14f 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5e2.572, disk.inode=a6 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5e4.573, disk.inode=151 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5e6.574, disk.inode=10e 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5e8.575, disk.inode=152 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5ea.576, disk.inode=154 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5ec.577, disk.inode=dd 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5ee.578, disk.inode=156 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5f0.579, disk.inode=157 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5f2.57a, disk.inode=6d 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5f4.57b, disk.inode=159 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5f6.57c, disk.inode=112 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5f8.57d, disk.inode=15b 18:19:22 GetAttrPlusSHA: Computing SHA 1000003.5fa.57e, disk.inode=df 18:28:21 SmonDaemon timer expired 18:28:21 Entered CheckRVMResStat 18:28:21 Starting SmonDaemon timer 18:30:38 CheckRemoveSemantics: 1000003.49.1d3, VCP error (198) 18:30:38 Entering VFlushVnode for vnode 0x49 18:30:38 Entering VFlushVnode for vnode 0xcc 18:30:43 CheckRemoveSemantics: 1000003.49.1d3, VCP error (198) 18:30:43 Entering VFlushVnode for vnode 0x49 18:30:43 Entering VFlushVnode for vnode 0xe2 18:30:47 ****** WARNING entry at 0x811fa08 already has deqing set! 18:30:51 GetAttrPlusSHA: Computing SHA 1000003.70.161, disk.inode=6 18:31:24 VAllocFid: volume disk uniquifier being extended 18:32:08 GetAttrPlusSHA: Computing SHA 1000003.648.6a1, disk.inode=0 18:32:49 Total operations for File Server = 4096 : time = Mon May 31 18:32:49 2004 18:32:49 Vice was last started at Mon May 31 15:28:15 2004 18:32:49 NewConnectFS 22 18:32:49 DisconnectFS 11 18:32:49 GetAttr 0 18:32:49 GetAcl 0 18:32:49 Fetch 419 18:32:49 SetAttr 4 18:32:49 SetAcl 0 18:32:49 Store 1054 18:32:49 ValidateAttrs 0 18:32:49 Remove 712 18:32:49 Create 1052 18:32:49 Rename 0 18:32:49 SymLink 0 18:32:49 Link 0 18:32:49 MakeDir 30 18:32:49 RemoveDir 30 18:32:49 GetRootVolume 1 18:32:49 SetRootVolume 0 18:32:49 GetVolumeStatus 2 18:32:49 SetVolumeStatus 0 18:32:49 GetTime 133 18:32:49 GetStatistics 0 18:32:49 GetVolumeInfo 0 18:32:49 AllocFids 35 18:32:49 COP2 36 18:32:49 Resolve 0 18:32:49 Repair 0 18:32:49 SetVV 0 18:32:49 Reintegrate 0 18:32:49 OpenReintHandle 0 18:32:49 QueryReintHandle 0 18:32:49 SendReintFragment 0 18:32:49 CloseReintHandle 0 18:32:49 GetVolVS 11 18:32:49 ValidateVols 0 18:32:49 GetWBPermit 0 18:32:49 TossWBPermit 0 18:32:49 RejectWBPermit 0 18:32:49 GetAttrPlusSHA 367 18:32:49 ValidateAttrsPlusSHA 177 18:32:49 Total FetchDatas = 419, bytes transfered = 1386801, transfer rate = 1386801 bps 18:32:49 Fetched files <1k = 5; <8k = 386; <64k = 27; <512k = 1; >512k = 0. 18:32:49 Total StoreDatas = 1054, bytes transfered = 4243000, transfer rate = 2121500 bps 18:32:49 Stored files <1k = 12; <8k = 1041; <64k = 0; <512k = 1; >512k = 0. 18:32:49 Large vnode cache, 500 entries, 30 allocs, 5725 gets (7 reads), 3730 writes 18:32:49 Small vnode cache,500 entries, 1052 allocs, 4567 gets (2 reads), 4929 writes 18:32:49 Volume header cache, 50 entries, 6773 gets, 0 replacements 18:32:49 Partition /vicepa: 32298296K available (minfree=5%), 29792335K free. Dirstats: get 0, put 0, flush 0 18:32:49 RPC Total bytes: sent = 2545942, received = 5804202 18:32:49 bytes sent: rpc = 889194, multirpc = 0, sftp = 1656748, sftp multicasted = 0 18:32:49 bytes received: rpc = 1242267, multirpc = 0, sftp = 4561935, sftp multicasted = 0 18:32:49 RPC Total packets: sent = 8153, received = 9761 18:32:49 packets sent: rpc = 4402, multirpc = 0, sftp = 3751, sftp multicasted = 0 18:32:49 packets received: rpc = 4404, multirpc = 0, sftp = 5357, sftp multicasted = 0 18:32:49 RPC Packets retried = 0, Invalid packets received = 56, Busies sent = 0 18:32:49 RPC Requests 4153, Good Requests 4097, Replies 251, Busies 0 18:32:49 RPC Counters: CCount 14; Unbinds 11; FConns 11; AConns 25; GCConns 0 18:32:49 RPC Creation counts: Conn 14; SL 22; PB Small 8, Med 11, Large 8; SS 3 18:32:49 RPC2 In Use: Conn 14; SS 3 18:32:49 RPC2 PB: InUse 4, Hold 0, Freeze 0, SFree 7, MFree 8, LFree 8 18:32:49 RPC2 HW: Freeze 0, Hold 0 18:32:49 SFTP: datas 1634, datar 4890, acks 1071, ackr 466, retries 3, duplicates 0 18:32:49 SFTP: timeouts 1, windowfulls 0, bogus 1, didpiggy 13 18:32:49 Total CB entries= 1437, blocks = 2; and total file entries = 1090, blocks = 5 18:32:49 There are currently 11 connections in use 18:32:49 There are 2 workstations and 2 are active (req in < 15 mins) 18:32:49 Ether Total bytes: sent = 0, received = 0 18:32:49 Ether Packets: sent = 0, received = 0, errors = 0 18:32:49 Printing RVM statistics Transaction statistics current cumulative Started: 67 8220 Aborted: 0 0 Committed, flush: 55 7367 Committed, no_flush: 12 853 Total committed: 67 8220 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 0 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 1 Internal flushes, incl. commit: 55 7188 Total flushes: 55 7189 Average flush time (msec): 0 3 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 13 Forced synch. truncations: 0 Recovery truncations: 22 Total truncations: 37 Average truncation time (sec): 0 Last truncation time (sec): 0 Last tree build time (sec): 0 Last tree apply time (sec): 0 Transaction records: 67 8220 Bookeeping records: 1 38 Log wrap-arounds: 0 0 Total records: 68 8258 Log used: 0% 5% Log written (bytes): 206424 21904692 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 55 1 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 7122 8 1 0 0 0 0 0 0 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 39 0 0 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 38 0 1 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 38 0 1 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 113 44 48 11 23 36 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 43 23 67 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 9193 2109 4157 372 2136 5518 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 4204 2176 7183 2 0 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 69 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 69 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 138 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 7940 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 7940 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 15880 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 69 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 69 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 138 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 7940 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 7940 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 15880 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 8009 0 0 0 0 0 18:32:49 Printing RDS statistics Number of Free bytes: 3fee7740 Alloced bytes: 18560 Mallocs: 1403 Frees: 994 Preallocs: 0 Hits: 580 Misses: 821 Large Hits: 0 Large Misses: 2 Coalesces: 852 Merges 408 Not Merged: 4822 Times the Large List pointer has changed: 0 18:32:49 done 18:35:17 VAllocFid: volume disk uniquifier being extended 18:35:22 ****** WARNING entry at 0x811fa08 already has deqing set! 18:35:49 VAllocFid: volume disk uniquifier being extended 18:35:56 GrowVnodes: growing Small list from 1024 to 1280 for volume 0x1000003 18:36:05 VAllocFid: volume disk uniquifier being extended 18:36:16 GrowVnodes: growing Small list from 1280 to 1536 for volume 0x1000003 18:36:21 VAllocFid: volume disk uniquifier being extended 18:36:22 GetVolObj: Volume (1000003) already write locked 18:36:22 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:36:22 GetVolObj: Volume (1000003) already write locked 18:36:22 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:36:43 GetVolObj: Volume (1000003) already write locked 18:36:43 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:36:43 GetVolObj: Volume (1000003) already write locked 18:36:43 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:37:05 VAllocFid: volume disk uniquifier being extended 18:37:05 GrowVnodes: growing Small list from 1536 to 1792 for volume 0x1000003 18:37:21 VAllocFid: volume disk uniquifier being extended 18:37:37 VAllocFid: volume disk uniquifier being extended 18:37:54 Total operations for File Server = 8192 : time = Mon May 31 18:37:54 2004 18:37:54 Vice was last started at Mon May 31 15:28:15 2004 18:37:54 NewConnectFS 23 18:37:54 DisconnectFS 11 18:37:54 GetAttr 0 18:37:54 GetAcl 0 18:37:54 Fetch 425 18:37:54 SetAttr 4 18:37:54 SetAcl 0 18:37:54 Store 2529 18:37:54 ValidateAttrs 0 18:37:54 Remove 1738 18:37:54 Create 2530 18:37:54 Rename 0 18:37:54 SymLink 0 18:37:54 Link 0 18:37:54 MakeDir 32 18:37:54 RemoveDir 31 18:37:54 GetRootVolume 1 18:37:54 SetRootVolume 0 18:37:54 GetVolumeStatus 2 18:37:54 SetVolumeStatus 0 18:37:54 GetTime 135 18:37:54 GetStatistics 0 18:37:54 GetVolumeInfo 0 18:37:54 AllocFids 84 18:37:54 COP2 74 18:37:54 Resolve 0 18:37:54 Repair 0 18:37:54 SetVV 0 18:37:54 Reintegrate 5 18:37:54 OpenReintHandle 0 18:37:54 QueryReintHandle 0 18:37:54 SendReintFragment 0 18:37:54 CloseReintHandle 0 18:37:54 GetVolVS 11 18:37:54 ValidateVols 0 18:37:54 GetWBPermit 0 18:37:54 TossWBPermit 0 18:37:54 RejectWBPermit 0 18:37:54 GetAttrPlusSHA 369 18:37:54 ValidateAttrsPlusSHA 188 18:37:54 Total FetchDatas = 425, bytes transfered = 1419569, transfer rate = 1419569 bps 18:37:54 Fetched files <1k = 5; <8k = 391; <64k = 28; <512k = 1; >512k = 0. 18:37:54 Total StoreDatas = 2527, bytes transfered = 10115638, transfer rate = 1123959 bps 18:37:54 Stored files <1k = 12; <8k = 2514; <64k = 0; <512k = 1; >512k = 0. 18:37:54 Large vnode cache, 500 entries, 33 allocs, 12256 gets (7 reads), 8757 writes 18:37:54 Small vnode cache,500 entries, 2531 allocs, 10036 gets (2 reads), 11872 writes 18:37:54 Volume header cache, 50 entries, 14830 gets, 0 replacements 18:37:54 Partition /vicepa: 32298296K available (minfree=5%), 29787801K free. Dirstats: get 0, put 0, flush 0 18:37:54 RPC Total bytes: sent = 3771050, received = 13630900 18:37:54 bytes sent: rpc = 1837698, multirpc = 0, sftp = 1933352, sftp multicasted = 0 18:37:54 bytes received: rpc = 2773312, multirpc = 0, sftp = 10857588, sftp multicasted = 0 18:37:54 RPC Total packets: sent = 15248, received = 20675 18:37:54 packets sent: rpc = 8514, multirpc = 0, sftp = 6734, sftp multicasted = 0 18:37:54 packets received: rpc = 8516, multirpc = 0, sftp = 12159, sftp multicasted = 0 18:37:54 RPC Packets retried = 0, Invalid packets received = 59, Busies sent = 0 18:37:54 RPC Requests 8252, Good Requests 8193, Replies 264, Busies 0 18:37:54 RPC Counters: CCount 15; Unbinds 11; FConns 11; AConns 26; GCConns 0 18:37:54 RPC Creation counts: Conn 15; SL 22; PB Small 8, Med 11, Large 8; SS 3 18:37:54 RPC2 In Use: Conn 15; SS 3 18:37:54 RPC2 PB: InUse 3, Hold 0, Freeze 0, SFree 8, MFree 8, LFree 8 18:37:54 RPC2 HW: Freeze 0, Hold 0 18:37:54 SFTP: datas 1666, datar 11684, acks 2548, ackr 474, retries 3, duplicates 0 18:37:54 SFTP: timeouts 1, windowfulls 0, bogus 1, didpiggy 18 18:37:54 Total CB entries= 2919, blocks = 3; and total file entries = 2572, blocks = 12 18:37:54 There are currently 12 connections in use 18:37:54 There are 2 workstations and 2 are active (req in < 15 mins) 18:37:54 Ether Total bytes: sent = 0, received = 0 18:37:54 Ether Packets: sent = 0, received = 0, errors = 0 18:37:54 Printing RVM statistics Transaction statistics current cumulative Started: 7 19221 Aborted: 0 0 Committed, flush: 7 17338 Committed, no_flush: 0 1883 Total committed: 7 19221 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 0 2 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 1 Internal flushes, incl. commit: 7 17159 Total flushes: 7 17160 Average flush time (msec): 0 3 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 43 Forced synch. truncations: 0 Recovery truncations: 22 Total truncations: 67 Average truncation time (sec): 0 Last truncation time (sec): 0 Last tree build time (sec): 0 Last tree apply time (sec): 0 Transaction records: 7 19223 Bookeeping records: 1 68 Log wrap-arounds: 0 2 Total records: 8 19293 Log used: 0% 5% Log written (bytes): 35312 68843312 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 8 0 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 17131 13 6 0 0 0 0 0 0 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 69 0 0 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 68 0 1 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 68 0 1 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 2 2 0 2 8 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 3 2 14 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 20653 4363 8123 491 4689 12931 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 9272 4814 25731 3 2 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 8 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 8 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 16 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 19002 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 19002 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 38004 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 8 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 8 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 16 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 19002 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 19002 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 38004 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 19010 0 0 0 0 0 18:37:54 Printing RDS statistics Number of Free bytes: 3fed2880 Alloced bytes: 2d420 Mallocs: 2905 Frees: 2040 Preallocs: 0 Hits: 1493 Misses: 1407 Large Hits: 0 Large Misses: 5 Coalesces: 1882 Merges 516 Not Merged: 26333 Times the Large List pointer has changed: 0 18:37:54 done 18:37:55 VAllocFid: volume disk uniquifier being extended 18:38:13 VAllocFid: volume disk uniquifier being extended 18:38:28 VAllocFid: volume disk uniquifier being extended 18:38:44 VAllocFid: volume disk uniquifier being extended 18:38:52 GetVolObj: Volume (1000003) already write locked 18:38:52 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:38:52 GetVolObj: Volume (1000003) already write locked 18:38:52 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:38:52 GetVolObj: Volume (1000003) already write locked 18:38:52 PutReintegrateObjects: stale directory fid 0x7f000002.47.1d2, num 0, max 50 18:39:03 PutReintegrateObjects: stale directory fid 0x7f000002.5b.9e2, num 0, max 50 18:39:13 GetVolObj: Volume (1000003) already write locked 18:39:13 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:39:14 GetAttrPlusSHA: Computing SHA 1000003.a60.103f, disk.inode=1c8 18:39:15 GetAttrPlusSHA: Computing SHA 1000003.a64.1041, disk.inode=1d8 18:39:16 GetAttrPlusSHA: Computing SHA 1000003.c78.109a, disk.inode=27d 18:39:16 VAllocFid: volume disk uniquifier being extended 18:39:16 GetAttrPlusSHA: Computing SHA 1000003.b52.1087, disk.inode=27f 18:39:20 GetAttrPlusSHA: Computing SHA 1000003.bda.10cb, disk.inode=2df 18:39:21 GetAttrPlusSHA: Computing SHA 1000003.be2.10cf, disk.inode=304 18:39:21 GetAttrPlusSHA: Computing SHA 1000003.be6.10d1, disk.inode=307 18:39:21 GetAttrPlusSHA: Computing SHA 1000003.be8.10d2, disk.inode=309 18:39:22 GetAttrPlusSHA: Computing SHA 1000003.748.115a, disk.inode=172 18:39:22 GetAttrPlusSHA: Computing SHA 1000003.bf0.10d6, disk.inode=179 18:39:23 GetAttrPlusSHA: Computing SHA 1000003.788.117a, disk.inode=6 18:39:23 VAllocFid: volume disk uniquifier being extended 18:39:23 GetAttrPlusSHA: Computing SHA 1000003.78a.117b, disk.inode=162 18:39:29 GetAttrPlusSHA: Computing SHA 1000003.688.121a, disk.inode=e 18:39:30 PutReintegrateObjects: stale directory fid 0x7f000002.5b.9e2, num 0, max 50 18:39:30 GetAttrPlusSHA: Computing SHA 1000003.68a.121b, disk.inode=15f 18:39:30 GetVolObj: Volume (1000003) already write locked 18:39:30 GrabFsObj, GetVolObj error Resource temporarily unavailable 18:39:30 GetAttrPlusSHA: Computing SHA 1000003.862.1245, disk.inode=174 18:39:30 PutReintegrateObjects: stale directory fid 0x7f000002.5b.9e2, num 0, max 50 18:39:30 PutReintegrateObjects: stale directory fid 0x7f000002.5b.9e2, num 0, max 50 18:39:30 GetAttrPlusSHA: Computing SHA 1000003.864.1246, disk.inode=176 18:39:30 PutReintegrateObjects: stale directory fid 0x7f000002.5b.9e2, num 0, max 50 18:39:31 PutReintegrateObjects: stale directory fid 0x7f000002.5b.9e2, num 0, max 50 18:39:31 ****** WARNING entry at 0x811fa08 already has deqing set! 18:39:46 ****** WARNING entry at 0x811fa08 already has deqing set! 18:47:01 Worker5: Unbinding RPC connection 4084 18:47:01 Worker1: Unbinding RPC connection 14363 18:47:49 Worker2: Unbinding RPC connection 9764 18:47:49 Worker5: Unbinding RPC connection 9705 18:47:49 Worker1: Unbinding RPC connection 14012 19:28:21 SmonDaemon timer expired 19:28:21 Entered CheckRVMResStat 19:28:21 Starting SmonDaemon timer 19:47:01 Worker1: Unbinding RPC connection 14475 19:47:49 Worker2: Unbinding RPC connection 15054 20:28:21 SmonDaemon timer expired 20:28:21 Entered CheckRVMResStat 20:28:21 Starting SmonDaemon timer 20:47:01 Worker2: Unbinding RPC connection 774 20:47:50 Worker5: Unbinding RPC connection 1052 21:28:21 SmonDaemon timer expired 21:28:21 Entered CheckRVMResStat 21:28:21 Starting SmonDaemon timer 21:47:01 Worker5: Unbinding RPC connection 1470 21:47:49 Worker1: Unbinding RPC connection 8369 22:28:21 SmonDaemon timer expired 22:28:21 Entered CheckRVMResStat 22:28:21 Starting SmonDaemon timer 22:47:01 Worker1: Unbinding RPC connection 11569 22:47:50 Worker2: Unbinding RPC connection 15558 22:48:57 Shutdown received 22:48:57 Shutdown received 22:49:23 Shutting down the File Server Mon May 31 22:49:23 2004 22:49:23 Total operations for File Server = 10859 : time = Mon May 31 22:49:23 2004 22:49:23 Vice was last started at Mon May 31 15:28:15 2004 22:49:23 NewConnectFS 32 22:49:23 DisconnectFS 24 22:49:23 GetAttr 0 22:49:23 GetAcl 0 22:49:23 Fetch 435 22:49:23 SetAttr 4 22:49:23 SetAcl 0 22:49:23 Store 3293 22:49:23 ValidateAttrs 0 22:49:23 Remove 2497 22:49:23 Create 3298 22:49:23 Rename 0 22:49:23 SymLink 0 22:49:23 Link 0 22:49:23 MakeDir 33 22:49:23 RemoveDir 31 22:49:23 GetRootVolume 1 22:49:23 SetRootVolume 0 22:49:23 GetVolumeStatus 2 22:49:23 SetVolumeStatus 0 22:49:23 GetTime 231 22:49:23 GetStatistics 0 22:49:23 GetVolumeInfo 0 22:49:23 AllocFids 125 22:49:23 COP2 212 22:49:23 Resolve 0 22:49:23 Repair 0 22:49:23 SetVV 0 22:49:23 Reintegrate 30 22:49:23 OpenReintHandle 0 22:49:23 QueryReintHandle 0 22:49:23 SendReintFragment 0 22:49:23 CloseReintHandle 0 22:49:23 GetVolVS 11 22:49:23 ValidateVols 0 22:49:23 GetWBPermit 0 22:49:23 TossWBPermit 0 22:49:23 RejectWBPermit 0 22:49:23 GetAttrPlusSHA 382 22:49:23 ValidateAttrsPlusSHA 218 22:49:23 Total FetchDatas = 435, bytes transfered = 1462577, transfer rate = 1462577 bps 22:49:23 Fetched files <1k = 5; <8k = 400; <64k = 29; <512k = 1; >512k = 0. 22:49:23 Total StoreDatas = 3288, bytes transfered = 13066194, transfer rate = 1005091 bps 22:49:23 Stored files <1k = 12; <8k = 3275; <64k = 0; <512k = 1; >512k = 0. 22:49:23 Large vnode cache, 500 entries, 35 allocs, 16968 gets (7 reads), 11845 writes 22:49:23 Small vnode cache,500 entries, 3650 allocs, 14487 gets (2 reads), 17178 writes 22:49:23 Volume header cache, 50 entries, 20000 gets, 0 replacements 22:49:23 Partition /vicepa: 32298296K available (minfree=5%), 29774964K free. Dirstats: get 0, put 0, flush 0 22:49:23 RPC Total bytes: sent = 5133394, received = 22580483 22:49:23 bytes sent: rpc = 3004438, multirpc = 0, sftp = 2128956, sftp multicasted = 0 22:49:23 bytes received: rpc = 7340544, multirpc = 0, sftp = 15239939, sftp multicasted = 0 22:49:23 RPC Total packets: sent = 29594, received = 37874 22:49:23 packets sent: rpc = 20888, multirpc = 0, sftp = 8706, sftp multicasted = 0 22:49:23 packets received: rpc = 20871, multirpc = 0, sftp = 17003, sftp multicasted = 0 22:49:23 RPC Packets retried = 0, Invalid packets received = 9222, Busies sent = 9162 22:49:23 RPC Requests 20084, Good Requests 10862, Replies 787, Busies 0 22:49:23 RPC Counters: CCount 13; Unbinds 24; FConns 24; AConns 37; GCConns 0 22:49:23 RPC Creation counts: Conn 15; SL 22; PB Small 8, Med 11, Large 8; SS 3 22:49:23 RPC2 In Use: Conn 13; SS 3 22:49:23 RPC2 PB: InUse 6, Hold 0, Freeze 0, SFree 6, MFree 7, LFree 8 22:49:23 RPC2 HW: Freeze 0, Hold 0 22:49:23 SFTP: datas 1709, datar 16516, acks 3698, ackr 486, retries 3, duplicates 3 22:49:23 SFTP: timeouts 1, windowfulls 0, bogus 1, didpiggy 25 22:49:23 Total CB entries= 3944, blocks = 4; and total file entries = 3596, blocks = 17 22:49:23 There are currently 8 connections in use 22:49:23 There are 2 workstations and 2 are active (req in < 15 mins) 22:49:23 Ether Total bytes: sent = 0, received = 0 22:49:23 Ether Packets: sent = 0, received = 0, errors = 0 22:49:23 Printing RVM statistics Transaction statistics current cumulative Started: 1458 27636 Aborted: 0 0 Committed, flush: 1445 24976 Committed, no_flush: 13 2660 Total committed: 1458 27636 Committed, but not flushed: 0 Unflushed transactions length: 0 Not committed: 0 Split by log wrap: 1 3 Delayed by truncation: 0 0 Log function statistics current cumulative rvm_flush calls: 0 1 Internal flushes, incl. commit: 1445 24797 Total flushes: 1445 24798 Average flush time (msec): 4 3 Last flush time (msec): 3 rvm_truncate calls: 2 Automatic truncations: 59 Forced synch. truncations: 0 Recovery truncations: 22 Total truncations: 83 Average truncation time (sec): 0 Last truncation time (sec): 0 Last tree build time (sec): 0 Last tree apply time (sec): 0 Transaction records: 1459 27639 Bookeeping records: 1 84 Log wrap-arounds: 1 4 Total records: 1461 27727 Log used: 3% 5% Log written (bytes): 1191156 93868072 Timing Histograms Current Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 1445 1 0 0 0 0 0 0 0 0 Cummulative Flush Timings (msec): <=25 <=50 <=100 <=250 <=500 <=1000 <=2500 <=5000 <=10000 > 10000 23325 15 10 0 0 0 0 0 0 0 Truncation Timings for Tree Build (sec): <=1 <=10 <=100 <=500 > 500 85 0 0 0 0 Truncation Timings for Tree Apply (sec): <=1 <=10 <=100 <=500 > 500 84 0 1 0 0 Total Truncation Timings (sec): <=1 <=10 <=100 <=500 > 500 84 0 1 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 6429 1261 1588 554 244 710 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 46 47 122 0 0 Cumulative Range Lengths (bytes): <=0 <=4 <=8 <=16 <=32 <=64 <=128 0 29796 6247 10793 688 6356 17115 <=256 <=512 <=1024 <=2048 <=4096 > 4096 0 12319 6403 35276 3 2 Current Range Lengths Eliminated (bytes): Range coalesce: <=0 <=4 <=8 <=16 <=32 <=64 <=128 1460 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 1460 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 2920 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 25965 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 25965 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 51930 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 1460 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 1460 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 2920 0 0 0 0 0 Cummulative Number of Eliminated Ranges per Transaction Range coalesce: <=0 <=5 <=10 <=50 <=100 > 100 25965 0 0 0 0 0 Trans coalesce: <=0 <=5 <=10 <=50 <=100 > 100 25965 0 0 0 0 0 Totals: <=0 <=5 <=10 <=50 <=100 > 100 51930 0 0 0 0 0 Cummulative Number of Transactions Coalesced per flush Cycle <=0 <=5 <=10 <=50 <=100 > 100 27425 0 0 0 0 0 22:49:23 Printing RDS statistics Number of Free bytes: 3feda8e0 Alloced bytes: 253c0 Mallocs: 4037 Frees: 3579 Preallocs: 0 Hits: 2278 Misses: 1754 Large Hits: 0 Large Misses: 5 Coalesces: 2659 Merges 1267 Not Merged: 63582 Times the Large List pointer has changed: 0 22:49:23 done 22:49:23 VShutdown: shutting down on-line volumes... 22:49:23 VShutdown: Taking volume coda.root.0(0x1000003) offline...