results/20110409/sfsc001.20110411-s3-west-hi16-crypt

   1 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 100  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
   2 NFS V3 Protocol Version
   3
   4 ************************************************************************
   5 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
   6 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
   7
   8 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
   9 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
  10
  11 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 08:14:31 2011
  12         Client hostname = c09-44
  13         Prime Client hostname = c09-44.sysnet.ucsd.edu
  14
  15 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
  16 NFS V3 Protocol
  17 Mon Apr 11 08:14:31 2011 Mounting 16 remote test directories.
  18 Child[009]: NFS negotiated block size 32768
  19 Child[011]: NFS negotiated block size 32768
  20 Child[014]: NFS negotiated block size 32768
  21 Child[000]: NFS negotiated block size 32768
  22 Child[001]: NFS negotiated block size 32768
  23 Child[002]: NFS negotiated block size 32768
  24 Child[003]: NFS negotiated block size 32768
  25 Child[004]: NFS negotiated block size 32768
  26 Child[005]: NFS negotiated block size 32768
  27 Child[006]: NFS negotiated block size 32768
  28 Child[007]: NFS negotiated block size 32768
  29 Child[008]: NFS negotiated block size 32768
  30 Child[010]: NFS negotiated block size 32768
  31 Child[012]: NFS negotiated block size 32768
  32 Child[013]: NFS negotiated block size 32768
  33 Child[015]: NFS negotiated block size 32768
  34 Mon Apr 11 08:14:50 2011 Completed.
  35 Mon Apr 11 08:14:50 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
  36 Mon Apr 11 08:14:50 2011 Completed.
  37 Mon Apr 11 08:14:50 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
  38 Mon Apr 11 08:14:50 2011 Received.
  39
  40 Mon Apr 11 08:14:50 2011 Initializing test directories.
  41 Mon Apr 11 08:14:50 2011 Child 14 will create 46 directories.
  42 Mon Apr 11 08:14:50 2011 Child 12 will create 46 directories.
  43 Mon Apr 11 08:14:50 2011 Child 11 will create 46 directories.
  44 Mon Apr 11 08:14:50 2011 Child 13 will create 46 directories.
  45 Mon Apr 11 08:14:50 2011 Child 1 will create 46 directories.
  46 Mon Apr 11 08:14:50 2011 Child 0 will create 46 directories.
  47 Mon Apr 11 08:14:50 2011 Child 3 will create 46 directories.
  48 Mon Apr 11 08:14:50 2011 Child 9 will create 46 directories.
  49 Mon Apr 11 08:14:50 2011 Child 8 will create 46 directories.
  50 Mon Apr 11 08:14:50 2011 Child 15 will create 46 directories.
  51 Mon Apr 11 08:14:50 2011 Child 5 will create 46 directories.
  52 Mon Apr 11 08:14:50 2011 Child 6 will create 46 directories.
  53 Mon Apr 11 08:14:50 2011 Child 4 will create 46 directories.
  54 Mon Apr 11 08:14:50 2011 Child 7 will create 46 directories.
  55 Mon Apr 11 08:14:50 2011 Child 2 will create 46 directories.
  56 Mon Apr 11 08:14:50 2011 Child 10 will create 46 directories.
  57 Mon Apr 11 08:14:50 2011 Child 13 finished creating 46 directories.
  58 Mon Apr 11 08:14:50 2011 Child 13 will create 1386 files.
  59 Mon Apr 11 08:14:50 2011 Child 8 finished creating 46 directories.
  60 Mon Apr 11 08:14:50 2011 Child 8 will create 1386 files.
  61 Mon Apr 11 08:14:50 2011 Child 12 finished creating 46 directories.
  62 Mon Apr 11 08:14:50 2011 Child 12 will create 1386 files.
  63 Mon Apr 11 08:14:50 2011 Child 9 finished creating 46 directories.
  64 Mon Apr 11 08:14:50 2011 Child 9 will create 1386 files.
  65 Mon Apr 11 08:14:50 2011 Child 11 finished creating 46 directories.
  66 Mon Apr 11 08:14:50 2011 Child 11 will create 1386 files.
  67 Mon Apr 11 08:14:50 2011 Child 6 finished creating 46 directories.
  68 Mon Apr 11 08:14:50 2011 Child 6 will create 1386 files.
  69 Mon Apr 11 08:14:50 2011 Child 5 finished creating 46 directories.
  70 Mon Apr 11 08:14:50 2011 Child 5 will create 1386 files.
  71 Mon Apr 11 08:14:50 2011 Child 4 finished creating 46 directories.
  72 Mon Apr 11 08:14:50 2011 Child 4 will create 1386 files.
  73 Mon Apr 11 08:14:50 2011 Child 1 finished creating 46 directories.
  74 Mon Apr 11 08:14:50 2011 Child 1 will create 1386 files.
  75 Mon Apr 11 08:14:50 2011 Child 3 finished creating 46 directories.
  76 Mon Apr 11 08:14:50 2011 Child 3 will create 1386 files.
  77 Mon Apr 11 08:14:50 2011 Child 10 finished creating 46 directories.
  78 Mon Apr 11 08:14:50 2011 Child 10 will create 1386 files.
  79 Mon Apr 11 08:14:50 2011 Child 0 finished creating 46 directories.
  80 Mon Apr 11 08:14:50 2011 Child 0 will create 1386 files.
  81 Mon Apr 11 08:14:50 2011 Child 2 finished creating 46 directories.
  82 Mon Apr 11 08:14:50 2011 Child 2 will create 1386 files.
  83 Mon Apr 11 08:14:50 2011 Child 7 finished creating 46 directories.
  84 Mon Apr 11 08:14:50 2011 Child 7 will create 1386 files.
  85 Mon Apr 11 08:14:50 2011 Child 15 finished creating 46 directories.
  86 Mon Apr 11 08:14:50 2011 Child 15 will create 1386 files.
  87 Mon Apr 11 08:14:50 2011 Child 14 finished creating 46 directories.
  88 Mon Apr 11 08:14:50 2011 Child 14 will create 1386 files.
  89 Mon Apr 11 08:19:51 2011 Child 8 verified 1327 files (1327 creates).
  90 Mon Apr 11 08:19:51 2011 Child 10 verified 1247 files (1247 creates).
  91 Mon Apr 11 08:19:52 2011 Child 14 verified 1263 files (1263 creates).
  92 Mon Apr 11 08:19:57 2011 Child 13 verified 1247 files (1247 creates).
  93 Mon Apr 11 08:19:59 2011 Child 9 verified 1332 files (1332 creates).
  94 Mon Apr 11 08:20:00 2011 Child 15 verified 996 files (996 creates).
  95 Mon Apr 11 08:20:00 2011 Child 5 verified 996 files (996 creates).
  96 Mon Apr 11 08:20:00 2011 Child 6 verified 996 files (996 creates).
  97 Mon Apr 11 08:20:00 2011 Child 2 verified 996 files (996 creates).
  98 Mon Apr 11 08:20:00 2011 Child 11 verified 1247 files (1247 creates).
  99 Mon Apr 11 08:20:00 2011 Child 12 verified 1247 files (1247 creates).
 100 Mon Apr 11 08:20:01 2011 Child 7 verified 996 files (996 creates).
 101 Mon Apr 11 08:20:05 2011 Child 4 verified 1118 files (1118 creates).
 102 Mon Apr 11 08:20:05 2011 Child 3 verified 1118 files (1118 creates).
 103 Mon Apr 11 08:20:05 2011 Child 1 verified 1118 files (1118 creates).
 104 Mon Apr 11 08:20:05 2011 Child 0 verified 1118 files (1118 creates).
 105 Mon Apr 11 08:20:06 2011 Child 8 verified 1386 files, created 1373
 106 Mon Apr 11 08:20:06 2011 Child 8 will create 2 symlinks.
 107 Mon Apr 11 08:20:06 2011 Child 8 finished creating 2 symlinks.
 108 Mon Apr 11 08:20:08 2011 Child 9 verified 1386 files, created 1373
 109 Mon Apr 11 08:20:08 2011 Child 9 will create 2 symlinks.
 110 Mon Apr 11 08:20:08 2011 Child 9 finished creating 2 symlinks.
 111 Mon Apr 11 08:20:15 2011 Child 14 verified 1386 files, created 1373
 112 Mon Apr 11 08:20:15 2011 Child 14 will create 2 symlinks.
 113 Mon Apr 11 08:20:15 2011 Child 14 finished creating 2 symlinks.
 114 Mon Apr 11 08:20:16 2011 Child 10 verified 1386 files, created 1373
 115 Mon Apr 11 08:20:16 2011 Child 10 will create 2 symlinks.
 116 Mon Apr 11 08:20:16 2011 Child 10 finished creating 2 symlinks.
 117 Mon Apr 11 08:20:16 2011 Child 11 verified 1386 files, created 1373
 118 Mon Apr 11 08:20:16 2011 Child 11 will create 2 symlinks.
 119 Mon Apr 11 08:20:16 2011 Child 11 finished creating 2 symlinks.
 120 Mon Apr 11 08:20:16 2011 Child 12 verified 1386 files, created 1373
 121 Mon Apr 11 08:20:16 2011 Child 12 will create 2 symlinks.
 122 Mon Apr 11 08:20:16 2011 Child 13 verified 1386 files, created 1373
 123 Mon Apr 11 08:20:16 2011 Child 13 will create 2 symlinks.
 124 Mon Apr 11 08:20:16 2011 Child 12 finished creating 2 symlinks.
 125 Mon Apr 11 08:20:16 2011 Child 13 finished creating 2 symlinks.
 126 Mon Apr 11 08:20:30 2011 Child 4 verified 1386 files, created 1373
 127 Mon Apr 11 08:20:30 2011 Child 4 will create 2 symlinks.
 128 Mon Apr 11 08:20:30 2011 Child 4 finished creating 2 symlinks.
 129 Mon Apr 11 08:20:30 2011 Child 1 verified 1386 files, created 1373
 130 Mon Apr 11 08:20:30 2011 Child 1 will create 2 symlinks.
 131 Mon Apr 11 08:20:30 2011 Child 1 finished creating 2 symlinks.
 132 Mon Apr 11 08:20:30 2011 Child 3 verified 1386 files, created 1373
 133 Mon Apr 11 08:20:30 2011 Child 3 will create 2 symlinks.
 134 Mon Apr 11 08:20:31 2011 Child 3 finished creating 2 symlinks.
 135 Mon Apr 11 08:20:31 2011 Child 0 verified 1386 files, created 1373
 136 Mon Apr 11 08:20:31 2011 Child 0 will create 2 symlinks.
 137 Mon Apr 11 08:20:31 2011 Child 0 finished creating 2 symlinks.
 138 Mon Apr 11 08:20:37 2011 Child 6 verified 1386 files, created 1373
 139 Mon Apr 11 08:20:37 2011 Child 6 will create 2 symlinks.
 140 Mon Apr 11 08:20:37 2011 Child 6 finished creating 2 symlinks.
 141 Mon Apr 11 08:20:37 2011 Child 15 verified 1386 files, created 1373
 142 Mon Apr 11 08:20:37 2011 Child 15 will create 2 symlinks.
 143 Mon Apr 11 08:20:37 2011 Child 5 verified 1386 files, created 1373
 144 Mon Apr 11 08:20:37 2011 Child 5 will create 2 symlinks.
 145 Mon Apr 11 08:20:37 2011 Child 2 verified 1386 files, created 1373
 146 Mon Apr 11 08:20:37 2011 Child 2 will create 2 symlinks.
 147 Mon Apr 11 08:20:37 2011 Child 15 finished creating 2 symlinks.
 148 Mon Apr 11 08:20:37 2011 Child 5 finished creating 2 symlinks.
 149 Mon Apr 11 08:20:37 2011 Child 2 finished creating 2 symlinks.
 150 Mon Apr 11 08:20:38 2011 Child 7 verified 1386 files, created 1373
 151 Mon Apr 11 08:20:38 2011 Child 7 will create 2 symlinks.
 152 Mon Apr 11 08:20:38 2011 Child 7 finished creating 2 symlinks.
 153 Mon Apr 11 08:20:38 2011 Completed.
 154 Mon Apr 11 08:20:38 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 155 Mon Apr 11 08:20:38 2011 Completed.
 156 Mon Apr 11 08:20:38 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 157 Mon Apr 11 08:20:39 2011 Received.
 158 Mon Apr 11 08:20:39 2011 Performing 300 seconds pretest warmup.
 159 Mon Apr 11 08:25:39 2011 Completed.
 160 Mon Apr 11 08:25:39 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 161 Mon Apr 11 08:25:39 2011 Completed.
 162 Mon Apr 11 08:25:39 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 163 Mon Apr 11 08:25:40 2011 Received.
 164 Mon Apr 11 08:25:40 2011 Starting 300 seconds test run.
 165 Child 4 - 50 aborted ops
 166 Child 4 - 100 aborted ops
 167 SFS Single Client (c09-44) Results, Mon Apr 11 08:30:50 2011
 168 --------------------------------------------------------------------------------------
 169 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 170 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 171 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 172            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 173 --------------------------------------------------------------------------------------
 174 getattr     26.0%    25.9%     7752     7752      0      0.19     0.03      0.00  5.4%
 175 setattr      4.0%     4.0%     1192     1192      0      0.94     1.05      0.06  4.2%
 176 lookup      24.0%    23.7%     7100     7100      0      0.19     0.03      0.00  5.1%
 177 readlink     1.0%     1.0%      302      302      0      0.19     0.04      0.02  0.2%
 178 read        18.0%    18.1%     5439     6642      0      1.29     2.94      0.05 26.0%
 179 write       10.0%    10.2%     3052     3705      0      3.54    13.89      0.13 40.1%
 180 create       1.0%     1.0%      303      303      0      1.83     0.83      0.10  2.1%
 181 remove       1.0%     1.0%      297      297      0      1.26     0.90      0.11  1.4%
 182 readdir      1.0%     1.0%      287      287      0      0.35     0.07      0.03  0.4%
 183 fsstat       1.0%     1.0%      292      292      0      0.19     0.03      0.02  0.2%
 184 access      11.0%    11.1%     3337     3337      0      0.19     0.03      0.01  2.3%
 185 commit       0.0%     0.0%        0     1342      0      0.00     0.00      0.00 11.4%
 186 readdirplus  2.0%     2.1%      629      629      0      0.55     0.05      0.02  1.3%
 187 --------------------------------------------------------------------------------------
 188
 189       --------------------------------------------------
 190       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 191       --------------------------+-----------------------
 192 SFS NFS V3 THROUGHPUT:   99.94 Ops/Sec   AVG. RESPONSE TIME:    0.89 Msec/Op
 193 TCP PROTOCOL
 194 FAST CALLS: 0
 195 NFS V3 MIXFILE: [ SFS Default ]
 196 CLIENT REQUESTED LOAD: 100 Ops/Sec
 197 TOTAL LOGICAL NFS V3 OPERATIONS:  29982          TEST TIME: 300 Sec
 198 TOTAL PHYSICAL NFS V3 OPERATIONS: 33180
 199 PHYSICAL NFS V3 THROUGHPUT:  110.60 Ops/Sec
 200 FILE SET SIZE CREATED: 11537568.0 KB
 201 FILE SET SIZE ACCESSED: 3466368.0 - 3536572.0 KB  (100.00% to 102.03% of Base)
 202
 203 ------------------------------------------------------------------------
 204
 205 Mon Apr 11 08:30:50 2011 Completed.
 206
 207 Client Test Parameters:
 208         Number of processes = 16
 209         Requested Load (NFS V3 operations/second) = 100
 210         Maximum number of outstanding biod writes = 8
 211         Maximum number of outstanding biod reads = 8
 212         Warm-up time (seconds) = 300
 213         Run time (seconds) = 300
 214         File Set = 21728 Files created for I/O operations
 215                    6512 Files accessed for I/O operations
 216                     448 Files for non-I/O operations
 217                      32 Symlinks
 218                     720 Directories
 219                         Additional non-I/O files created as necessary
 220
 221
 222 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 223 Mon Apr 11 08:30:50 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 224 Mon Apr 11 08:30:50 2011 Completed.
 225 Mon Apr 11 08:30:50 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 226
 227 ************************************************************************
 228 Mon Apr 11 08:30:51 2011 Received.
 229 Mon Apr 11 08:30:51 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 230 Mon Apr 11 08:30:51 2011 Completed.
 231 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 200  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
 232 NFS V3 Protocol Version
 233
 234 ************************************************************************
 235 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 236 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 237
 238 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 239 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 240
 241 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 08:30:53 2011
 242         Client hostname = c09-44
 243         Prime Client hostname = c09-44.sysnet.ucsd.edu
 244
 245 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 246 NFS V3 Protocol
 247 Mon Apr 11 08:30:53 2011 Mounting 16 remote test directories.
 248 Child[000]: NFS negotiated block size 32768
 249 Child[001]: NFS negotiated block size 32768
 250 Child[002]: NFS negotiated block size 32768
 251 Child[003]: NFS negotiated block size 32768
 252 Child[004]: NFS negotiated block size 32768
 253 Child[005]: NFS negotiated block size 32768
 254 Child[006]: NFS negotiated block size 32768
 255 Child[007]: NFS negotiated block size 32768
 256 Child[008]: NFS negotiated block size 32768
 257 Child[009]: NFS negotiated block size 32768
 258 Child[010]: NFS negotiated block size 32768
 259 Child[011]: NFS negotiated block size 32768
 260 Child[012]: NFS negotiated block size 32768
 261 Child[013]: NFS negotiated block size 32768
 262 Child[014]: NFS negotiated block size 32768
 263 Child[015]: NFS negotiated block size 32768
 264 Mon Apr 11 08:31:07 2011 Completed.
 265 Mon Apr 11 08:31:07 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 266 Mon Apr 11 08:31:07 2011 Completed.
 267 Mon Apr 11 08:31:07 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 268 Mon Apr 11 08:31:09 2011 Received.
 269
 270 Mon Apr 11 08:31:09 2011 Initializing test directories.
 271 Mon Apr 11 08:31:09 2011 Child 8 will create 95 directories.
 272 Mon Apr 11 08:31:09 2011 Child 15 will create 95 directories.
 273 Mon Apr 11 08:31:09 2011 Child 10 will create 95 directories.
 274 Mon Apr 11 08:31:09 2011 Child 0 will create 95 directories.
 275 Mon Apr 11 08:31:09 2011 Child 12 will create 95 directories.
 276 Mon Apr 11 08:31:09 2011 Child 2 will create 95 directories.
 277 Mon Apr 11 08:31:09 2011 Child 14 will create 95 directories.
 278 Mon Apr 11 08:31:09 2011 Child 1 will create 95 directories.
 279 Mon Apr 11 08:31:09 2011 Child 9 will create 95 directories.
 280 Mon Apr 11 08:31:09 2011 Child 11 will create 95 directories.
 281 Mon Apr 11 08:31:09 2011 Child 13 will create 95 directories.
 282 Mon Apr 11 08:31:09 2011 Child 3 will create 95 directories.
 283 Mon Apr 11 08:31:09 2011 Child 6 will create 95 directories.
 284 Mon Apr 11 08:31:09 2011 Child 4 will create 95 directories.
 285 Mon Apr 11 08:31:09 2011 Child 5 will create 95 directories.
 286 Mon Apr 11 08:31:09 2011 Child 7 will create 95 directories.
 287 Mon Apr 11 08:31:09 2011 Child 15 finished creating 95 directories.
 288 Mon Apr 11 08:31:09 2011 Child 15 will create 2897 files.
 289 Mon Apr 11 08:31:09 2011 Child 1 finished creating 95 directories.
 290 Mon Apr 11 08:31:09 2011 Child 1 will create 2897 files.
 291 Mon Apr 11 08:31:10 2011 Child 0 finished creating 95 directories.
 292 Mon Apr 11 08:31:10 2011 Child 0 will create 2897 files.
 293 Mon Apr 11 08:31:10 2011 Child 3 finished creating 95 directories.
 294 Mon Apr 11 08:31:10 2011 Child 3 will create 2897 files.
 295 Mon Apr 11 08:31:10 2011 Child 12 finished creating 95 directories.
 296 Mon Apr 11 08:31:10 2011 Child 12 will create 2897 files.
 297 Mon Apr 11 08:31:10 2011 Child 5 finished creating 95 directories.
 298 Mon Apr 11 08:31:10 2011 Child 5 will create 2897 files.
 299 Mon Apr 11 08:31:11 2011 Child 11 finished creating 95 directories.
 300 Mon Apr 11 08:31:11 2011 Child 11 will create 2897 files.
 301 Mon Apr 11 08:31:11 2011 Child 13 finished creating 95 directories.
 302 Mon Apr 11 08:31:11 2011 Child 13 will create 2897 files.
 303 Mon Apr 11 08:31:13 2011 Child 7 finished creating 95 directories.
 304 Mon Apr 11 08:31:13 2011 Child 7 will create 2897 files.
 305 Mon Apr 11 08:31:14 2011 Child 9 finished creating 95 directories.
 306 Mon Apr 11 08:31:14 2011 Child 9 will create 2897 files.
 307 Mon Apr 11 08:31:14 2011 Child 14 finished creating 95 directories.
 308 Mon Apr 11 08:31:14 2011 Child 14 will create 2897 files.
 309 Mon Apr 11 08:31:14 2011 Child 4 finished creating 95 directories.
 310 Mon Apr 11 08:31:14 2011 Child 4 will create 2897 files.
 311 Mon Apr 11 08:31:14 2011 Child 2 finished creating 95 directories.
 312 Mon Apr 11 08:31:14 2011 Child 2 will create 2897 files.
 313 Mon Apr 11 08:31:14 2011 Child 10 finished creating 95 directories.
 314 Mon Apr 11 08:31:14 2011 Child 10 will create 2897 files.
 315 Mon Apr 11 08:31:14 2011 Child 8 finished creating 95 directories.
 316 Mon Apr 11 08:31:14 2011 Child 8 will create 2897 files.
 317 Mon Apr 11 08:31:14 2011 Child 6 finished creating 95 directories.
 318 Mon Apr 11 08:31:14 2011 Child 6 will create 2897 files.
 319 Mon Apr 11 08:36:10 2011 Child 15 verified 2172 files (814 creates).
 320 Mon Apr 11 08:36:12 2011 Child 11 verified 2023 files (665 creates).
 321 Mon Apr 11 08:36:12 2011 Child 1 verified 2310 files (952 creates).
 322 Mon Apr 11 08:36:13 2011 Child 12 verified 2175 files (817 creates).
 323 Mon Apr 11 08:36:19 2011 Child 14 verified 1982 files (624 creates).
 324 Mon Apr 11 08:36:20 2011 Child 13 verified 2141 files (783 creates).
 325 Mon Apr 11 08:36:20 2011 Child 9 verified 1982 files (624 creates).
 326 Mon Apr 11 08:36:20 2011 Child 4 verified 2134 files (776 creates).
 327 Mon Apr 11 08:36:20 2011 Child 5 verified 2141 files (783 creates).
 328 Mon Apr 11 08:36:20 2011 Child 2 verified 1988 files (630 creates).
 329 Mon Apr 11 08:36:20 2011 Child 3 verified 2141 files (783 creates).
 330 Mon Apr 11 08:36:20 2011 Child 6 verified 1988 files (630 creates).
 331 Mon Apr 11 08:36:20 2011 Child 7 verified 2141 files (783 creates).
 332 Mon Apr 11 08:36:20 2011 Child 8 verified 1907 files (549 creates).
 333 Mon Apr 11 08:36:20 2011 Child 0 verified 2203 files (845 creates).
 334 Mon Apr 11 08:36:20 2011 Child 10 verified 1907 files (549 creates).
 335 Mon Apr 11 08:41:12 2011 Child 12 verified 2660 files (1302 creates).
 336 Mon Apr 11 08:41:12 2011 Child 15 verified 2547 files (1189 creates).
 337 Mon Apr 11 08:41:17 2011 Child 1 verified 2897 files, created 1511
 338 Mon Apr 11 08:41:17 2011 Child 1 will create 2 symlinks.
 339 Mon Apr 11 08:41:20 2011 Child 11 verified 2542 files (1184 creates).
 340 Mon Apr 11 08:41:20 2011 Child 13 verified 2652 files (1294 creates).
 341 Mon Apr 11 08:41:20 2011 Child 5 verified 2652 files (1294 creates).
 342 Mon Apr 11 08:41:20 2011 Child 4 verified 2652 files (1294 creates).
 343 Mon Apr 11 08:41:20 2011 Child 0 verified 2741 files (1383 creates).
 344 Mon Apr 11 08:41:20 2011 Child 2 verified 2602 files (1244 creates).
 345 Mon Apr 11 08:41:20 2011 Child 14 verified 2478 files (1120 creates).
 346 Mon Apr 11 08:41:20 2011 Child 3 verified 2561 files (1203 creates).
 347 Mon Apr 11 08:41:20 2011 Child 6 verified 2544 files (1186 creates).
 348 Mon Apr 11 08:41:20 2011 Child 7 verified 2561 files (1203 creates).
 349 Mon Apr 11 08:41:20 2011 Child 9 verified 2539 files (1181 creates).
 350 Mon Apr 11 08:41:21 2011 Child 8 verified 2459 files (1101 creates).
 351 Mon Apr 11 08:41:23 2011 Child 1 finished creating 2 symlinks.
 352 Mon Apr 11 08:41:29 2011 Child 10 verified 2501 files (1143 creates).
 353 Mon Apr 11 08:41:47 2011 Child 0 verified 2897 files, created 1511
 354 Mon Apr 11 08:41:47 2011 Child 0 will create 2 symlinks.
 355 Mon Apr 11 08:41:52 2011 Child 0 finished creating 2 symlinks.
 356 Mon Apr 11 08:42:00 2011 Child 12 verified 2897 files, created 1511
 357 Mon Apr 11 08:42:00 2011 Child 12 will create 2 symlinks.
 358 Mon Apr 11 08:42:01 2011 Child 12 finished creating 2 symlinks.
 359 Mon Apr 11 08:42:03 2011 Child 13 verified 2897 files, created 1511
 360 Mon Apr 11 08:42:03 2011 Child 13 will create 2 symlinks.
 361 Mon Apr 11 08:42:03 2011 Child 4 verified 2897 files, created 1511
 362 Mon Apr 11 08:42:03 2011 Child 4 will create 2 symlinks.
 363 Mon Apr 11 08:42:05 2011 Child 4 finished creating 2 symlinks.
 364 Mon Apr 11 08:42:05 2011 Child 13 finished creating 2 symlinks.
 365 Mon Apr 11 08:42:07 2011 Child 5 verified 2897 files, created 1511
 366 Mon Apr 11 08:42:07 2011 Child 5 will create 2 symlinks.
 367 Mon Apr 11 08:42:07 2011 Child 5 finished creating 2 symlinks.
 368 Mon Apr 11 08:42:17 2011 Child 2 verified 2897 files, created 1511
 369 Mon Apr 11 08:42:17 2011 Child 2 will create 2 symlinks.
 370 Mon Apr 11 08:42:20 2011 Child 3 verified 2897 files, created 1511
 371 Mon Apr 11 08:42:20 2011 Child 3 will create 2 symlinks.
 372 Mon Apr 11 08:42:24 2011 Child 6 verified 2897 files, created 1511
 373 Mon Apr 11 08:42:24 2011 Child 6 will create 2 symlinks.
 374 Mon Apr 11 08:42:24 2011 Child 6 finished creating 2 symlinks.
 375 Mon Apr 11 08:42:26 2011 Child 11 verified 2897 files, created 1511
 376 Mon Apr 11 08:42:26 2011 Child 11 will create 2 symlinks.
 377 Mon Apr 11 08:42:26 2011 Child 11 finished creating 2 symlinks.
 378 Mon Apr 11 08:42:26 2011 Child 7 verified 2897 files, created 1511
 379 Mon Apr 11 08:42:26 2011 Child 7 will create 2 symlinks.
 380 Mon Apr 11 08:42:26 2011 Child 7 finished creating 2 symlinks.
 381 Mon Apr 11 08:42:26 2011 Child 2 finished creating 2 symlinks.
 382 Mon Apr 11 08:42:26 2011 Child 3 finished creating 2 symlinks.
 383 Mon Apr 11 08:42:28 2011 Child 9 verified 2897 files, created 1511
 384 Mon Apr 11 08:42:28 2011 Child 9 will create 2 symlinks.
 385 Mon Apr 11 08:42:28 2011 Child 15 verified 2897 files, created 1511
 386 Mon Apr 11 08:42:28 2011 Child 15 will create 2 symlinks.
 387 Mon Apr 11 08:42:29 2011 Child 9 finished creating 2 symlinks.
 388 Mon Apr 11 08:42:29 2011 Child 15 finished creating 2 symlinks.
 389 Mon Apr 11 08:42:30 2011 Child 10 verified 2897 files, created 1511
 390 Mon Apr 11 08:42:30 2011 Child 10 will create 2 symlinks.
 391 Mon Apr 11 08:42:30 2011 Child 10 finished creating 2 symlinks.
 392 Mon Apr 11 08:42:31 2011 Child 14 verified 2897 files, created 1511
 393 Mon Apr 11 08:42:31 2011 Child 14 will create 2 symlinks.
 394 Mon Apr 11 08:42:31 2011 Child 14 finished creating 2 symlinks.
 395 Mon Apr 11 08:42:36 2011 Child 8 verified 2897 files, created 1511
 396 Mon Apr 11 08:42:36 2011 Child 8 will create 2 symlinks.
 397 Mon Apr 11 08:42:36 2011 Child 8 finished creating 2 symlinks.
 398 Mon Apr 11 08:42:37 2011 Completed.
 399 Mon Apr 11 08:42:37 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 400 Mon Apr 11 08:42:37 2011 Completed.
 401 Mon Apr 11 08:42:37 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 402 Mon Apr 11 08:42:38 2011 Received.
 403 Mon Apr 11 08:42:38 2011 Performing 300 seconds pretest warmup.
 404 Mon Apr 11 08:47:38 2011 Completed.
 405 Mon Apr 11 08:47:38 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 406 Mon Apr 11 08:47:38 2011 Completed.
 407 Mon Apr 11 08:47:38 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 408 Mon Apr 11 08:47:39 2011 Received.
 409 Mon Apr 11 08:47:39 2011 Starting 300 seconds test run.
 410 SFS Single Client (c09-44) Results, Mon Apr 11 08:52:49 2011
 411 --------------------------------------------------------------------------------------
 412 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 413 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 414 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 415            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 416 --------------------------------------------------------------------------------------
 417 getattr     26.0%    26.0%    15632    15632      0      0.19     0.06      0.00  4.7%
 418 setattr      4.0%     4.1%     2443     2443      0      1.28     2.19      0.06  4.9%
 419 lookup      24.0%    23.9%    14313    14313      0      0.20     0.07      0.00  4.4%
 420 readlink     1.0%     1.0%      621      621      0      0.19     0.05      0.02  0.2%
 421 read        18.0%    18.1%    10843    13426      0      2.15     4.90      0.04 36.4%
 422 write       10.0%     9.9%     5967     7276      0      3.17     8.23      0.07 29.5%
 423 create       1.0%     1.0%      599      599      0      3.12     3.62      0.15  2.9%
 424 remove       1.0%     1.0%      622      622      0      2.05     2.46      0.12  2.0%
 425 readdir      1.0%     1.0%      604      604      0      0.40     0.14      0.03  0.4%
 426 fsstat       1.0%     1.0%      616      616      0      0.19     0.04      0.02  0.2%
 427 access      11.0%    10.9%     6544     6544      0      0.19     0.06      0.01  2.0%
 428 commit       0.0%     0.0%        0     2600      0      0.00     0.00      0.00 11.2%
 429 readdirplus  2.0%     2.0%     1206     1206      0      0.64     0.08      0.02  1.2%
 430 --------------------------------------------------------------------------------------
 431
 432       --------------------------------------------------
 433       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 434       --------------------------+-----------------------
 435 SFS NFS V3 THROUGHPUT:  200.03 Ops/Sec   AVG. RESPONSE TIME:    1.06 Msec/Op
 436 TCP PROTOCOL
 437 FAST CALLS: 0
 438 NFS V3 MIXFILE: [ SFS Default ]
 439 CLIENT REQUESTED LOAD: 200 Ops/Sec
 440 TOTAL LOGICAL NFS V3 OPERATIONS:  60010          TEST TIME: 300 Sec
 441 TOTAL PHYSICAL NFS V3 OPERATIONS: 66502
 442 PHYSICAL NFS V3 THROUGHPUT:  221.67 Ops/Sec
 443 FILE SET SIZE CREATED: 24128640.0 KB
 444 FILE SET SIZE ACCESSED: 7247088.0 - 7391749.0 KB  (100.00% to 102.00% of Base)
 445
 446 ------------------------------------------------------------------------
 447
 448 Mon Apr 11 08:52:49 2011 Completed.
 449
 450 Client Test Parameters:
 451         Number of processes = 16
 452         Requested Load (NFS V3 operations/second) = 200
 453         Maximum number of outstanding biod writes = 8
 454         Maximum number of outstanding biod reads = 8
 455         Warm-up time (seconds) = 300
 456         Run time (seconds) = 300
 457         File Set = 45440 Files created for I/O operations
 458                    13632 Files accessed for I/O operations
 459                     912 Files for non-I/O operations
 460                      32 Symlinks
 461                    1504 Directories
 462                         Additional non-I/O files created as necessary
 463
 464
 465 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 466 Mon Apr 11 08:52:49 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 467 Mon Apr 11 08:52:49 2011 Completed.
 468 Mon Apr 11 08:52:49 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 469
 470 ************************************************************************
 471 Mon Apr 11 08:52:50 2011 Received.
 472 Mon Apr 11 08:52:50 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 473 Mon Apr 11 08:52:50 2011 Completed.
 474 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 300  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
 475 NFS V3 Protocol Version
 476
 477 ************************************************************************
 478 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 479 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 480
 481 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 482 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 483
 484 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 08:52:52 2011
 485         Client hostname = c09-44
 486         Prime Client hostname = c09-44.sysnet.ucsd.edu
 487
 488 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 489 NFS V3 Protocol
 490 Mon Apr 11 08:52:52 2011 Mounting 16 remote test directories.
 491 Child[000]: NFS negotiated block size 32768
 492 Child[001]: NFS negotiated block size 32768
 493 Child[002]: NFS negotiated block size 32768
 494 Child[003]: NFS negotiated block size 32768
 495 Child[004]: NFS negotiated block size 32768
 496 Child[005]: NFS negotiated block size 32768
 497 Child[006]: NFS negotiated block size 32768
 498 Child[007]: NFS negotiated block size 32768
 499 Child[008]: NFS negotiated block size 32768
 500 Child[009]: NFS negotiated block size 32768
 501 Child[010]: NFS negotiated block size 32768
 502 Child[011]: NFS negotiated block size 32768
 503 Child[012]: NFS negotiated block size 32768
 504 Child[013]: NFS negotiated block size 32768
 505 Child[014]: NFS negotiated block size 32768
 506 Child[015]: NFS negotiated block size 32768
 507 Mon Apr 11 08:53:06 2011 Completed.
 508 Mon Apr 11 08:53:06 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 509 Mon Apr 11 08:53:06 2011 Completed.
 510 Mon Apr 11 08:53:06 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 511 Mon Apr 11 08:53:08 2011 Received.
 512
 513 Mon Apr 11 08:53:08 2011 Initializing test directories.
 514 Mon Apr 11 08:53:08 2011 Child 15 will create 145 directories.
 515 Mon Apr 11 08:53:08 2011 Child 14 will create 145 directories.
 516 Mon Apr 11 08:53:08 2011 Child 13 will create 145 directories.
 517 Mon Apr 11 08:53:08 2011 Child 10 will create 145 directories.
 518 Mon Apr 11 08:53:08 2011 Child 4 will create 145 directories.
 519 Mon Apr 11 08:53:08 2011 Child 11 will create 145 directories.
 520 Mon Apr 11 08:53:08 2011 Child 0 will create 145 directories.
 521 Mon Apr 11 08:53:08 2011 Child 12 will create 145 directories.
 522 Mon Apr 11 08:53:08 2011 Child 5 will create 145 directories.
 523 Mon Apr 11 08:53:08 2011 Child 1 will create 145 directories.
 524 Mon Apr 11 08:53:08 2011 Child 9 will create 145 directories.
 525 Mon Apr 11 08:53:08 2011 Child 6 will create 145 directories.
 526 Mon Apr 11 08:53:08 2011 Child 2 will create 145 directories.
 527 Mon Apr 11 08:53:08 2011 Child 8 will create 145 directories.
 528 Mon Apr 11 08:53:08 2011 Child 7 will create 145 directories.
 529 Mon Apr 11 08:53:08 2011 Child 3 will create 145 directories.
 530 Mon Apr 11 08:53:08 2011 Child 4 finished creating 145 directories.
 531 Mon Apr 11 08:53:08 2011 Child 4 will create 4408 files.
 532 Mon Apr 11 08:53:08 2011 Child 5 finished creating 145 directories.
 533 Mon Apr 11 08:53:08 2011 Child 5 will create 4408 files.
 534 Mon Apr 11 08:53:09 2011 Child 1 finished creating 145 directories.
 535 Mon Apr 11 08:53:09 2011 Child 1 will create 4408 files.
 536 Mon Apr 11 08:53:09 2011 Child 3 finished creating 145 directories.
 537 Mon Apr 11 08:53:09 2011 Child 3 will create 4408 files.
 538 Mon Apr 11 08:53:10 2011 Child 10 finished creating 145 directories.
 539 Mon Apr 11 08:53:10 2011 Child 10 will create 4408 files.
 540 Mon Apr 11 08:53:11 2011 Child 13 finished creating 145 directories.
 541 Mon Apr 11 08:53:11 2011 Child 13 will create 4408 files.
 542 Mon Apr 11 08:53:11 2011 Child 15 finished creating 145 directories.
 543 Mon Apr 11 08:53:11 2011 Child 15 will create 4408 files.
 544 Mon Apr 11 08:53:11 2011 Child 6 finished creating 145 directories.
 545 Mon Apr 11 08:53:11 2011 Child 6 will create 4408 files.
 546 Mon Apr 11 08:53:14 2011 Child 9 finished creating 145 directories.
 547 Mon Apr 11 08:53:14 2011 Child 9 will create 4408 files.
 548 Mon Apr 11 08:53:14 2011 Child 7 finished creating 145 directories.
 549 Mon Apr 11 08:53:14 2011 Child 7 will create 4408 files.
 550 Mon Apr 11 08:53:17 2011 Child 12 finished creating 145 directories.
 551 Mon Apr 11 08:53:17 2011 Child 12 will create 4408 files.
 552 Mon Apr 11 08:53:18 2011 Child 14 finished creating 145 directories.
 553 Mon Apr 11 08:53:18 2011 Child 14 will create 4408 files.
 554 Mon Apr 11 08:53:18 2011 Child 8 finished creating 145 directories.
 555 Mon Apr 11 08:53:18 2011 Child 8 will create 4408 files.
 556 Mon Apr 11 08:53:18 2011 Child 0 finished creating 145 directories.
 557 Mon Apr 11 08:53:18 2011 Child 0 will create 4408 files.
 558 Mon Apr 11 08:53:18 2011 Child 2 finished creating 145 directories.
 559 Mon Apr 11 08:53:18 2011 Child 2 will create 4408 files.
 560 Mon Apr 11 08:53:18 2011 Child 11 finished creating 145 directories.
 561 Mon Apr 11 08:53:18 2011 Child 11 will create 4408 files.
 562 Mon Apr 11 08:58:10 2011 Child 5 verified 3766 files (926 creates).
 563 Mon Apr 11 08:58:10 2011 Child 1 verified 3767 files (927 creates).
 564 Mon Apr 11 08:58:11 2011 Child 3 verified 3616 files (776 creates).
 565 Mon Apr 11 08:58:12 2011 Child 13 verified 3496 files (656 creates).
 566 Mon Apr 11 08:58:22 2011 Child 11 verified 3338 files (498 creates).
 567 Mon Apr 11 08:58:22 2011 Child 14 verified 3407 files (567 creates).
 568 Mon Apr 11 08:58:23 2011 Child 0 verified 3449 files (609 creates).
 569 Mon Apr 11 08:58:29 2011 Child 6 verified 3505 files (665 creates).
 570 Mon Apr 11 08:58:29 2011 Child 9 verified 3505 files (665 creates).
 571 Mon Apr 11 08:58:29 2011 Child 12 verified 3505 files (665 creates).
 572 Mon Apr 11 08:58:29 2011 Child 7 verified 3544 files (704 creates).
 573 Mon Apr 11 08:58:29 2011 Child 2 verified 3456 files (616 creates).
 574 Mon Apr 11 08:58:29 2011 Child 10 verified 3743 files (903 creates).
 575 Mon Apr 11 08:58:29 2011 Child 4 verified 3743 files (903 creates).
 576 Mon Apr 11 08:58:29 2011 Child 8 verified 3456 files (616 creates).
 577 Mon Apr 11 08:58:29 2011 Child 15 verified 3505 files (665 creates).
 578 Mon Apr 11 09:01:47 2011 Child 1 verified 4408 files, created 1525
 579 Mon Apr 11 09:01:47 2011 Child 1 will create 2 symlinks.
 580 Mon Apr 11 09:01:47 2011 Child 1 finished creating 2 symlinks.
 581 Mon Apr 11 09:01:50 2011 Child 4 verified 4408 files, created 1525
 582 Mon Apr 11 09:01:50 2011 Child 4 will create 2 symlinks.
 583 Mon Apr 11 09:01:50 2011 Child 4 finished creating 2 symlinks.
 584 Mon Apr 11 09:01:57 2011 Child 5 verified 4408 files, created 1525
 585 Mon Apr 11 09:01:57 2011 Child 5 will create 2 symlinks.
 586 Mon Apr 11 09:01:57 2011 Child 5 finished creating 2 symlinks.
 587 Mon Apr 11 09:01:59 2011 Child 10 verified 4408 files, created 1525
 588 Mon Apr 11 09:01:59 2011 Child 10 will create 2 symlinks.
 589 Mon Apr 11 09:01:59 2011 Child 10 finished creating 2 symlinks.
 590 Mon Apr 11 09:02:15 2011 Child 3 verified 4408 files, created 1525
 591 Mon Apr 11 09:02:15 2011 Child 3 will create 2 symlinks.
 592 Mon Apr 11 09:02:21 2011 Child 3 finished creating 2 symlinks.
 593 Mon Apr 11 09:02:22 2011 Child 7 verified 4408 files, created 1525
 594 Mon Apr 11 09:02:22 2011 Child 7 will create 2 symlinks.
 595 Mon Apr 11 09:02:22 2011 Child 7 finished creating 2 symlinks.
 596 Mon Apr 11 09:02:33 2011 Child 6 verified 4408 files, created 1525
 597 Mon Apr 11 09:02:33 2011 Child 6 will create 2 symlinks.
 598 Mon Apr 11 09:02:34 2011 Child 6 finished creating 2 symlinks.
 599 Mon Apr 11 09:02:36 2011 Child 15 verified 4408 files, created 1525
 600 Mon Apr 11 09:02:36 2011 Child 15 will create 2 symlinks.
 601 Mon Apr 11 09:02:36 2011 Child 15 finished creating 2 symlinks.
 602 Mon Apr 11 09:02:36 2011 Child 13 verified 4408 files, created 1525
 603 Mon Apr 11 09:02:36 2011 Child 13 will create 2 symlinks.
 604 Mon Apr 11 09:02:37 2011 Child 13 finished creating 2 symlinks.
 605 Mon Apr 11 09:02:39 2011 Child 8 verified 4408 files, created 1525
 606 Mon Apr 11 09:02:39 2011 Child 8 will create 2 symlinks.
 607 Mon Apr 11 09:02:40 2011 Child 2 verified 4408 files, created 1525
 608 Mon Apr 11 09:02:40 2011 Child 2 will create 2 symlinks.
 609 Mon Apr 11 09:02:40 2011 Child 12 verified 4408 files, created 1525
 610 Mon Apr 11 09:02:40 2011 Child 12 will create 2 symlinks.
 611 Mon Apr 11 09:02:40 2011 Child 8 finished creating 2 symlinks.
 612 Mon Apr 11 09:02:40 2011 Child 2 finished creating 2 symlinks.
 613 Mon Apr 11 09:02:40 2011 Child 12 finished creating 2 symlinks.
 614 Mon Apr 11 09:02:40 2011 Child 0 verified 4408 files, created 1525
 615 Mon Apr 11 09:02:40 2011 Child 0 will create 2 symlinks.
 616 Mon Apr 11 09:02:40 2011 Child 0 finished creating 2 symlinks.
 617 Mon Apr 11 09:02:42 2011 Child 9 verified 4408 files, created 1525
 618 Mon Apr 11 09:02:42 2011 Child 9 will create 2 symlinks.
 619 Mon Apr 11 09:02:42 2011 Child 9 finished creating 2 symlinks.
 620 Mon Apr 11 09:02:42 2011 Child 11 verified 4408 files, created 1525
 621 Mon Apr 11 09:02:42 2011 Child 11 will create 2 symlinks.
 622 Mon Apr 11 09:02:42 2011 Child 14 verified 4408 files, created 1525
 623 Mon Apr 11 09:02:42 2011 Child 14 will create 2 symlinks.
 624 Mon Apr 11 09:02:42 2011 Child 11 finished creating 2 symlinks.
 625 Mon Apr 11 09:02:42 2011 Child 14 finished creating 2 symlinks.
 626 Mon Apr 11 09:02:43 2011 Completed.
 627 Mon Apr 11 09:02:43 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 628 Mon Apr 11 09:02:43 2011 Completed.
 629 Mon Apr 11 09:02:43 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 630 Mon Apr 11 09:02:43 2011 Received.
 631 Mon Apr 11 09:02:43 2011 Performing 300 seconds pretest warmup.
 632 Mon Apr 11 09:07:43 2011 Completed.
 633 Mon Apr 11 09:07:43 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 634 Mon Apr 11 09:07:43 2011 Completed.
 635 Mon Apr 11 09:07:43 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 636 Mon Apr 11 09:07:44 2011 Received.
 637 Mon Apr 11 09:07:44 2011 Starting 300 seconds test run.
 638 SFS Single Client (c09-44) Results, Mon Apr 11 09:12:54 2011
 639 --------------------------------------------------------------------------------------
 640 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 641 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 642 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 643            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 644 --------------------------------------------------------------------------------------
 645 getattr     26.0%    26.0%    23377    23377      0      0.20     0.08      0.00  3.4%
 646 setattr      4.0%     4.0%     3604     3604      0      1.68     3.74      0.06  4.6%
 647 lookup      24.0%    24.1%    21657    21657      0      0.20     0.09      0.00  3.3%
 648 readlink     1.0%     1.0%      916      916      0      0.20     0.08      0.02  0.1%
 649 read        18.0%    17.9%    16140    19782      0      3.45    21.28      0.07 42.1%
 650 write       10.0%    10.1%     9116    11149      0      4.13    10.34      0.07 28.5%
 651 create       1.0%     1.0%      936      936      0      3.90     3.63      0.12  2.8%
 652 remove       1.0%     1.0%      868      868      0      3.27     8.69      0.20  2.1%
 653 readdir      1.0%     1.0%      858      858      0      0.40     0.09      0.02  0.3%
 654 fsstat       1.0%     1.0%      926      926      0      0.20     0.07      0.02  0.1%
 655 access      11.0%    11.0%     9867     9867      0      0.20     0.08      0.01  1.5%
 656 commit       0.0%     0.0%        0     4009      0      0.00     0.00      0.00 10.3%
 657 readdirplus  2.0%     2.0%     1778     1778      0      0.69     0.11      0.02  0.9%
 658 --------------------------------------------------------------------------------------
 659
 660       --------------------------------------------------
 661       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 662       --------------------------+-----------------------
 663 SFS NFS V3 THROUGHPUT:  300.14 Ops/Sec   AVG. RESPONSE TIME:    1.47 Msec/Op
 664 TCP PROTOCOL
 665 FAST CALLS: 0
 666 NFS V3 MIXFILE: [ SFS Default ]
 667 CLIENT REQUESTED LOAD: 300 Ops/Sec
 668 TOTAL LOGICAL NFS V3 OPERATIONS:  90043          TEST TIME: 300 Sec
 669 TOTAL PHYSICAL NFS V3 OPERATIONS: 99727
 670 PHYSICAL NFS V3 THROUGHPUT:  332.42 Ops/Sec
 671 FILE SET SIZE CREATED: 36711216.0 KB
 672 FILE SET SIZE ACCESSED: 11019312.0 - 11236756.0 KB  (100.00% to 101.97% of Base)
 673
 674 ------------------------------------------------------------------------
 675
 676 Mon Apr 11 09:12:54 2011 Completed.
 677
 678 Client Test Parameters:
 679         Number of processes = 16
 680         Requested Load (NFS V3 operations/second) = 300
 681         Maximum number of outstanding biod writes = 8
 682         Maximum number of outstanding biod reads = 8
 683         Warm-up time (seconds) = 300
 684         Run time (seconds) = 300
 685         File Set = 69136 Files created for I/O operations
 686                    20736 Files accessed for I/O operations
 687                    1392 Files for non-I/O operations
 688                      32 Symlinks
 689                    2304 Directories
 690                         Additional non-I/O files created as necessary
 691
 692
 693 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 694 Mon Apr 11 09:12:54 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 695 Mon Apr 11 09:12:54 2011 Completed.
 696 Mon Apr 11 09:12:54 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 697
 698 ************************************************************************
 699 Mon Apr 11 09:12:55 2011 Received.
 700 Mon Apr 11 09:12:55 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 701 Mon Apr 11 09:12:55 2011 Completed.
 702 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 400  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
 703 NFS V3 Protocol Version
 704
 705 ************************************************************************
 706 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 707 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 708
 709 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 710 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 711
 712 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 09:12:57 2011
 713         Client hostname = c09-44
 714         Prime Client hostname = c09-44.sysnet.ucsd.edu
 715
 716 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 717 NFS V3 Protocol
 718 Mon Apr 11 09:12:57 2011 Mounting 16 remote test directories.
 719 Child[000]: NFS negotiated block size 32768
 720 Child[001]: NFS negotiated block size 32768
 721 Child[002]: NFS negotiated block size 32768
 722 Child[003]: NFS negotiated block size 32768
 723 Child[004]: NFS negotiated block size 32768
 724 Child[005]: NFS negotiated block size 32768
 725 Child[006]: NFS negotiated block size 32768
 726 Child[007]: NFS negotiated block size 32768
 727 Child[008]: NFS negotiated block size 32768
 728 Child[009]: NFS negotiated block size 32768
 729 Child[010]: NFS negotiated block size 32768
 730 Child[011]: NFS negotiated block size 32768
 731 Child[012]: NFS negotiated block size 32768
 732 Child[013]: NFS negotiated block size 32768
 733 Child[014]: NFS negotiated block size 32768
 734 Child[015]: NFS negotiated block size 32768
 735 Mon Apr 11 09:13:11 2011 Completed.
 736 Mon Apr 11 09:13:11 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 737 Mon Apr 11 09:13:11 2011 Completed.
 738 Mon Apr 11 09:13:11 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 739 Mon Apr 11 09:13:13 2011 Received.
 740
 741 Mon Apr 11 09:13:13 2011 Initializing test directories.
 742 Mon Apr 11 09:13:13 2011 Child 11 will create 190 directories.
 743 Mon Apr 11 09:13:13 2011 Child 6 will create 190 directories.
 744 Mon Apr 11 09:13:13 2011 Child 9 will create 190 directories.
 745 Mon Apr 11 09:13:13 2011 Child 5 will create 190 directories.
 746 Mon Apr 11 09:13:13 2011 Child 1 will create 190 directories.
 747 Mon Apr 11 09:13:13 2011 Child 12 will create 190 directories.
 748 Mon Apr 11 09:13:13 2011 Child 0 will create 190 directories.
 749 Mon Apr 11 09:13:13 2011 Child 3 will create 190 directories.
 750 Mon Apr 11 09:13:13 2011 Child 8 will create 190 directories.
 751 Mon Apr 11 09:13:13 2011 Child 14 will create 190 directories.
 752 Mon Apr 11 09:13:13 2011 Child 13 will create 190 directories.
 753 Mon Apr 11 09:13:13 2011 Child 7 will create 190 directories.
 754 Mon Apr 11 09:13:13 2011 Child 15 will create 190 directories.
 755 Mon Apr 11 09:13:13 2011 Child 2 will create 190 directories.
 756 Mon Apr 11 09:13:13 2011 Child 4 will create 190 directories.
 757 Mon Apr 11 09:13:13 2011 Child 10 will create 190 directories.
 758 Mon Apr 11 09:13:14 2011 Child 2 finished creating 190 directories.
 759 Mon Apr 11 09:13:14 2011 Child 2 will create 5793 files.
 760 Mon Apr 11 09:13:15 2011 Child 15 finished creating 190 directories.
 761 Mon Apr 11 09:13:15 2011 Child 15 will create 5793 files.
 762 Mon Apr 11 09:13:15 2011 Child 6 finished creating 190 directories.
 763 Mon Apr 11 09:13:15 2011 Child 6 will create 5793 files.
 764 Mon Apr 11 09:13:17 2011 Child 10 finished creating 190 directories.
 765 Mon Apr 11 09:13:17 2011 Child 10 will create 5793 files.
 766 Mon Apr 11 09:13:17 2011 Child 7 finished creating 190 directories.
 767 Mon Apr 11 09:13:17 2011 Child 7 will create 5793 files.
 768 Mon Apr 11 09:13:18 2011 Child 0 finished creating 190 directories.
 769 Mon Apr 11 09:13:18 2011 Child 0 will create 5793 files.
 770 Mon Apr 11 09:13:18 2011 Child 8 finished creating 190 directories.
 771 Mon Apr 11 09:13:18 2011 Child 8 will create 5793 files.
 772 Mon Apr 11 09:13:19 2011 Child 13 finished creating 190 directories.
 773 Mon Apr 11 09:13:19 2011 Child 13 will create 5793 files.
 774 Mon Apr 11 09:13:27 2011 Child 14 finished creating 190 directories.
 775 Mon Apr 11 09:13:27 2011 Child 14 will create 5793 files.
 776 Mon Apr 11 09:13:27 2011 Child 4 finished creating 190 directories.
 777 Mon Apr 11 09:13:27 2011 Child 4 will create 5793 files.
 778 Mon Apr 11 09:13:27 2011 Child 12 finished creating 190 directories.
 779 Mon Apr 11 09:13:27 2011 Child 12 will create 5793 files.
 780 Mon Apr 11 09:13:27 2011 Child 5 finished creating 190 directories.
 781 Mon Apr 11 09:13:27 2011 Child 5 will create 5793 files.
 782 Mon Apr 11 09:13:27 2011 Child 9 finished creating 190 directories.
 783 Mon Apr 11 09:13:27 2011 Child 9 will create 5793 files.
 784 Mon Apr 11 09:13:27 2011 Child 11 finished creating 190 directories.
 785 Mon Apr 11 09:13:27 2011 Child 11 will create 5793 files.
 786 Mon Apr 11 09:13:29 2011 Child 3 finished creating 190 directories.
 787 Mon Apr 11 09:13:29 2011 Child 3 will create 5793 files.
 788 Mon Apr 11 09:13:30 2011 Child 1 finished creating 190 directories.
 789 Mon Apr 11 09:13:30 2011 Child 1 will create 5793 files.
 790 Mon Apr 11 09:18:31 2011 Child 8 verified 5078 files (757 creates).
 791 Mon Apr 11 09:18:32 2011 Child 0 verified 5145 files (824 creates).
 792 Mon Apr 11 09:18:32 2011 Child 13 verified 5145 files (824 creates).
 793 Mon Apr 11 09:18:32 2011 Child 6 verified 5269 files (948 creates).
 794 Mon Apr 11 09:18:32 2011 Child 4 verified 4962 files (641 creates).
 795 Mon Apr 11 09:18:32 2011 Child 7 verified 5269 files (948 creates).
 796 Mon Apr 11 09:18:32 2011 Child 15 verified 5269 files (948 creates).
 797 Mon Apr 11 09:18:32 2011 Child 10 verified 5275 files (954 creates).
 798 Mon Apr 11 09:18:32 2011 Child 14 verified 4879 files (558 creates).
 799 Mon Apr 11 09:18:32 2011 Child 9 verified 4875 files (554 creates).
 800 Mon Apr 11 09:18:32 2011 Child 11 verified 4956 files (635 creates).
 801 Mon Apr 11 09:18:32 2011 Child 5 verified 4991 files (670 creates).
 802 Mon Apr 11 09:18:32 2011 Child 12 verified 4962 files (641 creates).
 803 Mon Apr 11 09:18:32 2011 Child 2 verified 5362 files (1041 creates).
 804 Mon Apr 11 09:18:32 2011 Child 1 verified 4880 files (559 creates).
 805 Mon Apr 11 09:18:32 2011 Child 3 verified 4951 files (630 creates).
 806 Mon Apr 11 09:19:51 2011 Child 2 verified 5793 files, created 1416
 807 Mon Apr 11 09:19:51 2011 Child 2 will create 2 symlinks.
 808 Mon Apr 11 09:19:51 2011 Child 2 finished creating 2 symlinks.
 809 Mon Apr 11 09:20:27 2011 Child 6 verified 5793 files, created 1416
 810 Mon Apr 11 09:20:27 2011 Child 6 will create 2 symlinks.
 811 Mon Apr 11 09:20:27 2011 Child 6 finished creating 2 symlinks.
 812 Mon Apr 11 09:20:27 2011 Child 7 verified 5793 files, created 1416
 813 Mon Apr 11 09:20:27 2011 Child 7 will create 2 symlinks.
 814 Mon Apr 11 09:20:27 2011 Child 7 finished creating 2 symlinks.
 815 Mon Apr 11 09:20:28 2011 Child 15 verified 5793 files, created 1416
 816 Mon Apr 11 09:20:28 2011 Child 15 will create 2 symlinks.
 817 Mon Apr 11 09:20:28 2011 Child 15 finished creating 2 symlinks.
 818 Mon Apr 11 09:20:35 2011 Child 10 verified 5793 files, created 1416
 819 Mon Apr 11 09:20:35 2011 Child 10 will create 2 symlinks.
 820 Mon Apr 11 09:20:35 2011 Child 10 finished creating 2 symlinks.
 821 Mon Apr 11 09:20:54 2011 Child 5 verified 5793 files, created 1416
 822 Mon Apr 11 09:20:54 2011 Child 5 will create 2 symlinks.
 823 Mon Apr 11 09:20:54 2011 Child 5 finished creating 2 symlinks.
 824 Mon Apr 11 09:20:54 2011 Child 13 verified 5793 files, created 1416
 825 Mon Apr 11 09:20:54 2011 Child 13 will create 2 symlinks.
 826 Mon Apr 11 09:20:54 2011 Child 13 finished creating 2 symlinks.
 827 Mon Apr 11 09:20:56 2011 Child 8 verified 5793 files, created 1416
 828 Mon Apr 11 09:20:56 2011 Child 8 will create 2 symlinks.
 829 Mon Apr 11 09:20:56 2011 Child 0 verified 5793 files, created 1416
 830 Mon Apr 11 09:20:56 2011 Child 0 will create 2 symlinks.
 831 Mon Apr 11 09:20:56 2011 Child 8 finished creating 2 symlinks.
 832 Mon Apr 11 09:20:56 2011 Child 0 finished creating 2 symlinks.
 833 Mon Apr 11 09:21:05 2011 Child 12 verified 5793 files, created 1416
 834 Mon Apr 11 09:21:05 2011 Child 12 will create 2 symlinks.
 835 Mon Apr 11 09:21:05 2011 Child 12 finished creating 2 symlinks.
 836 Mon Apr 11 09:21:07 2011 Child 4 verified 5793 files, created 1416
 837 Mon Apr 11 09:21:07 2011 Child 4 will create 2 symlinks.
 838 Mon Apr 11 09:21:07 2011 Child 4 finished creating 2 symlinks.
 839 Mon Apr 11 09:21:07 2011 Child 3 verified 5793 files, created 1416
 840 Mon Apr 11 09:21:07 2011 Child 3 will create 2 symlinks.
 841 Mon Apr 11 09:21:07 2011 Child 3 finished creating 2 symlinks.
 842 Mon Apr 11 09:21:10 2011 Child 11 verified 5793 files, created 1416
 843 Mon Apr 11 09:21:10 2011 Child 11 will create 2 symlinks.
 844 Mon Apr 11 09:21:10 2011 Child 11 finished creating 2 symlinks.
 845 Mon Apr 11 09:21:13 2011 Child 1 verified 5793 files, created 1416
 846 Mon Apr 11 09:21:13 2011 Child 1 will create 2 symlinks.
 847 Mon Apr 11 09:21:13 2011 Child 1 finished creating 2 symlinks.
 848 Mon Apr 11 09:21:13 2011 Child 14 verified 5793 files, created 1416
 849 Mon Apr 11 09:21:13 2011 Child 14 will create 2 symlinks.
 850 Mon Apr 11 09:21:13 2011 Child 14 finished creating 2 symlinks.
 851 Mon Apr 11 09:21:14 2011 Child 9 verified 5793 files, created 1416
 852 Mon Apr 11 09:21:14 2011 Child 9 will create 2 symlinks.
 853 Mon Apr 11 09:21:14 2011 Child 9 finished creating 2 symlinks.
 854 Mon Apr 11 09:21:15 2011 Completed.
 855 Mon Apr 11 09:21:15 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 856 Mon Apr 11 09:21:15 2011 Completed.
 857 Mon Apr 11 09:21:15 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 858 Mon Apr 11 09:21:15 2011 Received.
 859 Mon Apr 11 09:21:15 2011 Performing 300 seconds pretest warmup.
 860 Mon Apr 11 09:26:15 2011 Completed.
 861 Mon Apr 11 09:26:15 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 862 Mon Apr 11 09:26:15 2011 Completed.
 863 Mon Apr 11 09:26:15 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 864 Mon Apr 11 09:26:16 2011 Received.
 865 Mon Apr 11 09:26:16 2011 Starting 300 seconds test run.
 866 SFS Single Client (c09-44) Results, Mon Apr 11 09:31:26 2011
 867 --------------------------------------------------------------------------------------
 868 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 869 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 870 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 871            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 872 --------------------------------------------------------------------------------------
 873 getattr     26.0%    25.9%    31143    31143      0      0.20     0.83      0.01  2.4%
 874 setattr      4.0%     4.0%     4792     4792      0      2.54     5.58      0.07  4.6%
 875 lookup      24.0%    23.8%    28621    28621      0      0.23     1.99      0.02  2.5%
 876 readlink     1.0%     1.0%     1221     1221      0      0.20     0.14      0.02  0.1%
 877 read        18.0%    18.2%    21822    26865      0      4.91    28.24      0.07 41.0%
 878 write       10.0%    10.1%    12119    14893      0      7.43    15.62      0.07 34.5%
 879 create       1.0%     1.0%     1177     1177      0      4.96     6.91      0.15  2.2%
 880 remove       1.0%     1.0%     1209     1209      0      3.95     5.11      0.13  1.8%
 881 readdir      1.0%     1.0%     1183     1183      0      0.41     0.12      0.02  0.2%
 882 fsstat       1.0%     1.0%     1254     1254      0      0.20     0.07      0.01  0.1%
 883 access      11.0%    11.0%    13175    13175      0      0.21     1.14      0.02  1.0%
 884 commit       0.0%     0.0%        0     5347      0      0.00     0.00      0.00  8.8%
 885 readdirplus  2.0%     2.0%     2389     2389      0      0.70     0.12      0.01  0.6%
 886 --------------------------------------------------------------------------------------
 887
 888       --------------------------------------------------
 889       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 890       --------------------------+-----------------------
 891 SFS NFS V3 THROUGHPUT:  400.35 Ops/Sec   AVG. RESPONSE TIME:    2.17 Msec/Op
 892 TCP PROTOCOL
 893 FAST CALLS: 0
 894 NFS V3 MIXFILE: [ SFS Default ]
 895 CLIENT REQUESTED LOAD: 400 Ops/Sec
 896 TOTAL LOGICAL NFS V3 OPERATIONS:  120105         TEST TIME: 300 Sec
 897 TOTAL PHYSICAL NFS V3 OPERATIONS: 133269
 898 PHYSICAL NFS V3 THROUGHPUT:  444.23 Ops/Sec
 899 FILE SET SIZE CREATED: 48248784.0 KB
 900 FILE SET SIZE ACCESSED: 14477184.0 - 14763481.0 KB  (100.00% to 101.98% of Base)
 901
 902 ------------------------------------------------------------------------
 903
 904 Mon Apr 11 09:31:26 2011 Completed.
 905
 906 Client Test Parameters:
 907         Number of processes = 16
 908         Requested Load (NFS V3 operations/second) = 400
 909         Maximum number of outstanding biod writes = 8
 910         Maximum number of outstanding biod reads = 8
 911         Warm-up time (seconds) = 300
 912         Run time (seconds) = 300
 913         File Set = 90864 Files created for I/O operations
 914                    27248 Files accessed for I/O operations
 915                    1824 Files for non-I/O operations
 916                      32 Symlinks
 917                    3024 Directories
 918                         Additional non-I/O files created as necessary
 919
 920
 921 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 922 Mon Apr 11 09:31:26 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 923 Mon Apr 11 09:31:26 2011 Completed.
 924 Mon Apr 11 09:31:26 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 925
 926 ************************************************************************
 927 Mon Apr 11 09:31:27 2011 Received.
 928 Mon Apr 11 09:31:27 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 929 Mon Apr 11 09:31:27 2011 Completed.
 930 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 500  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
 931 NFS V3 Protocol Version
 932
 933 ************************************************************************
 934 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 935 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 936
 937 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 938 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 939
 940 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 09:31:29 2011
 941         Client hostname = c09-44
 942         Prime Client hostname = c09-44.sysnet.ucsd.edu
 943
 944 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 945 NFS V3 Protocol
 946 Mon Apr 11 09:31:29 2011 Mounting 16 remote test directories.
 947 Child[000]: NFS negotiated block size 32768
 948 Child[001]: NFS negotiated block size 32768
 949 Child[002]: NFS negotiated block size 32768
 950 Child[003]: NFS negotiated block size 32768
 951 Child[004]: NFS negotiated block size 32768
 952 Child[005]: NFS negotiated block size 32768
 953 Child[006]: NFS negotiated block size 32768
 954 Child[007]: NFS negotiated block size 32768
 955 Child[008]: NFS negotiated block size 32768
 956 Child[009]: NFS negotiated block size 32768
 957 Child[010]: NFS negotiated block size 32768
 958 Child[011]: NFS negotiated block size 32768
 959 Child[012]: NFS negotiated block size 32768
 960 Child[013]: NFS negotiated block size 32768
 961 Child[014]: NFS negotiated block size 32768
 962 Child[015]: NFS negotiated block size 32768
 963 Mon Apr 11 09:31:43 2011 Completed.
 964 Mon Apr 11 09:31:43 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 965 Mon Apr 11 09:31:43 2011 Completed.
 966 Mon Apr 11 09:31:43 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 967 Mon Apr 11 09:31:45 2011 Received.
 968
 969 Mon Apr 11 09:31:45 2011 Initializing test directories.
 970 Mon Apr 11 09:31:45 2011 Child 4 will create 239 directories.
 971 Mon Apr 11 09:31:45 2011 Child 15 will create 239 directories.
 972 Mon Apr 11 09:31:45 2011 Child 11 will create 239 directories.
 973 Mon Apr 11 09:31:45 2011 Child 0 will create 239 directories.
 974 Mon Apr 11 09:31:45 2011 Child 14 will create 239 directories.
 975 Mon Apr 11 09:31:45 2011 Child 10 will create 239 directories.
 976 Mon Apr 11 09:31:45 2011 Child 1 will create 239 directories.
 977 Mon Apr 11 09:31:45 2011 Child 13 will create 239 directories.
 978 Mon Apr 11 09:31:45 2011 Child 6 will create 239 directories.
 979 Mon Apr 11 09:31:45 2011 Child 2 will create 239 directories.
 980 Mon Apr 11 09:31:45 2011 Child 12 will create 239 directories.
 981 Mon Apr 11 09:31:45 2011 Child 5 will create 239 directories.
 982 Mon Apr 11 09:31:45 2011 Child 7 will create 239 directories.
 983 Mon Apr 11 09:31:45 2011 Child 9 will create 239 directories.
 984 Mon Apr 11 09:31:45 2011 Child 3 will create 239 directories.
 985 Mon Apr 11 09:31:45 2011 Child 8 will create 239 directories.
 986 Mon Apr 11 09:31:46 2011 Child 2 finished creating 239 directories.
 987 Mon Apr 11 09:31:46 2011 Child 2 will create 7304 files.
 988 Mon Apr 11 09:31:47 2011 Child 6 finished creating 239 directories.
 989 Mon Apr 11 09:31:47 2011 Child 6 will create 7304 files.
 990 Mon Apr 11 09:31:47 2011 Child 3 finished creating 239 directories.
 991 Mon Apr 11 09:31:47 2011 Child 3 will create 7304 files.
 992 Mon Apr 11 09:31:50 2011 Child 10 finished creating 239 directories.
 993 Mon Apr 11 09:31:50 2011 Child 10 will create 7304 files.
 994 Mon Apr 11 09:31:51 2011 Child 12 finished creating 239 directories.
 995 Mon Apr 11 09:31:51 2011 Child 12 will create 7304 files.
 996 Mon Apr 11 09:31:54 2011 Child 15 finished creating 239 directories.
 997 Mon Apr 11 09:31:54 2011 Child 15 will create 7304 files.
 998 Mon Apr 11 09:31:54 2011 Child 9 finished creating 239 directories.
 999 Mon Apr 11 09:31:54 2011 Child 9 will create 7304 files.
1000 Mon Apr 11 09:31:57 2011 Child 4 finished creating 239 directories.
1001 Mon Apr 11 09:31:57 2011 Child 4 will create 7304 files.
1002 Mon Apr 11 09:31:57 2011 Child 11 finished creating 239 directories.
1003 Mon Apr 11 09:31:57 2011 Child 11 will create 7304 files.
1004 Mon Apr 11 09:31:58 2011 Child 0 finished creating 239 directories.
1005 Mon Apr 11 09:31:58 2011 Child 0 will create 7304 files.
1006 Mon Apr 11 09:31:58 2011 Child 1 finished creating 239 directories.
1007 Mon Apr 11 09:31:58 2011 Child 1 will create 7304 files.
1008 Mon Apr 11 09:32:00 2011 Child 7 finished creating 239 directories.
1009 Mon Apr 11 09:32:00 2011 Child 7 will create 7304 files.
1010 Mon Apr 11 09:32:02 2011 Child 13 finished creating 239 directories.
1011 Mon Apr 11 09:32:02 2011 Child 13 will create 7304 files.
1012 Mon Apr 11 09:32:03 2011 Child 5 finished creating 239 directories.
1013 Mon Apr 11 09:32:03 2011 Child 5 will create 7304 files.
1014 Mon Apr 11 09:32:03 2011 Child 14 finished creating 239 directories.
1015 Mon Apr 11 09:32:03 2011 Child 14 will create 7304 files.
1016 Mon Apr 11 09:32:05 2011 Child 8 finished creating 239 directories.
1017 Mon Apr 11 09:32:05 2011 Child 8 will create 7304 files.
1018 Mon Apr 11 09:36:52 2011 Child 12 verified 6704 files (1025 creates).
1019 Mon Apr 11 09:36:52 2011 Child 6 verified 6985 files (1306 creates).
1020 Mon Apr 11 09:36:52 2011 Child 3 verified 6853 files (1174 creates).
1021 Mon Apr 11 09:36:52 2011 Child 2 verified 6862 files (1183 creates).
1022 Mon Apr 11 09:36:59 2011 Child 15 verified 6515 files (836 creates).
1023 Mon Apr 11 09:36:59 2011 Child 9 verified 6543 files (864 creates).
1024 Mon Apr 11 09:36:59 2011 Child 4 verified 6500 files (821 creates).
1025 Mon Apr 11 09:36:59 2011 Child 0 verified 6516 files (837 creates).
1026 Mon Apr 11 09:37:01 2011 Child 7 verified 6528 files (849 creates).
1027 Mon Apr 11 09:37:08 2011 Child 14 verified 6456 files (777 creates).
1028 Mon Apr 11 09:37:08 2011 Child 1 verified 6456 files (777 creates).
1029 Mon Apr 11 09:37:19 2011 Child 11 verified 6456 files (777 creates).
1030 Mon Apr 11 09:37:19 2011 Child 5 verified 6515 files (836 creates).
1031 Mon Apr 11 09:37:33 2011 Child 8 verified 6456 files (777 creates).
1032 Mon Apr 11 09:37:33 2011 Child 13 verified 6456 files (777 creates).
1033 Mon Apr 11 09:37:33 2011 Child 10 verified 6543 files (864 creates).
1034 Mon Apr 11 09:38:51 2011 Child 6 verified 7304 files, created 1554
1035 Mon Apr 11 09:38:51 2011 Child 6 will create 2 symlinks.
1036 Mon Apr 11 09:38:51 2011 Child 6 finished creating 2 symlinks.
1037 Mon Apr 11 09:39:09 2011 Child 2 verified 7304 files, created 1554
1038 Mon Apr 11 09:39:09 2011 Child 2 will create 2 symlinks.
1039 Mon Apr 11 09:39:10 2011 Child 3 verified 7304 files, created 1554
1040 Mon Apr 11 09:39:10 2011 Child 3 will create 2 symlinks.
1041 Mon Apr 11 09:39:10 2011 Child 2 finished creating 2 symlinks.
1042 Mon Apr 11 09:39:11 2011 Child 3 finished creating 2 symlinks.
1043 Mon Apr 11 09:39:34 2011 Child 12 verified 7304 files, created 1554
1044 Mon Apr 11 09:39:34 2011 Child 12 will create 2 symlinks.
1045 Mon Apr 11 09:39:34 2011 Child 12 finished creating 2 symlinks.
1046 Mon Apr 11 09:39:59 2011 Child 9 verified 7304 files, created 1554
1047 Mon Apr 11 09:39:59 2011 Child 9 will create 2 symlinks.
1048 Mon Apr 11 09:39:59 2011 Child 9 finished creating 2 symlinks.
1049 Mon Apr 11 09:40:21 2011 Child 10 verified 7304 files, created 1554
1050 Mon Apr 11 09:40:21 2011 Child 10 will create 2 symlinks.
1051 Mon Apr 11 09:40:21 2011 Child 5 verified 7304 files, created 1554
1052 Mon Apr 11 09:40:21 2011 Child 5 will create 2 symlinks.
1053 Mon Apr 11 09:40:21 2011 Child 10 finished creating 2 symlinks.
1054 Mon Apr 11 09:40:21 2011 Child 5 finished creating 2 symlinks.
1055 Mon Apr 11 09:40:22 2011 Child 15 verified 7304 files, created 1554
1056 Mon Apr 11 09:40:22 2011 Child 15 will create 2 symlinks.
1057 Mon Apr 11 09:40:32 2011 Child 15 finished creating 2 symlinks.
1058 Mon Apr 11 09:40:33 2011 Child 4 verified 7304 files, created 1554
1059 Mon Apr 11 09:40:33 2011 Child 4 will create 2 symlinks.
1060 Mon Apr 11 09:40:34 2011 Child 4 finished creating 2 symlinks.
1061 Mon Apr 11 09:40:34 2011 Child 0 verified 7304 files, created 1554
1062 Mon Apr 11 09:40:34 2011 Child 0 will create 2 symlinks.
1063 Mon Apr 11 09:40:34 2011 Child 0 finished creating 2 symlinks.
1064 Mon Apr 11 09:40:40 2011 Child 7 verified 7304 files, created 1554
1065 Mon Apr 11 09:40:40 2011 Child 7 will create 2 symlinks.
1066 Mon Apr 11 09:40:40 2011 Child 7 finished creating 2 symlinks.
1067 Mon Apr 11 09:40:41 2011 Child 11 verified 7304 files, created 1554
1068 Mon Apr 11 09:40:41 2011 Child 11 will create 2 symlinks.
1069 Mon Apr 11 09:40:41 2011 Child 13 verified 7304 files, created 1554
1070 Mon Apr 11 09:40:41 2011 Child 13 will create 2 symlinks.
1071 Mon Apr 11 09:40:41 2011 Child 14 verified 7304 files, created 1554
1072 Mon Apr 11 09:40:41 2011 Child 14 will create 2 symlinks.
1073 Mon Apr 11 09:40:41 2011 Child 8 verified 7304 files, created 1554
1074 Mon Apr 11 09:40:41 2011 Child 8 will create 2 symlinks.
1075 Mon Apr 11 09:40:41 2011 Child 11 finished creating 2 symlinks.
1076 Mon Apr 11 09:40:41 2011 Child 13 finished creating 2 symlinks.
1077 Mon Apr 11 09:40:41 2011 Child 1 verified 7304 files, created 1554
1078 Mon Apr 11 09:40:41 2011 Child 1 will create 2 symlinks.
1079 Mon Apr 11 09:40:41 2011 Child 8 finished creating 2 symlinks.
1080 Mon Apr 11 09:40:41 2011 Child 14 finished creating 2 symlinks.
1081 Mon Apr 11 09:40:41 2011 Child 1 finished creating 2 symlinks.
1082 Mon Apr 11 09:40:42 2011 Completed.
1083 Mon Apr 11 09:40:42 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1084 Mon Apr 11 09:40:42 2011 Completed.
1085 Mon Apr 11 09:40:42 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1086 Mon Apr 11 09:40:42 2011 Received.
1087 Mon Apr 11 09:40:42 2011 Performing 300 seconds pretest warmup.
1088 Mon Apr 11 09:45:42 2011 Completed.
1089 Mon Apr 11 09:45:42 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1090 Mon Apr 11 09:45:42 2011 Completed.
1091 Mon Apr 11 09:45:42 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1092 Mon Apr 11 09:45:43 2011 Received.
1093 Mon Apr 11 09:45:43 2011 Starting 300 seconds test run.
1094 SFS Single Client (c09-44) Results, Mon Apr 11 09:50:53 2011
1095 --------------------------------------------------------------------------------------
1096 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1097 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1098 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1099            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1100 --------------------------------------------------------------------------------------
1101 getattr     26.0%    26.0%    39100    39100      0      0.31    14.59      0.04  2.2%
1102 setattr      4.0%     4.0%     5938     5938      0      7.76    67.11      0.21  8.3%
1103 lookup      24.0%    23.8%    35796    35796      0      0.38    18.64      0.04  2.5%
1104 readlink     1.0%     1.0%     1477     1477      0      0.21     0.12      0.02  0.1%
1105 read        18.0%    18.2%    27291    33642      0      7.62    54.83      0.09 37.5%
1106 write       10.0%    10.0%    15086    18320      0     11.45    45.11      0.11 31.2%
1107 create       1.0%     1.0%     1530     1530      0     11.84    71.35      0.42  3.3%
1108 remove       1.0%     1.0%     1481     1481      0      7.46    25.41      0.26  2.0%
1109 readdir      1.0%     1.0%     1519     1519      0      0.42     0.11      0.02  0.1%
1110 fsstat       1.0%     1.0%     1493     1493      0      0.21     0.11      0.02  0.1%
1111 access      11.0%    11.0%    16464    16464      0      0.33    15.88      0.06  1.0%
1112 commit       0.0%     0.0%        0     6624      0      0.00     0.00      0.00 11.5%
1113 readdirplus  2.0%     2.0%     3052     3052      0      0.72     0.19      0.02  0.4%
1114 --------------------------------------------------------------------------------------
1115
1116       --------------------------------------------------
1117       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1118       --------------------------+-----------------------
1119 SFS NFS V3 THROUGHPUT:  500.75 Ops/Sec   AVG. RESPONSE TIME:    3.69 Msec/Op
1120 TCP PROTOCOL
1121 FAST CALLS: 0
1122 NFS V3 MIXFILE: [ SFS Default ]
1123 CLIENT REQUESTED LOAD: 500 Ops/Sec
1124 TOTAL LOGICAL NFS V3 OPERATIONS:  150227         TEST TIME: 300 Sec
1125 TOTAL PHYSICAL NFS V3 OPERATIONS: 166436
1126 PHYSICAL NFS V3 THROUGHPUT:  554.78 Ops/Sec
1127 FILE SET SIZE CREATED: 60831360.0 KB
1128 FILE SET SIZE ACCESSED: 18257904.0 - 18611756.0 KB  (100.00% to 101.94% of Base)
1129
1130 ------------------------------------------------------------------------
1131
1132 Mon Apr 11 09:50:53 2011 Completed.
1133
1134 Client Test Parameters:
1135         Number of processes = 16
1136         Requested Load (NFS V3 operations/second) = 500
1137         Maximum number of outstanding biod writes = 8
1138         Maximum number of outstanding biod reads = 8
1139         Warm-up time (seconds) = 300
1140         Run time (seconds) = 300
1141         File Set = 114560 Files created for I/O operations
1142                    34368 Files accessed for I/O operations
1143                    2304 Files for non-I/O operations
1144                      32 Symlinks
1145                    3808 Directories
1146                         Additional non-I/O files created as necessary
1147
1148
1149 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1150 Mon Apr 11 09:50:53 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1151 Mon Apr 11 09:50:53 2011 Completed.
1152 Mon Apr 11 09:50:53 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1153
1154 ************************************************************************
1155 Mon Apr 11 09:50:54 2011 Received.
1156 Mon Apr 11 09:50:54 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1157 Mon Apr 11 09:50:54 2011 Completed.
1158 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 600  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
1159 NFS V3 Protocol Version
1160
1161 ************************************************************************
1162 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1163 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1164
1165 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1166 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1167
1168 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 09:50:56 2011
1169         Client hostname = c09-44
1170         Prime Client hostname = c09-44.sysnet.ucsd.edu
1171
1172 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1173 NFS V3 Protocol
1174 Mon Apr 11 09:50:56 2011 Mounting 16 remote test directories.
1175 Child[000]: NFS negotiated block size 32768
1176 Child[001]: NFS negotiated block size 32768
1177 Child[002]: NFS negotiated block size 32768
1178 Child[003]: NFS negotiated block size 32768
1179 Child[004]: NFS negotiated block size 32768
1180 Child[005]: NFS negotiated block size 32768
1181 Child[006]: NFS negotiated block size 32768
1182 Child[007]: NFS negotiated block size 32768
1183 Child[008]: NFS negotiated block size 32768
1184 Child[009]: NFS negotiated block size 32768
1185 Child[010]: NFS negotiated block size 32768
1186 Child[011]: NFS negotiated block size 32768
1187 Child[012]: NFS negotiated block size 32768
1188 Child[013]: NFS negotiated block size 32768
1189 Child[014]: NFS negotiated block size 32768
1190 Child[015]: NFS negotiated block size 32768
1191 Mon Apr 11 09:51:10 2011 Completed.
1192 Mon Apr 11 09:51:10 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1193 Mon Apr 11 09:51:10 2011 Completed.
1194 Mon Apr 11 09:51:10 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1195 Mon Apr 11 09:51:12 2011 Received.
1196
1197 Mon Apr 11 09:51:12 2011 Initializing test directories.
1198 Mon Apr 11 09:51:12 2011 Child 10 will create 289 directories.
1199 Mon Apr 11 09:51:12 2011 Child 15 will create 289 directories.
1200 Mon Apr 11 09:51:12 2011 Child 2 will create 289 directories.
1201 Mon Apr 11 09:51:12 2011 Child 5 will create 289 directories.
1202 Mon Apr 11 09:51:12 2011 Child 14 will create 289 directories.
1203 Mon Apr 11 09:51:12 2011 Child 4 will create 289 directories.
1204 Mon Apr 11 09:51:12 2011 Child 8 will create 289 directories.
1205 Mon Apr 11 09:51:12 2011 Child 13 will create 289 directories.
1206 Mon Apr 11 09:51:12 2011 Child 7 will create 289 directories.
1207 Mon Apr 11 09:51:12 2011 Child 6 will create 289 directories.
1208 Mon Apr 11 09:51:12 2011 Child 12 will create 289 directories.
1209 Mon Apr 11 09:51:12 2011 Child 9 will create 289 directories.
1210 Mon Apr 11 09:51:12 2011 Child 11 will create 289 directories.
1211 Mon Apr 11 09:51:12 2011 Child 3 will create 289 directories.
1212 Mon Apr 11 09:51:12 2011 Child 1 will create 289 directories.
1213 Mon Apr 11 09:51:12 2011 Child 0 will create 289 directories.
1214 Mon Apr 11 09:51:16 2011 Child 2 finished creating 289 directories.
1215 Mon Apr 11 09:51:16 2011 Child 2 will create 8814 files.
1216 Mon Apr 11 09:51:18 2011 Child 13 finished creating 289 directories.
1217 Mon Apr 11 09:51:18 2011 Child 13 will create 8814 files.
1218 Mon Apr 11 09:51:19 2011 Child 3 finished creating 289 directories.
1219 Mon Apr 11 09:51:19 2011 Child 3 will create 8814 files.
1220 Mon Apr 11 09:51:20 2011 Child 9 finished creating 289 directories.
1221 Mon Apr 11 09:51:20 2011 Child 9 will create 8814 files.
1222 Mon Apr 11 09:51:24 2011 Child 5 finished creating 289 directories.
1223 Mon Apr 11 09:51:24 2011 Child 5 will create 8814 files.
1224 Mon Apr 11 09:51:25 2011 Child 10 finished creating 289 directories.
1225 Mon Apr 11 09:51:25 2011 Child 10 will create 8814 files.
1226 Mon Apr 11 09:51:26 2011 Child 14 finished creating 289 directories.
1227 Mon Apr 11 09:51:26 2011 Child 14 will create 8814 files.
1228 Mon Apr 11 09:51:28 2011 Child 15 finished creating 289 directories.
1229 Mon Apr 11 09:51:28 2011 Child 15 will create 8814 files.
1230 Mon Apr 11 09:51:29 2011 Child 8 finished creating 289 directories.
1231 Mon Apr 11 09:51:29 2011 Child 8 will create 8814 files.
1232 Mon Apr 11 09:51:31 2011 Child 12 finished creating 289 directories.
1233 Mon Apr 11 09:51:31 2011 Child 12 will create 8814 files.
1234 Mon Apr 11 09:51:32 2011 Child 1 finished creating 289 directories.
1235 Mon Apr 11 09:51:32 2011 Child 1 will create 8814 files.
1236 Mon Apr 11 09:51:33 2011 Child 4 finished creating 289 directories.
1237 Mon Apr 11 09:51:33 2011 Child 4 will create 8814 files.
1238 Mon Apr 11 09:51:33 2011 Child 7 finished creating 289 directories.
1239 Mon Apr 11 09:51:33 2011 Child 7 will create 8814 files.
1240 Mon Apr 11 09:51:36 2011 Child 6 finished creating 289 directories.
1241 Mon Apr 11 09:51:36 2011 Child 6 will create 8814 files.
1242 Mon Apr 11 09:51:36 2011 Child 11 finished creating 289 directories.
1243 Mon Apr 11 09:51:36 2011 Child 11 will create 8814 files.
1244 Mon Apr 11 09:51:37 2011 Child 0 finished creating 289 directories.
1245 Mon Apr 11 09:51:37 2011 Child 0 will create 8814 files.
1246 Mon Apr 11 09:55:43 2011 Child 2 verified 8814 files, created 1568
1247 Mon Apr 11 09:55:43 2011 Child 2 will create 2 symlinks.
1248 Mon Apr 11 09:55:43 2011 Child 2 finished creating 2 symlinks.
1249 Mon Apr 11 09:56:22 2011 Child 3 verified 8609 files (1449 creates).
1250 Mon Apr 11 09:56:23 2011 Child 13 verified 8613 files (1453 creates).
1251 Mon Apr 11 09:56:23 2011 Child 9 verified 8519 files (1359 creates).
1252 Mon Apr 11 09:56:25 2011 Child 5 verified 8350 files (1190 creates).
1253 Mon Apr 11 09:56:29 2011 Child 14 verified 8306 files (1146 creates).
1254 Mon Apr 11 09:56:29 2011 Child 10 verified 8352 files (1192 creates).
1255 Mon Apr 11 09:56:29 2011 Child 15 verified 8223 files (1063 creates).
1256 Mon Apr 11 09:56:30 2011 Child 8 verified 8311 files (1151 creates).
1257 Mon Apr 11 09:56:35 2011 Child 13 verified 8814 files, created 1568
1258 Mon Apr 11 09:56:35 2011 Child 13 will create 2 symlinks.
1259 Mon Apr 11 09:56:35 2011 Child 13 finished creating 2 symlinks.
1260 Mon Apr 11 09:56:49 2011 Child 0 verified 8205 files (1045 creates).
1261 Mon Apr 11 09:56:54 2011 Child 6 verified 8205 files (1045 creates).
1262 Mon Apr 11 09:56:54 2011 Child 12 verified 8205 files (1045 creates).
1263 Mon Apr 11 09:56:54 2011 Child 4 verified 8205 files (1045 creates).
1264 Mon Apr 11 09:56:54 2011 Child 11 verified 8205 files (1045 creates).
1265 Mon Apr 11 09:56:54 2011 Child 1 verified 8286 files (1126 creates).
1266 Mon Apr 11 09:56:54 2011 Child 7 verified 8205 files (1045 creates).
1267 Mon Apr 11 09:57:01 2011 Child 3 verified 8814 files, created 1568
1268 Mon Apr 11 09:57:01 2011 Child 3 will create 2 symlinks.
1269 Mon Apr 11 09:57:01 2011 Child 3 finished creating 2 symlinks.
1270 Mon Apr 11 09:57:26 2011 Child 9 verified 8814 files, created 1568
1271 Mon Apr 11 09:57:26 2011 Child 9 will create 2 symlinks.
1272 Mon Apr 11 09:57:26 2011 Child 9 finished creating 2 symlinks.
1273 Mon Apr 11 09:58:29 2011 Child 5 verified 8814 files, created 1568
1274 Mon Apr 11 09:58:29 2011 Child 5 will create 2 symlinks.
1275 Mon Apr 11 09:58:30 2011 Child 5 finished creating 2 symlinks.
1276 Mon Apr 11 09:58:34 2011 Child 14 verified 8814 files, created 1568
1277 Mon Apr 11 09:58:34 2011 Child 14 will create 2 symlinks.
1278 Mon Apr 11 09:58:34 2011 Child 14 finished creating 2 symlinks.
1279 Mon Apr 11 09:58:35 2011 Child 8 verified 8814 files, created 1568
1280 Mon Apr 11 09:58:35 2011 Child 8 will create 2 symlinks.
1281 Mon Apr 11 09:58:35 2011 Child 8 finished creating 2 symlinks.
1282 Mon Apr 11 09:58:38 2011 Child 10 verified 8814 files, created 1568
1283 Mon Apr 11 09:58:38 2011 Child 10 will create 2 symlinks.
1284 Mon Apr 11 09:58:38 2011 Child 10 finished creating 2 symlinks.
1285 Mon Apr 11 09:58:51 2011 Child 1 verified 8814 files, created 1568
1286 Mon Apr 11 09:58:51 2011 Child 1 will create 2 symlinks.
1287 Mon Apr 11 09:58:51 2011 Child 1 finished creating 2 symlinks.
1288 Mon Apr 11 09:58:53 2011 Child 12 verified 8814 files, created 1568
1289 Mon Apr 11 09:58:53 2011 Child 12 will create 2 symlinks.
1290 Mon Apr 11 09:58:54 2011 Child 12 finished creating 2 symlinks.
1291 Mon Apr 11 09:58:54 2011 Child 0 verified 8814 files, created 1568
1292 Mon Apr 11 09:58:54 2011 Child 0 will create 2 symlinks.
1293 Mon Apr 11 09:58:54 2011 Child 0 finished creating 2 symlinks.
1294 Mon Apr 11 09:59:06 2011 Child 6 verified 8814 files, created 1568
1295 Mon Apr 11 09:59:06 2011 Child 6 will create 2 symlinks.
1296 Mon Apr 11 09:59:06 2011 Child 4 verified 8814 files, created 1568
1297 Mon Apr 11 09:59:06 2011 Child 4 will create 2 symlinks.
1298 Mon Apr 11 09:59:06 2011 Child 6 finished creating 2 symlinks.
1299 Mon Apr 11 09:59:06 2011 Child 15 verified 8814 files, created 1568
1300 Mon Apr 11 09:59:06 2011 Child 15 will create 2 symlinks.
1301 Mon Apr 11 09:59:06 2011 Child 7 verified 8814 files, created 1568
1302 Mon Apr 11 09:59:06 2011 Child 7 will create 2 symlinks.
1303 Mon Apr 11 09:59:06 2011 Child 4 finished creating 2 symlinks.
1304 Mon Apr 11 09:59:06 2011 Child 15 finished creating 2 symlinks.
1305 Mon Apr 11 09:59:06 2011 Child 7 finished creating 2 symlinks.
1306 Mon Apr 11 09:59:08 2011 Child 11 verified 8814 files, created 1568
1307 Mon Apr 11 09:59:08 2011 Child 11 will create 2 symlinks.
1308 Mon Apr 11 09:59:08 2011 Child 11 finished creating 2 symlinks.
1309 Mon Apr 11 09:59:08 2011 Completed.
1310 Mon Apr 11 09:59:08 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1311 Mon Apr 11 09:59:08 2011 Completed.
1312 Mon Apr 11 09:59:08 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1313 Mon Apr 11 09:59:08 2011 Received.
1314 Mon Apr 11 09:59:08 2011 Performing 300 seconds pretest warmup.
1315 Mon Apr 11 10:04:08 2011 Completed.
1316 Mon Apr 11 10:04:08 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1317 Mon Apr 11 10:04:08 2011 Completed.
1318 Mon Apr 11 10:04:08 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1319 Mon Apr 11 10:04:09 2011 Received.
1320 Mon Apr 11 10:04:09 2011 Starting 300 seconds test run.
1321 SFS Single Client (c09-44) Results, Mon Apr 11 10:09:19 2011
1322 --------------------------------------------------------------------------------------
1323 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1324 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1325 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1326            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1327 --------------------------------------------------------------------------------------
1328 getattr     26.0%    26.2%    47264    47264      0      0.22     0.46      0.01  1.4%
1329 setattr      4.0%     4.0%     7216     7216      0      9.49    54.34      0.17  9.5%
1330 lookup      24.0%    23.9%    43049    43049      0      0.23     0.43      0.01  1.4%
1331 readlink     1.0%     1.0%     1826     1826      0      0.24     0.65      0.04  0.1%
1332 read        18.0%    18.0%    32479    40021      0      7.87    39.72      0.07 35.5%
1333 write       10.0%     9.9%    17901    21832      0     12.47    37.56      0.09 31.0%
1334 create       1.0%     1.0%     1878     1878      0     14.55    57.59      0.34  3.8%
1335 remove       1.0%     1.0%     1811     1811      0     11.80    40.66      0.29  3.0%
1336 readdir      1.0%     1.0%     1816     1816      0      0.44     0.43      0.03  0.1%
1337 fsstat       1.0%     1.0%     1773     1773      0      0.22     0.18      0.02  0.1%
1338 access      11.0%    10.9%    19718    19718      0      0.22     0.40      0.01  0.6%
1339 commit       0.0%     0.0%        0     7854      0      0.00     0.00      0.00 13.2%
1340 readdirplus  2.0%     2.0%     3691     3691      0      0.75     0.33      0.02  0.4%
1341 --------------------------------------------------------------------------------------
1342
1343       --------------------------------------------------
1344       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1345       --------------------------+-----------------------
1346 SFS NFS V3 THROUGHPUT:  603.41 Ops/Sec   AVG. RESPONSE TIME:    3.99 Msec/Op
1347 TCP PROTOCOL
1348 FAST CALLS: 0
1349 NFS V3 MIXFILE: [ SFS Default ]
1350 CLIENT REQUESTED LOAD: 600 Ops/Sec
1351 TOTAL LOGICAL NFS V3 OPERATIONS:  180422         TEST TIME: 299 Sec
1352 TOTAL PHYSICAL NFS V3 OPERATIONS: 199749
1353 PHYSICAL NFS V3 THROUGHPUT:  668.05 Ops/Sec
1354 FILE SET SIZE CREATED: 73413936.0 KB
1355 FILE SET SIZE ACCESSED: 22030128.0 - 22447252.0 KB  (100.00% to 101.89% of Base)
1356
1357 ------------------------------------------------------------------------
1358
1359 Mon Apr 11 10:09:19 2011 Completed.
1360
1361 Client Test Parameters:
1362         Number of processes = 16
1363         Requested Load (NFS V3 operations/second) = 600
1364         Maximum number of outstanding biod writes = 8
1365         Maximum number of outstanding biod reads = 8
1366         Warm-up time (seconds) = 300
1367         Run time (seconds) = 300
1368         File Set = 138256 Files created for I/O operations
1369                    41472 Files accessed for I/O operations
1370                    2768 Files for non-I/O operations
1371                      32 Symlinks
1372                    4608 Directories
1373                         Additional non-I/O files created as necessary
1374
1375
1376 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1377 Mon Apr 11 10:09:19 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1378 Mon Apr 11 10:09:19 2011 Completed.
1379 Mon Apr 11 10:09:19 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1380
1381 ************************************************************************
1382 Mon Apr 11 10:09:20 2011 Received.
1383 Mon Apr 11 10:09:20 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1384 Mon Apr 11 10:09:20 2011 Completed.
1385 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 700  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
1386 NFS V3 Protocol Version
1387
1388 ************************************************************************
1389 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1390 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1391
1392 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1393 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1394
1395 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 10:09:22 2011
1396         Client hostname = c09-44
1397         Prime Client hostname = c09-44.sysnet.ucsd.edu
1398
1399 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1400 NFS V3 Protocol
1401 Mon Apr 11 10:09:22 2011 Mounting 16 remote test directories.
1402 Child[000]: NFS negotiated block size 32768
1403 Child[001]: NFS negotiated block size 32768
1404 Child[002]: NFS negotiated block size 32768
1405 Child[003]: NFS negotiated block size 32768
1406 Child[004]: NFS negotiated block size 32768
1407 Child[005]: NFS negotiated block size 32768
1408 Child[006]: NFS negotiated block size 32768
1409 Child[007]: NFS negotiated block size 32768
1410 Child[008]: NFS negotiated block size 32768
1411 Child[009]: NFS negotiated block size 32768
1412 Child[010]: NFS negotiated block size 32768
1413 Child[011]: NFS negotiated block size 32768
1414 Child[012]: NFS negotiated block size 32768
1415 Child[013]: NFS negotiated block size 32768
1416 Child[014]: NFS negotiated block size 32768
1417 Child[015]: NFS negotiated block size 32768
1418 Mon Apr 11 10:09:36 2011 Completed.
1419 Mon Apr 11 10:09:36 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1420 Mon Apr 11 10:09:36 2011 Completed.
1421 Mon Apr 11 10:09:36 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1422 Mon Apr 11 10:09:38 2011 Received.
1423
1424 Mon Apr 11 10:09:38 2011 Initializing test directories.
1425 Mon Apr 11 10:09:38 2011 Child 14 will create 338 directories.
1426 Mon Apr 11 10:09:38 2011 Child 15 will create 338 directories.
1427 Mon Apr 11 10:09:38 2011 Child 7 will create 338 directories.
1428 Mon Apr 11 10:09:38 2011 Child 1 will create 338 directories.
1429 Mon Apr 11 10:09:38 2011 Child 13 will create 338 directories.
1430 Mon Apr 11 10:09:38 2011 Child 11 will create 338 directories.
1431 Mon Apr 11 10:09:38 2011 Child 9 will create 338 directories.
1432 Mon Apr 11 10:09:38 2011 Child 2 will create 338 directories.
1433 Mon Apr 11 10:09:38 2011 Child 10 will create 338 directories.
1434 Mon Apr 11 10:09:38 2011 Child 0 will create 338 directories.
1435 Mon Apr 11 10:09:38 2011 Child 4 will create 338 directories.
1436 Mon Apr 11 10:09:38 2011 Child 5 will create 338 directories.
1437 Mon Apr 11 10:09:38 2011 Child 8 will create 338 directories.
1438 Mon Apr 11 10:09:38 2011 Child 3 will create 338 directories.
1439 Mon Apr 11 10:09:38 2011 Child 12 will create 338 directories.
1440 Mon Apr 11 10:09:38 2011 Child 6 will create 338 directories.
1441 Mon Apr 11 10:09:43 2011 Child 8 finished creating 338 directories.
1442 Mon Apr 11 10:09:43 2011 Child 8 will create 10325 files.
1443 Mon Apr 11 10:09:43 2011 Child 0 finished creating 338 directories.
1444 Mon Apr 11 10:09:43 2011 Child 0 will create 10325 files.
1445 Mon Apr 11 10:09:43 2011 Child 2 finished creating 338 directories.
1446 Mon Apr 11 10:09:43 2011 Child 2 will create 10325 files.
1447 Mon Apr 11 10:09:45 2011 Child 5 finished creating 338 directories.
1448 Mon Apr 11 10:09:45 2011 Child 5 will create 10325 files.
1449 Mon Apr 11 10:09:47 2011 Child 6 finished creating 338 directories.
1450 Mon Apr 11 10:09:47 2011 Child 6 will create 10325 files.
1451 Mon Apr 11 10:09:48 2011 Child 4 finished creating 338 directories.
1452 Mon Apr 11 10:09:48 2011 Child 4 will create 10325 files.
1453 Mon Apr 11 10:09:50 2011 Child 11 finished creating 338 directories.
1454 Mon Apr 11 10:09:50 2011 Child 11 will create 10325 files.
1455 Mon Apr 11 10:09:50 2011 Child 10 finished creating 338 directories.
1456 Mon Apr 11 10:09:50 2011 Child 10 will create 10325 files.
1457 Mon Apr 11 10:09:51 2011 Child 7 finished creating 338 directories.
1458 Mon Apr 11 10:09:51 2011 Child 7 will create 10325 files.
1459 Mon Apr 11 10:09:53 2011 Child 13 finished creating 338 directories.
1460 Mon Apr 11 10:09:53 2011 Child 13 will create 10325 files.
1461 Mon Apr 11 10:09:54 2011 Child 14 finished creating 338 directories.
1462 Mon Apr 11 10:09:54 2011 Child 14 will create 10325 files.
1463 Mon Apr 11 10:09:55 2011 Child 9 finished creating 338 directories.
1464 Mon Apr 11 10:09:55 2011 Child 9 will create 10325 files.
1465 Mon Apr 11 10:09:59 2011 Child 15 finished creating 338 directories.
1466 Mon Apr 11 10:09:59 2011 Child 15 will create 10325 files.
1467 Mon Apr 11 10:10:03 2011 Child 1 finished creating 338 directories.
1468 Mon Apr 11 10:10:03 2011 Child 1 will create 10325 files.
1469 Mon Apr 11 10:10:03 2011 Child 12 finished creating 338 directories.
1470 Mon Apr 11 10:10:03 2011 Child 12 will create 10325 files.
1471 Mon Apr 11 10:10:07 2011 Child 3 finished creating 338 directories.
1472 Mon Apr 11 10:10:07 2011 Child 3 will create 10325 files.
1473 Mon Apr 11 10:14:44 2011 Child 8 verified 9689 files (1048 creates).
1474 Mon Apr 11 10:14:44 2011 Child 0 verified 9656 files (1015 creates).
1475 Mon Apr 11 10:14:48 2011 Child 6 verified 9537 files (896 creates).
1476 Mon Apr 11 10:14:52 2011 Child 5 verified 9653 files (1012 creates).
1477 Mon Apr 11 10:14:52 2011 Child 2 verified 9653 files (1012 creates).
1478 Mon Apr 11 10:15:00 2011 Child 14 verified 9173 files (532 creates).
1479 Mon Apr 11 10:15:00 2011 Child 11 verified 9252 files (611 creates).
1480 Mon Apr 11 10:15:00 2011 Child 13 verified 9084 files (443 creates).
1481 Mon Apr 11 10:15:00 2011 Child 10 verified 9252 files (611 creates).
1482 Mon Apr 11 10:15:00 2011 Child 4 verified 9252 files (611 creates).
1483 Mon Apr 11 10:15:00 2011 Child 7 verified 9369 files (728 creates).
1484 Mon Apr 11 10:15:00 2011 Child 9 verified 9174 files (533 creates).
1485 Mon Apr 11 10:15:00 2011 Child 15 verified 9089 files (448 creates).
1486 Mon Apr 11 10:15:04 2011 Child 1 verified 8953 files (312 creates).
1487 Mon Apr 11 10:15:04 2011 Child 12 verified 8965 files (324 creates).
1488 Mon Apr 11 10:15:09 2011 Child 3 verified 9000 files (359 creates).
1489 Mon Apr 11 10:16:51 2011 Child 8 verified 10325 files, created 1583
1490 Mon Apr 11 10:16:51 2011 Child 8 will create 2 symlinks.
1491 Mon Apr 11 10:16:51 2011 Child 8 finished creating 2 symlinks.
1492 Mon Apr 11 10:17:01 2011 Child 0 verified 10325 files, created 1583
1493 Mon Apr 11 10:17:01 2011 Child 0 will create 2 symlinks.
1494 Mon Apr 11 10:17:02 2011 Child 0 finished creating 2 symlinks.
1495 Mon Apr 11 10:17:45 2011 Child 5 verified 10325 files, created 1583
1496 Mon Apr 11 10:17:45 2011 Child 5 will create 2 symlinks.
1497 Mon Apr 11 10:17:45 2011 Child 5 finished creating 2 symlinks.
1498 Mon Apr 11 10:17:47 2011 Child 6 verified 10325 files, created 1583
1499 Mon Apr 11 10:17:47 2011 Child 6 will create 2 symlinks.
1500 Mon Apr 11 10:17:47 2011 Child 2 verified 10325 files, created 1583
1501 Mon Apr 11 10:17:47 2011 Child 2 will create 2 symlinks.
1502 Mon Apr 11 10:17:47 2011 Child 2 finished creating 2 symlinks.
1503 Mon Apr 11 10:17:47 2011 Child 6 finished creating 2 symlinks.
1504 Mon Apr 11 10:18:16 2011 Child 7 verified 10325 files, created 1583
1505 Mon Apr 11 10:18:16 2011 Child 7 will create 2 symlinks.
1506 Mon Apr 11 10:18:16 2011 Child 7 finished creating 2 symlinks.
1507 Mon Apr 11 10:18:44 2011 Child 10 verified 10325 files, created 1583
1508 Mon Apr 11 10:18:44 2011 Child 10 will create 2 symlinks.
1509 Mon Apr 11 10:18:45 2011 Child 10 finished creating 2 symlinks.
1510 Mon Apr 11 10:18:49 2011 Child 11 verified 10325 files, created 1583
1511 Mon Apr 11 10:18:49 2011 Child 11 will create 2 symlinks.
1512 Mon Apr 11 10:18:49 2011 Child 11 finished creating 2 symlinks.
1513 Mon Apr 11 10:19:07 2011 Child 14 verified 10325 files, created 1583
1514 Mon Apr 11 10:19:07 2011 Child 14 will create 2 symlinks.
1515 Mon Apr 11 10:19:07 2011 Child 13 verified 10325 files, created 1583
1516 Mon Apr 11 10:19:07 2011 Child 13 will create 2 symlinks.
1517 Mon Apr 11 10:19:07 2011 Child 13 finished creating 2 symlinks.
1518 Mon Apr 11 10:19:07 2011 Child 14 finished creating 2 symlinks.
1519 Mon Apr 11 10:19:07 2011 Child 9 verified 10325 files, created 1583
1520 Mon Apr 11 10:19:07 2011 Child 9 will create 2 symlinks.
1521 Mon Apr 11 10:19:08 2011 Child 9 finished creating 2 symlinks.
1522 Mon Apr 11 10:19:08 2011 Child 4 verified 10325 files, created 1583
1523 Mon Apr 11 10:19:08 2011 Child 4 will create 2 symlinks.
1524 Mon Apr 11 10:19:08 2011 Child 4 finished creating 2 symlinks.
1525 Mon Apr 11 10:19:12 2011 Child 15 verified 10325 files, created 1583
1526 Mon Apr 11 10:19:12 2011 Child 15 will create 2 symlinks.
1527 Mon Apr 11 10:19:12 2011 Child 15 finished creating 2 symlinks.
1528 Mon Apr 11 10:19:13 2011 Child 3 verified 10325 files, created 1583
1529 Mon Apr 11 10:19:13 2011 Child 3 will create 2 symlinks.
1530 Mon Apr 11 10:19:13 2011 Child 3 finished creating 2 symlinks.
1531 Mon Apr 11 10:19:15 2011 Child 1 verified 10325 files, created 1583
1532 Mon Apr 11 10:19:15 2011 Child 1 will create 2 symlinks.
1533 Mon Apr 11 10:19:15 2011 Child 12 verified 10325 files, created 1583
1534 Mon Apr 11 10:19:15 2011 Child 12 will create 2 symlinks.
1535 Mon Apr 11 10:19:15 2011 Child 1 finished creating 2 symlinks.
1536 Mon Apr 11 10:19:15 2011 Child 12 finished creating 2 symlinks.
1537 Mon Apr 11 10:19:15 2011 Completed.
1538 Mon Apr 11 10:19:15 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1539 Mon Apr 11 10:19:15 2011 Completed.
1540 Mon Apr 11 10:19:15 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1541 Mon Apr 11 10:19:16 2011 Received.
1542 Mon Apr 11 10:19:16 2011 Performing 300 seconds pretest warmup.
1543 Mon Apr 11 10:24:16 2011 Completed.
1544 Mon Apr 11 10:24:16 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1545 Mon Apr 11 10:24:16 2011 Completed.
1546 Mon Apr 11 10:24:16 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1547 Mon Apr 11 10:24:17 2011 Received.
1548 Mon Apr 11 10:24:17 2011 Starting 300 seconds test run.
1549 SFS Single Client (c09-44) Results, Mon Apr 11 10:29:27 2011
1550 --------------------------------------------------------------------------------------
1551 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1552 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1553 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1554            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1555 --------------------------------------------------------------------------------------
1556 getattr     26.0%    26.0%    54756    54756      0      0.34     1.64      0.01  1.7%
1557 setattr      4.0%     4.0%     8433     8433      0     10.98    25.32      0.11  8.7%
1558 lookup      24.0%    24.0%    50532    50532      0      0.36     2.98      0.02  1.7%
1559 readlink     1.0%     1.0%     2119     2119      0      0.32     1.27      0.05  0.1%
1560 read        18.0%    18.0%    37979    46876      0     10.99    42.90      0.07 39.3%
1561 write       10.0%    10.0%    21009    25422      0     14.30    28.84      0.07 28.3%
1562 create       1.0%     1.0%     2164     2164      0     17.74    34.12      0.25  3.6%
1563 remove       1.0%     1.0%     2074     2074      0     14.23    30.96      0.24  2.8%
1564 readdir      1.0%     1.0%     2102     2102      0      0.58     1.67      0.06  0.1%
1565 fsstat       1.0%     1.0%     2127     2127      0      0.35     1.82      0.06  0.1%
1566 access      11.0%    11.0%    23089    23089      0      0.36     4.11      0.03  0.8%
1567 commit       0.0%     0.0%        0     9041      0      0.00     0.00      0.00 12.5%
1568 readdirplus  2.0%     2.0%     4235     4235      0      0.89     1.76      0.04  0.4%
1569 --------------------------------------------------------------------------------------
1570
1571       --------------------------------------------------
1572       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1573       --------------------------+-----------------------
1574 SFS NFS V3 THROUGHPUT:  704.41 Ops/Sec   AVG. RESPONSE TIME:    5.04 Msec/Op
1575 TCP PROTOCOL
1576 FAST CALLS: 0
1577 NFS V3 MIXFILE: [ SFS Default ]
1578 CLIENT REQUESTED LOAD: 700 Ops/Sec
1579 TOTAL LOGICAL NFS V3 OPERATIONS:  210619         TEST TIME: 299 Sec
1580 TOTAL PHYSICAL NFS V3 OPERATIONS: 232970
1581 PHYSICAL NFS V3 THROUGHPUT:  779.16 Ops/Sec
1582 FILE SET SIZE CREATED: 85996512.0 KB
1583 FILE SET SIZE ACCESSED: 25802352.0 - 26266644.0 KB  (100.00% to 101.80% of Base)
1584
1585 ------------------------------------------------------------------------
1586
1587 Mon Apr 11 10:29:27 2011 Completed.
1588
1589 Client Test Parameters:
1590         Number of processes = 16
1591         Requested Load (NFS V3 operations/second) = 700
1592         Maximum number of outstanding biod writes = 8
1593         Maximum number of outstanding biod reads = 8
1594         Warm-up time (seconds) = 300
1595         Run time (seconds) = 300
1596         File Set = 161952 Files created for I/O operations
1597                    48576 Files accessed for I/O operations
1598                    3248 Files for non-I/O operations
1599                      32 Symlinks
1600                    5392 Directories
1601                         Additional non-I/O files created as necessary
1602
1603
1604 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1605 Mon Apr 11 10:29:27 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1606 Mon Apr 11 10:29:27 2011 Completed.
1607 Mon Apr 11 10:29:27 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1608
1609 ************************************************************************
1610 Mon Apr 11 10:29:28 2011 Received.
1611 Mon Apr 11 10:29:28 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1612 Mon Apr 11 10:29:28 2011 Completed.
1613 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 800  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
1614 NFS V3 Protocol Version
1615
1616 ************************************************************************
1617 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1618 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1619
1620 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1621 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1622
1623 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 10:29:30 2011
1624         Client hostname = c09-44
1625         Prime Client hostname = c09-44.sysnet.ucsd.edu
1626
1627 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1628 NFS V3 Protocol
1629 Mon Apr 11 10:29:30 2011 Mounting 16 remote test directories.
1630 Child[000]: NFS negotiated block size 32768
1631 Child[001]: NFS negotiated block size 32768
1632 Child[002]: NFS negotiated block size 32768
1633 Child[003]: NFS negotiated block size 32768
1634 Child[004]: NFS negotiated block size 32768
1635 Child[005]: NFS negotiated block size 32768
1636 Child[006]: NFS negotiated block size 32768
1637 Child[007]: NFS negotiated block size 32768
1638 Child[008]: NFS negotiated block size 32768
1639 Child[009]: NFS negotiated block size 32768
1640 Child[010]: NFS negotiated block size 32768
1641 Child[011]: NFS negotiated block size 32768
1642 Child[012]: NFS negotiated block size 32768
1643 Child[013]: NFS negotiated block size 32768
1644 Child[014]: NFS negotiated block size 32768
1645 Child[015]: NFS negotiated block size 32768
1646 Mon Apr 11 10:29:44 2011 Completed.
1647 Mon Apr 11 10:29:44 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1648 Mon Apr 11 10:29:44 2011 Completed.
1649 Mon Apr 11 10:29:44 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1650 Mon Apr 11 10:29:46 2011 Received.
1651
1652 Mon Apr 11 10:29:46 2011 Initializing test directories.
1653 Mon Apr 11 10:29:46 2011 Child 13 will create 383 directories.
1654 Mon Apr 11 10:29:46 2011 Child 11 will create 383 directories.
1655 Mon Apr 11 10:29:46 2011 Child 12 will create 383 directories.
1656 Mon Apr 11 10:29:46 2011 Child 15 will create 383 directories.
1657 Mon Apr 11 10:29:46 2011 Child 1 will create 383 directories.
1658 Mon Apr 11 10:29:46 2011 Child 5 will create 383 directories.
1659 Mon Apr 11 10:29:46 2011 Child 6 will create 383 directories.
1660 Mon Apr 11 10:29:46 2011 Child 2 will create 383 directories.
1661 Mon Apr 11 10:29:46 2011 Child 14 will create 383 directories.
1662 Mon Apr 11 10:29:46 2011 Child 0 will create 383 directories.
1663 Mon Apr 11 10:29:46 2011 Child 3 will create 383 directories.
1664 Mon Apr 11 10:29:46 2011 Child 10 will create 383 directories.
1665 Mon Apr 11 10:29:46 2011 Child 9 will create 383 directories.
1666 Mon Apr 11 10:29:46 2011 Child 4 will create 383 directories.
1667 Mon Apr 11 10:29:46 2011 Child 8 will create 383 directories.
1668 Mon Apr 11 10:29:46 2011 Child 7 will create 383 directories.
1669 Mon Apr 11 10:29:47 2011 Child 14 finished creating 383 directories.
1670 Mon Apr 11 10:29:47 2011 Child 14 will create 11710 files.
1671 Mon Apr 11 10:29:48 2011 Child 7 finished creating 383 directories.
1672 Mon Apr 11 10:29:48 2011 Child 7 will create 11710 files.
1673 Mon Apr 11 10:29:49 2011 Child 12 finished creating 383 directories.
1674 Mon Apr 11 10:29:49 2011 Child 12 will create 11710 files.
1675 Mon Apr 11 10:29:56 2011 Child 15 finished creating 383 directories.
1676 Mon Apr 11 10:29:56 2011 Child 15 will create 11710 files.
1677 Mon Apr 11 10:29:58 2011 Child 13 finished creating 383 directories.
1678 Mon Apr 11 10:29:58 2011 Child 13 will create 11710 files.
1679 Mon Apr 11 10:29:59 2011 Child 3 finished creating 383 directories.
1680 Mon Apr 11 10:29:59 2011 Child 3 will create 11710 files.
1681 Mon Apr 11 10:30:00 2011 Child 9 finished creating 383 directories.
1682 Mon Apr 11 10:30:00 2011 Child 9 will create 11710 files.
1683 Mon Apr 11 10:30:00 2011 Child 1 finished creating 383 directories.
1684 Mon Apr 11 10:30:00 2011 Child 1 will create 11710 files.
1685 Mon Apr 11 10:30:03 2011 Child 6 finished creating 383 directories.
1686 Mon Apr 11 10:30:03 2011 Child 6 will create 11710 files.
1687 Mon Apr 11 10:30:08 2011 Child 10 finished creating 383 directories.
1688 Mon Apr 11 10:30:08 2011 Child 10 will create 11710 files.
1689 Mon Apr 11 10:30:11 2011 Child 0 finished creating 383 directories.
1690 Mon Apr 11 10:30:11 2011 Child 0 will create 11710 files.
1691 Mon Apr 11 10:30:11 2011 Child 8 finished creating 383 directories.
1692 Mon Apr 11 10:30:11 2011 Child 8 will create 11710 files.
1693 Mon Apr 11 10:30:11 2011 Child 2 finished creating 383 directories.
1694 Mon Apr 11 10:30:11 2011 Child 2 will create 11710 files.
1695 Mon Apr 11 10:30:16 2011 Child 5 finished creating 383 directories.
1696 Mon Apr 11 10:30:16 2011 Child 5 will create 11710 files.
1697 Mon Apr 11 10:30:17 2011 Child 11 finished creating 383 directories.
1698 Mon Apr 11 10:30:17 2011 Child 11 will create 11710 files.
1699 Mon Apr 11 10:30:19 2011 Child 4 finished creating 383 directories.
1700 Mon Apr 11 10:30:19 2011 Child 4 will create 11710 files.
1701 Mon Apr 11 10:34:58 2011 Child 7 verified 11071 files (949 creates).
1702 Mon Apr 11 10:34:58 2011 Child 14 verified 11148 files (1026 creates).
1703 Mon Apr 11 10:35:37 2011 Child 6 verified 10431 files (309 creates).
1704 Mon Apr 11 10:35:39 2011 Child 15 verified 10533 files (411 creates).
1705 Mon Apr 11 10:35:39 2011 Child 10 verified 10353 files (231 creates).
1706 Mon Apr 11 10:35:39 2011 Child 11 verified 10318 files (196 creates).
1707 Mon Apr 11 10:35:39 2011 Child 0 verified 10353 files (231 creates).
1708 Mon Apr 11 10:35:39 2011 Child 8 verified 10353 files (231 creates).
1709 Mon Apr 11 10:35:39 2011 Child 4 verified 10318 files (196 creates).
1710 Mon Apr 11 10:35:49 2011 Child 13 verified 10533 files (411 creates).
1711 Mon Apr 11 10:35:49 2011 Child 5 verified 10275 files (153 creates).
1712 Mon Apr 11 10:35:50 2011 Child 12 verified 10972 files (850 creates).
1713 Mon Apr 11 10:35:50 2011 Child 1 verified 10485 files (363 creates).
1714 Mon Apr 11 10:35:50 2011 Child 2 verified 10318 files (196 creates).
1715 Mon Apr 11 10:35:50 2011 Child 9 verified 10485 files (363 creates).
1716 Mon Apr 11 10:35:50 2011 Child 3 verified 10533 files (411 creates).
1717 Mon Apr 11 10:37:03 2011 Child 14 verified 11710 files, created 1474
1718 Mon Apr 11 10:37:03 2011 Child 14 will create 2 symlinks.
1719 Mon Apr 11 10:37:03 2011 Child 14 finished creating 2 symlinks.
1720 Mon Apr 11 10:37:10 2011 Child 7 verified 11710 files, created 1474
1721 Mon Apr 11 10:37:10 2011 Child 7 will create 2 symlinks.
1722 Mon Apr 11 10:37:10 2011 Child 7 finished creating 2 symlinks.
1723 Mon Apr 11 10:37:55 2011 Child 12 verified 11710 files, created 1474
1724 Mon Apr 11 10:37:55 2011 Child 12 will create 2 symlinks.
1725 Mon Apr 11 10:37:56 2011 Child 12 finished creating 2 symlinks.
1726 Mon Apr 11 10:39:29 2011 Child 3 verified 11710 files, created 1474
1727 Mon Apr 11 10:39:29 2011 Child 3 will create 2 symlinks.
1728 Mon Apr 11 10:39:29 2011 Child 3 finished creating 2 symlinks.
1729 Mon Apr 11 10:40:17 2011 Child 11 verified 11710 files, created 1474
1730 Mon Apr 11 10:40:17 2011 Child 11 will create 2 symlinks.
1731 Mon Apr 11 10:40:17 2011 Child 11 finished creating 2 symlinks.
1732 Mon Apr 11 10:40:18 2011 Child 15 verified 11710 files, created 1474
1733 Mon Apr 11 10:40:18 2011 Child 15 will create 2 symlinks.
1734 Mon Apr 11 10:40:18 2011 Child 15 finished creating 2 symlinks.
1735 Mon Apr 11 10:40:18 2011 Child 13 verified 11710 files, created 1474
1736 Mon Apr 11 10:40:18 2011 Child 13 will create 2 symlinks.
1737 Mon Apr 11 10:40:19 2011 Child 13 finished creating 2 symlinks.
1738 Mon Apr 11 10:40:30 2011 Child 1 verified 11710 files, created 1474
1739 Mon Apr 11 10:40:30 2011 Child 1 will create 2 symlinks.
1740 Mon Apr 11 10:40:30 2011 Child 1 finished creating 2 symlinks.
1741 Mon Apr 11 10:40:33 2011 Child 8 verified 11710 files, created 1474
1742 Mon Apr 11 10:40:33 2011 Child 8 will create 2 symlinks.
1743 Mon Apr 11 10:40:33 2011 Child 8 finished creating 2 symlinks.
1744 Mon Apr 11 10:40:33 2011 Child 0 verified 11710 files, created 1474
1745 Mon Apr 11 10:40:33 2011 Child 0 will create 2 symlinks.
1746 Mon Apr 11 10:40:34 2011 Child 9 verified 11710 files, created 1474
1747 Mon Apr 11 10:40:34 2011 Child 9 will create 2 symlinks.
1748 Mon Apr 11 10:40:34 2011 Child 6 verified 11710 files, created 1474
1749 Mon Apr 11 10:40:34 2011 Child 6 will create 2 symlinks.
1750 Mon Apr 11 10:40:34 2011 Child 4 verified 11710 files, created 1474
1751 Mon Apr 11 10:40:34 2011 Child 4 will create 2 symlinks.
1752 Mon Apr 11 10:40:36 2011 Child 9 finished creating 2 symlinks.
1753 Mon Apr 11 10:40:36 2011 Child 6 finished creating 2 symlinks.
1754 Mon Apr 11 10:40:36 2011 Child 0 finished creating 2 symlinks.
1755 Mon Apr 11 10:40:36 2011 Child 4 finished creating 2 symlinks.
1756 Mon Apr 11 10:40:40 2011 Child 10 verified 11437 files (1315 creates).
1757 Mon Apr 11 10:40:42 2011 Child 10 verified 11710 files, created 1474
1758 Mon Apr 11 10:40:42 2011 Child 10 will create 2 symlinks.
1759 Mon Apr 11 10:40:42 2011 Child 10 finished creating 2 symlinks.
1760 Mon Apr 11 10:40:42 2011 Child 2 verified 11710 files, created 1474
1761 Mon Apr 11 10:40:42 2011 Child 2 will create 2 symlinks.
1762 Mon Apr 11 10:40:42 2011 Child 2 finished creating 2 symlinks.
1763 Mon Apr 11 10:40:47 2011 Child 5 verified 11710 files, created 1474
1764 Mon Apr 11 10:40:47 2011 Child 5 will create 2 symlinks.
1765 Mon Apr 11 10:40:47 2011 Child 5 finished creating 2 symlinks.
1766 Mon Apr 11 10:40:47 2011 Completed.
1767 Mon Apr 11 10:40:47 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1768 Mon Apr 11 10:40:47 2011 Completed.
1769 Mon Apr 11 10:40:47 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1770 Mon Apr 11 10:40:48 2011 Received.
1771 Mon Apr 11 10:40:48 2011 Performing 300 seconds pretest warmup.
1772 Mon Apr 11 10:45:48 2011 Completed.
1773 Mon Apr 11 10:45:48 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1774 Mon Apr 11 10:45:48 2011 Completed.
1775 Mon Apr 11 10:45:48 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1776 Mon Apr 11 10:45:49 2011 Received.
1777 Mon Apr 11 10:45:49 2011 Starting 300 seconds test run.
1778 SFS Single Client (c09-44) Results, Mon Apr 11 10:50:59 2011
1779 --------------------------------------------------------------------------------------
1780 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1781 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1782 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1783            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1784 --------------------------------------------------------------------------------------
1785 getattr     26.0%    25.8%    60565    60565      0      0.47     4.20      0.02  1.6%
1786 setattr      4.0%     4.1%     9518     9518      0     21.06    78.25      0.18 11.2%
1787 lookup      24.0%    24.1%    56558    56558      0      0.54     7.83      0.02  1.7%
1788 readlink     1.0%     1.0%     2357     2357      0      0.42     2.11      0.06  0.1%
1789 read        18.0%    17.9%    42038    51618      0     17.08    63.64      0.08 40.2%
1790 write       10.0%    10.0%    23402    28293      0     17.25    33.89      0.07 22.6%
1791 create       1.0%     1.0%     2421     2421      0     30.90    81.04      0.36  4.2%
1792 remove       1.0%     1.0%     2327     2327      0     28.16    97.86      0.40  3.7%
1793 readdir      1.0%     1.0%     2385     2385      0      0.68     2.45      0.06  0.1%
1794 fsstat       1.0%     1.0%     2324     2324      0      0.56     3.32      0.07  0.1%
1795 access      11.0%    11.0%    25873    25873      0      0.50     4.42      0.03  0.7%
1796 commit       0.0%     0.0%        0     9670      0      0.00     0.00      0.00 13.6%
1797 readdirplus  2.0%     2.0%     4618     4618      0      1.04     3.49      0.05  0.3%
1798 --------------------------------------------------------------------------------------
1799
1800       --------------------------------------------------
1801       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1802       --------------------------+-----------------------
1803 SFS NFS V3 THROUGHPUT:  802.69 Ops/Sec   AVG. RESPONSE TIME:    7.62 Msec/Op
1804 TCP PROTOCOL
1805 FAST CALLS: 0
1806 NFS V3 MIXFILE: [ SFS Default ]
1807 CLIENT REQUESTED LOAD: 800 Ops/Sec
1808 TOTAL LOGICAL NFS V3 OPERATIONS:  234386         TEST TIME: 292 Sec
1809 TOTAL PHYSICAL NFS V3 OPERATIONS: 258527
1810 PHYSICAL NFS V3 THROUGHPUT:  885.36 Ops/Sec
1811 FILE SET SIZE CREATED: 97534080.0 KB
1812 FILE SET SIZE ACCESSED: 29268720.0 - 29716992.0 KB  (100.00% to 101.53% of Base)
1813
1814 ------------------------------------------------------------------------
1815
1816 Mon Apr 11 10:50:59 2011 Completed.
1817
1818 Client Test Parameters:
1819         Number of processes = 16
1820         Requested Load (NFS V3 operations/second) = 800
1821         Maximum number of outstanding biod writes = 8
1822         Maximum number of outstanding biod reads = 8
1823         Warm-up time (seconds) = 300
1824         Run time (seconds) = 300
1825         File Set = 183680 Files created for I/O operations
1826                    55104 Files accessed for I/O operations
1827                    3680 Files for non-I/O operations
1828                      32 Symlinks
1829                    6112 Directories
1830                         Additional non-I/O files created as necessary
1831
1832
1833 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1834 Mon Apr 11 10:50:59 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1835 Mon Apr 11 10:50:59 2011 Completed.
1836 Mon Apr 11 10:50:59 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1837
1838 ************************************************************************
1839 Mon Apr 11 10:51:00 2011 Received.
1840 Mon Apr 11 10:51:00 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1841 Mon Apr 11 10:51:00 2011 Completed.
1842 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 900  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
1843 NFS V3 Protocol Version
1844
1845 ************************************************************************
1846 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1847 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1848
1849 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1850 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1851
1852 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 10:51:02 2011
1853         Client hostname = c09-44
1854         Prime Client hostname = c09-44.sysnet.ucsd.edu
1855
1856 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1857 NFS V3 Protocol
1858 Mon Apr 11 10:51:02 2011 Mounting 16 remote test directories.
1859 Child[000]: NFS negotiated block size 32768
1860 Child[001]: NFS negotiated block size 32768
1861 Child[002]: NFS negotiated block size 32768
1862 Child[003]: NFS negotiated block size 32768
1863 Child[004]: NFS negotiated block size 32768
1864 Child[005]: NFS negotiated block size 32768
1865 Child[006]: NFS negotiated block size 32768
1866 Child[007]: NFS negotiated block size 32768
1867 Child[008]: NFS negotiated block size 32768
1868 Child[009]: NFS negotiated block size 32768
1869 Child[010]: NFS negotiated block size 32768
1870 Child[011]: NFS negotiated block size 32768
1871 Child[012]: NFS negotiated block size 32768
1872 Child[013]: NFS negotiated block size 32768
1873 Child[014]: NFS negotiated block size 32768
1874 Child[015]: NFS negotiated block size 32768
1875 Mon Apr 11 10:51:16 2011 Completed.
1876 Mon Apr 11 10:51:16 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1877 Mon Apr 11 10:51:16 2011 Completed.
1878 Mon Apr 11 10:51:16 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1879 Mon Apr 11 10:51:18 2011 Received.
1880
1881 Mon Apr 11 10:51:18 2011 Initializing test directories.
1882 Mon Apr 11 10:51:18 2011 Child 15 will create 433 directories.
1883 Mon Apr 11 10:51:18 2011 Child 13 will create 433 directories.
1884 Mon Apr 11 10:51:18 2011 Child 14 will create 433 directories.
1885 Mon Apr 11 10:51:18 2011 Child 1 will create 433 directories.
1886 Mon Apr 11 10:51:18 2011 Child 0 will create 433 directories.
1887 Mon Apr 11 10:51:18 2011 Child 12 will create 433 directories.
1888 Mon Apr 11 10:51:18 2011 Child 4 will create 433 directories.
1889 Mon Apr 11 10:51:18 2011 Child 2 will create 433 directories.
1890 Mon Apr 11 10:51:18 2011 Child 11 will create 433 directories.
1891 Mon Apr 11 10:51:18 2011 Child 10 will create 433 directories.
1892 Mon Apr 11 10:51:18 2011 Child 6 will create 433 directories.
1893 Mon Apr 11 10:51:18 2011 Child 3 will create 433 directories.
1894 Mon Apr 11 10:51:18 2011 Child 7 will create 433 directories.
1895 Mon Apr 11 10:51:18 2011 Child 9 will create 433 directories.
1896 Mon Apr 11 10:51:18 2011 Child 5 will create 433 directories.
1897 Mon Apr 11 10:51:18 2011 Child 8 will create 433 directories.
1898 Mon Apr 11 10:51:21 2011 Child 10 finished creating 433 directories.
1899 Mon Apr 11 10:51:21 2011 Child 10 will create 13221 files.
1900 Mon Apr 11 10:51:21 2011 Child 5 finished creating 433 directories.
1901 Mon Apr 11 10:51:21 2011 Child 5 will create 13221 files.
1902 Mon Apr 11 10:51:25 2011 Child 7 finished creating 433 directories.
1903 Mon Apr 11 10:51:25 2011 Child 7 will create 13221 files.
1904 Mon Apr 11 10:51:26 2011 Child 3 finished creating 433 directories.
1905 Mon Apr 11 10:51:26 2011 Child 3 will create 13221 files.
1906 Mon Apr 11 10:51:29 2011 Child 8 finished creating 433 directories.
1907 Mon Apr 11 10:51:29 2011 Child 8 will create 13221 files.
1908 Mon Apr 11 10:51:34 2011 Child 13 finished creating 433 directories.
1909 Mon Apr 11 10:51:34 2011 Child 13 will create 13221 files.
1910 Mon Apr 11 10:51:35 2011 Child 12 finished creating 433 directories.
1911 Mon Apr 11 10:51:35 2011 Child 12 will create 13221 files.
1912 Mon Apr 11 10:51:37 2011 Child 1 finished creating 433 directories.
1913 Mon Apr 11 10:51:37 2011 Child 1 will create 13221 files.
1914 Mon Apr 11 10:51:39 2011 Child 11 finished creating 433 directories.
1915 Mon Apr 11 10:51:39 2011 Child 11 will create 13221 files.
1916 Mon Apr 11 10:51:41 2011 Child 15 finished creating 433 directories.
1917 Mon Apr 11 10:51:41 2011 Child 15 will create 13221 files.
1918 Mon Apr 11 10:51:48 2011 Child 0 finished creating 433 directories.
1919 Mon Apr 11 10:51:48 2011 Child 0 will create 13221 files.
1920 Mon Apr 11 10:51:50 2011 Child 4 finished creating 433 directories.
1921 Mon Apr 11 10:51:50 2011 Child 4 will create 13221 files.
1922 Mon Apr 11 10:51:57 2011 Child 2 finished creating 433 directories.
1923 Mon Apr 11 10:51:57 2011 Child 2 will create 13221 files.
1924 Mon Apr 11 10:51:57 2011 Child 14 finished creating 433 directories.
1925 Mon Apr 11 10:51:57 2011 Child 14 will create 13221 files.
1926 Mon Apr 11 10:51:57 2011 Child 6 finished creating 433 directories.
1927 Mon Apr 11 10:51:57 2011 Child 6 will create 13221 files.
1928 Mon Apr 11 10:51:57 2011 Child 9 finished creating 433 directories.
1929 Mon Apr 11 10:51:57 2011 Child 9 will create 13221 files.
1930 Mon Apr 11 10:55:05 2011 Child 10 verified 13221 files, created 1612
1931 Mon Apr 11 10:55:05 2011 Child 10 will create 2 symlinks.
1932 Mon Apr 11 10:55:05 2011 Child 10 finished creating 2 symlinks.
1933 Mon Apr 11 10:55:25 2011 Child 5 verified 13221 files, created 1612
1934 Mon Apr 11 10:55:25 2011 Child 5 will create 2 symlinks.
1935 Mon Apr 11 10:55:25 2011 Child 5 finished creating 2 symlinks.
1936 Mon Apr 11 10:56:35 2011 Child 8 verified 12735 files (1255 creates).
1937 Mon Apr 11 10:56:37 2011 Child 3 verified 12951 files (1471 creates).
1938 Mon Apr 11 10:56:39 2011 Child 7 verified 12951 files (1471 creates).
1939 Mon Apr 11 10:56:40 2011 Child 13 verified 12859 files (1379 creates).
1940 Mon Apr 11 10:56:41 2011 Child 11 verified 12382 files (902 creates).
1941 Mon Apr 11 10:56:41 2011 Child 12 verified 12541 files (1061 creates).
1942 Mon Apr 11 10:56:41 2011 Child 1 verified 12670 files (1190 creates).
1943 Mon Apr 11 10:56:42 2011 Child 15 verified 12548 files (1068 creates).
1944 Mon Apr 11 10:56:52 2011 Child 0 verified 12241 files (761 creates).
1945 Mon Apr 11 10:56:52 2011 Child 4 verified 12314 files (834 creates).
1946 Mon Apr 11 10:56:58 2011 Child 2 verified 12414 files (934 creates).
1947 Mon Apr 11 10:57:00 2011 Child 6 verified 12313 files (833 creates).
1948 Mon Apr 11 10:57:00 2011 Child 9 verified 12313 files (833 creates).
1949 Mon Apr 11 10:57:01 2011 Child 14 verified 12260 files (780 creates).
1950 Mon Apr 11 10:57:05 2011 Child 7 verified 13221 files, created 1612
1951 Mon Apr 11 10:57:05 2011 Child 7 will create 2 symlinks.
1952 Mon Apr 11 10:57:05 2011 Child 3 verified 13221 files, created 1612
1953 Mon Apr 11 10:57:05 2011 Child 3 will create 2 symlinks.
1954 Mon Apr 11 10:57:06 2011 Child 7 finished creating 2 symlinks.
1955 Mon Apr 11 10:57:06 2011 Child 3 finished creating 2 symlinks.
1956 Mon Apr 11 10:57:14 2011 Child 13 verified 13221 files, created 1612
1957 Mon Apr 11 10:57:14 2011 Child 13 will create 2 symlinks.
1958 Mon Apr 11 10:57:14 2011 Child 13 finished creating 2 symlinks.
1959 Mon Apr 11 10:57:40 2011 Child 1 verified 13221 files, created 1612
1960 Mon Apr 11 10:57:40 2011 Child 1 will create 2 symlinks.
1961 Mon Apr 11 10:57:40 2011 Child 1 finished creating 2 symlinks.
1962 Mon Apr 11 10:57:49 2011 Child 8 verified 13221 files, created 1612
1963 Mon Apr 11 10:57:49 2011 Child 8 will create 2 symlinks.
1964 Mon Apr 11 10:57:49 2011 Child 8 finished creating 2 symlinks.
1965 Mon Apr 11 10:58:06 2011 Child 15 verified 13221 files, created 1612
1966 Mon Apr 11 10:58:06 2011 Child 15 will create 2 symlinks.
1967 Mon Apr 11 10:58:06 2011 Child 15 finished creating 2 symlinks.
1968 Mon Apr 11 10:58:09 2011 Child 12 verified 13221 files, created 1612
1969 Mon Apr 11 10:58:09 2011 Child 12 will create 2 symlinks.
1970 Mon Apr 11 10:58:10 2011 Child 12 finished creating 2 symlinks.
1971 Mon Apr 11 10:58:16 2011 Child 11 verified 13221 files, created 1612
1972 Mon Apr 11 10:58:16 2011 Child 11 will create 2 symlinks.
1973 Mon Apr 11 10:58:18 2011 Child 11 finished creating 2 symlinks.
1974 Mon Apr 11 10:58:24 2011 Child 2 verified 13221 files, created 1612
1975 Mon Apr 11 10:58:24 2011 Child 2 will create 2 symlinks.
1976 Mon Apr 11 10:58:27 2011 Child 4 verified 13221 files, created 1612
1977 Mon Apr 11 10:58:27 2011 Child 4 will create 2 symlinks.
1978 Mon Apr 11 10:58:27 2011 Child 4 finished creating 2 symlinks.
1979 Mon Apr 11 10:58:30 2011 Child 2 finished creating 2 symlinks.
1980 Mon Apr 11 10:58:38 2011 Child 14 verified 13221 files, created 1612
1981 Mon Apr 11 10:58:38 2011 Child 14 will create 2 symlinks.
1982 Mon Apr 11 10:58:38 2011 Child 14 finished creating 2 symlinks.
1983 Mon Apr 11 10:58:38 2011 Child 0 verified 13221 files, created 1612
1984 Mon Apr 11 10:58:38 2011 Child 0 will create 2 symlinks.
1985 Mon Apr 11 10:58:38 2011 Child 0 finished creating 2 symlinks.
1986 Mon Apr 11 10:58:39 2011 Child 9 verified 13221 files, created 1612
1987 Mon Apr 11 10:58:39 2011 Child 9 will create 2 symlinks.
1988 Mon Apr 11 10:58:39 2011 Child 9 finished creating 2 symlinks.
1989 Mon Apr 11 10:58:39 2011 Child 6 verified 13221 files, created 1612
1990 Mon Apr 11 10:58:39 2011 Child 6 will create 2 symlinks.
1991 Mon Apr 11 10:58:39 2011 Child 6 finished creating 2 symlinks.
1992 Mon Apr 11 10:58:39 2011 Completed.
1993 Mon Apr 11 10:58:39 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1994 Mon Apr 11 10:58:39 2011 Completed.
1995 Mon Apr 11 10:58:39 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1996 Mon Apr 11 10:58:39 2011 Received.
1997 Mon Apr 11 10:58:39 2011 Performing 300 seconds pretest warmup.
1998 Mon Apr 11 11:03:39 2011 Completed.
1999 Mon Apr 11 11:03:39 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2000 Mon Apr 11 11:03:39 2011 Completed.
2001 Mon Apr 11 11:03:39 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2002 Mon Apr 11 11:03:40 2011 Received.
2003 Mon Apr 11 11:03:40 2011 Starting 300 seconds test run.
2004 SFS Single Client (c09-44) Results, Mon Apr 11 11:08:51 2011
2005 --------------------------------------------------------------------------------------
2006 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2007 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2008 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2009            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2010 --------------------------------------------------------------------------------------
2011 getattr     26.0%    26.0%    61368    61368      0      2.63     8.81      0.02  2.8%
2012 setattr      4.0%     4.0%     9347     9347      0     43.51    61.77      0.16  7.0%
2013 lookup      24.0%    24.1%    56938    56938      0      3.63   184.88      0.11  3.6%
2014 readlink     1.0%     1.0%     2360     2360      0      2.77     8.21      0.12  0.1%
2015 read        18.0%    18.0%    42503    52137      0     59.09   599.22      0.23 43.5%
2016 write       10.0%    10.1%    23842    28808      0     66.52   826.21      0.36 27.5%
2017 create       1.0%     1.0%     2408     2408      0     81.72    71.65      0.34  3.4%
2018 remove       1.0%     1.0%     2344     2344      0     66.56   858.02      1.19  2.7%
2019 readdir      1.0%     1.0%     2332     2332      0      3.26     9.18      0.12  0.1%
2020 fsstat       1.0%     1.0%     2377     2377      0      2.81     8.71      0.12  0.1%
2021 access      11.0%    10.9%    25847    25847      0      2.69     9.93      0.04  1.2%
2022 commit       0.0%     0.0%        0     9506      0      0.00     0.00      0.00  7.7%
2023 readdirplus  2.0%     2.0%     4725     4725      0      3.45     7.96      0.08  0.3%
2024 --------------------------------------------------------------------------------------
2025
2026       --------------------------------------------------
2027       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2028       --------------------------+-----------------------
2029 SFS NFS V3 THROUGHPUT:  790.60 Ops/Sec   AVG. RESPONSE TIME:   24.43 Msec/Op
2030 TCP PROTOCOL
2031 FAST CALLS: 0
2032 NFS V3 MIXFILE: [ SFS Default ]
2033 CLIENT REQUESTED LOAD: 900 Ops/Sec
2034 TOTAL LOGICAL NFS V3 OPERATIONS:  236391         TEST TIME: 299 Sec
2035 TOTAL PHYSICAL NFS V3 OPERATIONS: 260497
2036 PHYSICAL NFS V3 THROUGHPUT:  871.22 Ops/Sec
2037 FILE SET SIZE CREATED: 110116656.0 KB
2038 FILE SET SIZE ACCESSED: 33040944.0 - 33470748.0 KB  (100.00% to 101.30% of Base)
2039
2040 ------------------------------------------------------------------------
2041
2042 Mon Apr 11 11:08:51 2011 Completed.
2043
2044 Client Test Parameters:
2045         Number of processes = 16
2046         Requested Load (NFS V3 operations/second) = 900
2047         Maximum number of outstanding biod writes = 8
2048         Maximum number of outstanding biod reads = 8
2049         Warm-up time (seconds) = 300
2050         Run time (seconds) = 300
2051         File Set = 207376 Files created for I/O operations
2052                    62208 Files accessed for I/O operations
2053                    4160 Files for non-I/O operations
2054                      32 Symlinks
2055                    6912 Directories
2056                         Additional non-I/O files created as necessary
2057
2058
2059 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2060 Mon Apr 11 11:08:51 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2061 Mon Apr 11 11:08:51 2011 Completed.
2062 Mon Apr 11 11:08:51 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2063
2064 ************************************************************************
2065 Mon Apr 11 11:08:51 2011 Received.
2066 Mon Apr 11 11:08:51 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2067 Mon Apr 11 11:08:51 2011 Completed.
2068 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1000  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2069 NFS V3 Protocol Version
2070
2071 ************************************************************************
2072 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2073 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2074
2075 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2076 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2077
2078 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 11:08:53 2011
2079         Client hostname = c09-44
2080         Prime Client hostname = c09-44.sysnet.ucsd.edu
2081
2082 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2083 NFS V3 Protocol
2084 Mon Apr 11 11:08:53 2011 Mounting 16 remote test directories.
2085 Child[000]: NFS negotiated block size 32768
2086 Child[001]: NFS negotiated block size 32768
2087 Child[002]: NFS negotiated block size 32768
2088 Child[003]: NFS negotiated block size 32768
2089 Child[004]: NFS negotiated block size 32768
2090 Child[005]: NFS negotiated block size 32768
2091 Child[006]: NFS negotiated block size 32768
2092 Child[007]: NFS negotiated block size 32768
2093 Child[008]: NFS negotiated block size 32768
2094 Child[009]: NFS negotiated block size 32768
2095 Child[010]: NFS negotiated block size 32768
2096 Child[011]: NFS negotiated block size 32768
2097 Child[012]: NFS negotiated block size 32768
2098 Child[013]: NFS negotiated block size 32768
2099 Child[014]: NFS negotiated block size 32768
2100 Child[015]: NFS negotiated block size 32768
2101 Mon Apr 11 11:09:07 2011 Completed.
2102 Mon Apr 11 11:09:07 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2103 Mon Apr 11 11:09:07 2011 Completed.
2104 Mon Apr 11 11:09:07 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2105 Mon Apr 11 11:09:09 2011 Received.
2106
2107 Mon Apr 11 11:09:09 2011 Initializing test directories.
2108 Mon Apr 11 11:09:09 2011 Child 15 will create 482 directories.
2109 Mon Apr 11 11:09:09 2011 Child 1 will create 482 directories.
2110 Mon Apr 11 11:09:09 2011 Child 14 will create 482 directories.
2111 Mon Apr 11 11:09:09 2011 Child 0 will create 482 directories.
2112 Mon Apr 11 11:09:09 2011 Child 2 will create 482 directories.
2113 Mon Apr 11 11:09:09 2011 Child 13 will create 482 directories.
2114 Mon Apr 11 11:09:09 2011 Child 4 will create 482 directories.
2115 Mon Apr 11 11:09:09 2011 Child 12 will create 482 directories.
2116 Mon Apr 11 11:09:09 2011 Child 3 will create 482 directories.
2117 Mon Apr 11 11:09:09 2011 Child 11 will create 482 directories.
2118 Mon Apr 11 11:09:09 2011 Child 5 will create 482 directories.
2119 Mon Apr 11 11:09:09 2011 Child 9 will create 482 directories.
2120 Mon Apr 11 11:09:09 2011 Child 10 will create 482 directories.
2121 Mon Apr 11 11:09:09 2011 Child 7 will create 482 directories.
2122 Mon Apr 11 11:09:09 2011 Child 6 will create 482 directories.
2123 Mon Apr 11 11:09:09 2011 Child 8 will create 482 directories.
2124 Mon Apr 11 11:09:10 2011 Child 6 finished creating 482 directories.
2125 Mon Apr 11 11:09:10 2011 Child 6 will create 14732 files.
2126 Mon Apr 11 11:09:14 2011 Child 9 finished creating 482 directories.
2127 Mon Apr 11 11:09:14 2011 Child 9 will create 14732 files.
2128 Mon Apr 11 11:09:15 2011 Child 3 finished creating 482 directories.
2129 Mon Apr 11 11:09:15 2011 Child 3 will create 14732 files.
2130 Mon Apr 11 11:09:16 2011 Child 8 finished creating 482 directories.
2131 Mon Apr 11 11:09:16 2011 Child 8 will create 14732 files.
2132 Mon Apr 11 11:09:17 2011 Child 1 finished creating 482 directories.
2133 Mon Apr 11 11:09:17 2011 Child 1 will create 14732 files.
2134 Mon Apr 11 11:09:20 2011 Child 0 finished creating 482 directories.
2135 Mon Apr 11 11:09:20 2011 Child 0 will create 14732 files.
2136 Mon Apr 11 11:09:21 2011 Child 10 finished creating 482 directories.
2137 Mon Apr 11 11:09:21 2011 Child 10 will create 14732 files.
2138 Mon Apr 11 11:09:22 2011 Child 11 finished creating 482 directories.
2139 Mon Apr 11 11:09:22 2011 Child 11 will create 14732 files.
2140 Mon Apr 11 11:09:23 2011 Child 7 finished creating 482 directories.
2141 Mon Apr 11 11:09:23 2011 Child 7 will create 14732 files.
2142 Mon Apr 11 11:09:28 2011 Child 15 finished creating 482 directories.
2143 Mon Apr 11 11:09:28 2011 Child 15 will create 14732 files.
2144 Mon Apr 11 11:09:29 2011 Child 13 finished creating 482 directories.
2145 Mon Apr 11 11:09:29 2011 Child 13 will create 14732 files.
2146 Mon Apr 11 11:09:30 2011 Child 2 finished creating 482 directories.
2147 Mon Apr 11 11:09:30 2011 Child 2 will create 14732 files.
2148 Mon Apr 11 11:09:31 2011 Child 4 finished creating 482 directories.
2149 Mon Apr 11 11:09:31 2011 Child 4 will create 14732 files.
2150 Mon Apr 11 11:09:31 2011 Child 5 finished creating 482 directories.
2151 Mon Apr 11 11:09:31 2011 Child 5 will create 14732 files.
2152 Mon Apr 11 11:10:00 2011 Child 12 finished creating 482 directories.
2153 Mon Apr 11 11:10:00 2011 Child 12 will create 14732 files.
2154 Mon Apr 11 11:10:00 2011 Child 14 finished creating 482 directories.
2155 Mon Apr 11 11:10:00 2011 Child 14 will create 14732 files.
2156 Mon Apr 11 11:14:17 2011 Child 3 verified 13887 files (926 creates).
2157 Mon Apr 11 11:14:24 2011 Child 8 verified 13953 files (992 creates).
2158 Mon Apr 11 11:14:26 2011 Child 10 verified 13718 files (757 creates).
2159 Mon Apr 11 11:14:26 2011 Child 6 verified 14214 files (1253 creates).
2160 Mon Apr 11 11:14:26 2011 Child 9 verified 13903 files (942 creates).
2161 Mon Apr 11 11:14:26 2011 Child 11 verified 13629 files (668 creates).
2162 Mon Apr 11 11:14:26 2011 Child 1 verified 13812 files (851 creates).
2163 Mon Apr 11 11:14:26 2011 Child 0 verified 13761 files (800 creates).
2164 Mon Apr 11 11:14:27 2011 Child 7 verified 13421 files (460 creates).
2165 Mon Apr 11 11:14:30 2011 Child 13 verified 13609 files (648 creates).
2166 Mon Apr 11 11:14:30 2011 Child 15 verified 13469 files (508 creates).
2167 Mon Apr 11 11:14:34 2011 Child 2 verified 13384 files (423 creates).
2168 Mon Apr 11 11:14:34 2011 Child 4 verified 13461 files (500 creates).
2169 Mon Apr 11 11:14:34 2011 Child 5 verified 13376 files (415 creates).
2170 Mon Apr 11 11:15:25 2011 Child 14 verified 13205 files (244 creates).
2171 Mon Apr 11 11:16:09 2011 Child 12 verified 13212 files (251 creates).
2172 Mon Apr 11 11:17:06 2011 Child 6 verified 14732 files, created 1627
2173 Mon Apr 11 11:17:06 2011 Child 6 will create 2 symlinks.
2174 Mon Apr 11 11:17:06 2011 Child 6 finished creating 2 symlinks.
2175 Mon Apr 11 11:18:51 2011 Child 8 verified 14732 files, created 1627
2176 Mon Apr 11 11:18:51 2011 Child 8 will create 2 symlinks.
2177 Mon Apr 11 11:18:51 2011 Child 8 finished creating 2 symlinks.
2178 Mon Apr 11 11:19:03 2011 Child 3 verified 14732 files, created 1627
2179 Mon Apr 11 11:19:03 2011 Child 3 will create 2 symlinks.
2180 Mon Apr 11 11:19:03 2011 Child 3 finished creating 2 symlinks.
2181 Mon Apr 11 11:19:25 2011 Child 10 verified 14219 files (1258 creates).
2182 Mon Apr 11 11:19:25 2011 Child 9 verified 14334 files (1373 creates).
2183 Mon Apr 11 11:19:27 2011 Child 11 verified 14082 files (1121 creates).
2184 Mon Apr 11 11:19:27 2011 Child 7 verified 13969 files (1008 creates).
2185 Mon Apr 11 11:19:33 2011 Child 1 verified 14346 files (1385 creates).
2186 Mon Apr 11 11:19:33 2011 Child 0 verified 14346 files (1385 creates).
2187 Mon Apr 11 11:19:34 2011 Child 2 verified 13904 files (943 creates).
2188 Mon Apr 11 11:19:34 2011 Child 4 verified 13904 files (943 creates).
2189 Mon Apr 11 11:19:36 2011 Child 5 verified 14001 files (1040 creates).
2190 Mon Apr 11 11:19:50 2011 Child 15 verified 14065 files (1104 creates).
2191 Mon Apr 11 11:19:50 2011 Child 13 verified 14277 files (1316 creates).
2192 Mon Apr 11 11:20:07 2011 Child 0 verified 14732 files, created 1627
2193 Mon Apr 11 11:20:07 2011 Child 0 will create 2 symlinks.
2194 Mon Apr 11 11:20:07 2011 Child 0 finished creating 2 symlinks.
2195 Mon Apr 11 11:20:08 2011 Child 1 verified 14732 files, created 1627
2196 Mon Apr 11 11:20:08 2011 Child 1 will create 2 symlinks.
2197 Mon Apr 11 11:20:09 2011 Child 1 finished creating 2 symlinks.
2198 Mon Apr 11 11:20:22 2011 Child 9 verified 14732 files, created 1627
2199 Mon Apr 11 11:20:22 2011 Child 9 will create 2 symlinks.
2200 Mon Apr 11 11:20:26 2011 Child 14 verified 13873 files (912 creates).
2201 Mon Apr 11 11:20:35 2011 Child 9 finished creating 2 symlinks.
2202 Mon Apr 11 11:20:45 2011 Child 11 verified 14732 files, created 1627
2203 Mon Apr 11 11:20:45 2011 Child 11 will create 2 symlinks.
2204 Mon Apr 11 11:20:48 2011 Child 11 finished creating 2 symlinks.
2205 Mon Apr 11 11:21:10 2011 Child 12 verified 14214 files (1253 creates).
2206 Mon Apr 11 11:21:10 2011 Child 15 verified 14732 files, created 1627
2207 Mon Apr 11 11:21:10 2011 Child 15 will create 2 symlinks.
2208 Mon Apr 11 11:21:10 2011 Child 15 finished creating 2 symlinks.
2209 Mon Apr 11 11:21:13 2011 Child 10 verified 14732 files, created 1627
2210 Mon Apr 11 11:21:13 2011 Child 10 will create 2 symlinks.
2211 Mon Apr 11 11:21:13 2011 Child 10 finished creating 2 symlinks.
2212 Mon Apr 11 11:21:13 2011 Child 5 verified 14732 files, created 1627
2213 Mon Apr 11 11:21:13 2011 Child 5 will create 2 symlinks.
2214 Mon Apr 11 11:21:13 2011 Child 5 finished creating 2 symlinks.
2215 Mon Apr 11 11:21:13 2011 Child 7 verified 14732 files, created 1627
2216 Mon Apr 11 11:21:13 2011 Child 7 will create 2 symlinks.
2217 Mon Apr 11 11:21:13 2011 Child 7 finished creating 2 symlinks.
2218 Mon Apr 11 11:21:20 2011 Child 2 verified 14732 files, created 1627
2219 Mon Apr 11 11:21:20 2011 Child 2 will create 2 symlinks.
2220 Mon Apr 11 11:21:20 2011 Child 2 finished creating 2 symlinks.
2221 Mon Apr 11 11:21:24 2011 Child 4 verified 14732 files, created 1627
2222 Mon Apr 11 11:21:24 2011 Child 4 will create 2 symlinks.
2223 Mon Apr 11 11:21:24 2011 Child 4 finished creating 2 symlinks.
2224 Mon Apr 11 11:21:30 2011 Child 12 verified 14732 files, created 1627
2225 Mon Apr 11 11:21:30 2011 Child 12 will create 2 symlinks.
2226 Mon Apr 11 11:21:30 2011 Child 12 finished creating 2 symlinks.
2227 Mon Apr 11 11:21:32 2011 Child 14 verified 14732 files, created 1627
2228 Mon Apr 11 11:21:32 2011 Child 14 will create 2 symlinks.
2229 Mon Apr 11 11:21:32 2011 Child 14 finished creating 2 symlinks.
2230 Mon Apr 11 11:21:54 2011 Child 13 verified 14732 files, created 1627
2231 Mon Apr 11 11:21:54 2011 Child 13 will create 2 symlinks.
2232 Mon Apr 11 11:21:54 2011 Child 13 finished creating 2 symlinks.
2233 Mon Apr 11 11:21:55 2011 Completed.
2234 Mon Apr 11 11:21:55 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2235 Mon Apr 11 11:21:55 2011 Completed.
2236 Mon Apr 11 11:21:55 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2237 Mon Apr 11 11:21:56 2011 Received.
2238 Mon Apr 11 11:21:56 2011 Performing 300 seconds pretest warmup.
2239 Mon Apr 11 11:26:56 2011 Completed.
2240 Mon Apr 11 11:26:56 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2241 Mon Apr 11 11:26:56 2011 Completed.
2242 Mon Apr 11 11:26:56 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2243 Mon Apr 11 11:26:57 2011 Received.
2244 Mon Apr 11 11:26:57 2011 Starting 300 seconds test run.
2245 SFS Single Client (c09-44) Results, Mon Apr 11 11:32:07 2011
2246 --------------------------------------------------------------------------------------
2247 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2248 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2249 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2250            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2251 --------------------------------------------------------------------------------------
2252 getattr     26.0%    25.9%    57854    57854      0      3.85    61.53      0.06  3.8%
2253 setattr      4.0%     4.0%     9025     9025      0     56.32   104.27      0.21  8.6%
2254 lookup      24.0%    24.0%    53635    53635      0      3.42    15.69      0.03  3.1%
2255 readlink     1.0%     1.0%     2249     2249      0      3.71    25.44      0.21  0.1%
2256 read        18.0%    18.0%    40136    49062      0     64.13   305.01      0.17 43.8%
2257 write       10.0%    10.1%    22442    27284      0     60.21   274.35      0.22 23.0%
2258 create       1.0%     1.0%     2249     2249      0    100.95   112.31      0.44  3.9%
2259 remove       1.0%     1.0%     2239     2239      0     67.22   116.69      0.45  2.6%
2260 readdir      1.0%     1.0%     2195     2195      0      3.72    10.82      0.14  0.1%
2261 fsstat       1.0%     1.0%     2283     2283      0      3.98    31.31      0.23  0.2%
2262 access      11.0%    11.0%    24476    24476      0      3.33    10.04      0.04  1.4%
2263 commit       0.0%     0.0%        0     8708      0      0.00     0.00      0.00  9.2%
2264 readdirplus  2.0%     2.0%     4500     4500      0      3.98     9.67      0.09  0.3%
2265 --------------------------------------------------------------------------------------
2266
2267       --------------------------------------------------
2268       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2269       --------------------------+-----------------------
2270 SFS NFS V3 THROUGHPUT:  746.76 Ops/Sec   AVG. RESPONSE TIME:   26.33 Msec/Op
2271 TCP PROTOCOL
2272 FAST CALLS: 0
2273 NFS V3 MIXFILE: [ SFS Default ]
2274 CLIENT REQUESTED LOAD: 1000 Ops/Sec
2275 TOTAL LOGICAL NFS V3 OPERATIONS:  223283         TEST TIME: 299 Sec
2276 TOTAL PHYSICAL NFS V3 OPERATIONS: 245759
2277 PHYSICAL NFS V3 THROUGHPUT:  821.93 Ops/Sec
2278 FILE SET SIZE CREATED: 122707728.0 KB
2279 FILE SET SIZE ACCESSED: 36813168.0 - 37219776.0 KB  (100.00% to 101.10% of Base)
2280
2281 ------------------------------------------------------------------------
2282
2283 Mon Apr 11 11:32:07 2011 Completed.
2284
2285 Client Test Parameters:
2286         Number of processes = 16
2287         Requested Load (NFS V3 operations/second) = 1000
2288         Maximum number of outstanding biod writes = 8
2289         Maximum number of outstanding biod reads = 8
2290         Warm-up time (seconds) = 300
2291         Run time (seconds) = 300
2292         File Set = 231088 Files created for I/O operations
2293                    69312 Files accessed for I/O operations
2294                    4624 Files for non-I/O operations
2295                      32 Symlinks
2296                    7696 Directories
2297                         Additional non-I/O files created as necessary
2298
2299
2300 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2301 Mon Apr 11 11:32:07 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2302 Mon Apr 11 11:32:07 2011 Completed.
2303 Mon Apr 11 11:32:07 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2304
2305 ************************************************************************
2306 Mon Apr 11 11:32:08 2011 Received.
2307 Mon Apr 11 11:32:08 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2308 Mon Apr 11 11:32:08 2011 Completed.
2309 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1100  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2310 NFS V3 Protocol Version
2311
2312 ************************************************************************
2313 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2314 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2315
2316 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2317 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2318
2319 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 11:32:10 2011
2320         Client hostname = c09-44
2321         Prime Client hostname = c09-44.sysnet.ucsd.edu
2322
2323 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2324 NFS V3 Protocol
2325 Mon Apr 11 11:32:10 2011 Mounting 16 remote test directories.
2326 Child[000]: NFS negotiated block size 32768
2327 Child[001]: NFS negotiated block size 32768
2328 Child[002]: NFS negotiated block size 32768
2329 Child[003]: NFS negotiated block size 32768
2330 Child[004]: NFS negotiated block size 32768
2331 Child[005]: NFS negotiated block size 32768
2332 Child[006]: NFS negotiated block size 32768
2333 Child[007]: NFS negotiated block size 32768
2334 Child[008]: NFS negotiated block size 32768
2335 Child[009]: NFS negotiated block size 32768
2336 Child[010]: NFS negotiated block size 32768
2337 Child[011]: NFS negotiated block size 32768
2338 Child[012]: NFS negotiated block size 32768
2339 Child[013]: NFS negotiated block size 32768
2340 Child[014]: NFS negotiated block size 32768
2341 Child[015]: NFS negotiated block size 32768
2342 Mon Apr 11 11:32:24 2011 Completed.
2343 Mon Apr 11 11:32:24 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2344 Mon Apr 11 11:32:24 2011 Completed.
2345 Mon Apr 11 11:32:24 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2346 Mon Apr 11 11:32:26 2011 Received.
2347
2348 Mon Apr 11 11:32:26 2011 Initializing test directories.
2349 Mon Apr 11 11:32:26 2011 Child 15 will create 527 directories.
2350 Mon Apr 11 11:32:26 2011 Child 9 will create 527 directories.
2351 Mon Apr 11 11:32:26 2011 Child 1 will create 527 directories.
2352 Mon Apr 11 11:32:26 2011 Child 0 will create 527 directories.
2353 Mon Apr 11 11:32:26 2011 Child 12 will create 527 directories.
2354 Mon Apr 11 11:32:26 2011 Child 10 will create 527 directories.
2355 Mon Apr 11 11:32:26 2011 Child 3 will create 527 directories.
2356 Mon Apr 11 11:32:26 2011 Child 6 will create 527 directories.
2357 Mon Apr 11 11:32:26 2011 Child 4 will create 527 directories.
2358 Mon Apr 11 11:32:26 2011 Child 8 will create 527 directories.
2359 Mon Apr 11 11:32:26 2011 Child 5 will create 527 directories.
2360 Mon Apr 11 11:32:26 2011 Child 14 will create 527 directories.
2361 Mon Apr 11 11:32:26 2011 Child 7 will create 527 directories.
2362 Mon Apr 11 11:32:26 2011 Child 13 will create 527 directories.
2363 Mon Apr 11 11:32:26 2011 Child 2 will create 527 directories.
2364 Mon Apr 11 11:32:26 2011 Child 11 will create 527 directories.
2365 Mon Apr 11 11:32:31 2011 Child 4 finished creating 527 directories.
2366 Mon Apr 11 11:32:31 2011 Child 4 will create 16118 files.
2367 Mon Apr 11 11:32:32 2011 Child 14 finished creating 527 directories.
2368 Mon Apr 11 11:32:32 2011 Child 14 will create 16118 files.
2369 Mon Apr 11 11:32:35 2011 Child 2 finished creating 527 directories.
2370 Mon Apr 11 11:32:35 2011 Child 2 will create 16118 files.
2371 Mon Apr 11 11:32:38 2011 Child 1 finished creating 527 directories.
2372 Mon Apr 11 11:32:38 2011 Child 1 will create 16118 files.
2373 Mon Apr 11 11:32:39 2011 Child 6 finished creating 527 directories.
2374 Mon Apr 11 11:32:39 2011 Child 6 will create 16118 files.
2375 Mon Apr 11 11:32:41 2011 Child 7 finished creating 527 directories.
2376 Mon Apr 11 11:32:41 2011 Child 7 will create 16118 files.
2377 Mon Apr 11 11:32:44 2011 Child 8 finished creating 527 directories.
2378 Mon Apr 11 11:32:44 2011 Child 8 will create 16118 files.
2379 Mon Apr 11 11:32:52 2011 Child 12 finished creating 527 directories.
2380 Mon Apr 11 11:32:52 2011 Child 12 will create 16118 files.
2381 Mon Apr 11 11:32:54 2011 Child 15 finished creating 527 directories.
2382 Mon Apr 11 11:32:54 2011 Child 15 will create 16118 files.
2383 Mon Apr 11 11:32:57 2011 Child 5 finished creating 527 directories.
2384 Mon Apr 11 11:32:57 2011 Child 5 will create 16118 files.
2385 Mon Apr 11 11:32:57 2011 Child 13 finished creating 527 directories.
2386 Mon Apr 11 11:32:57 2011 Child 13 will create 16118 files.
2387 Mon Apr 11 11:32:58 2011 Child 11 finished creating 527 directories.
2388 Mon Apr 11 11:32:58 2011 Child 11 will create 16118 files.
2389 Mon Apr 11 11:33:01 2011 Child 9 finished creating 527 directories.
2390 Mon Apr 11 11:33:01 2011 Child 9 will create 16118 files.
2391 Mon Apr 11 11:33:05 2011 Child 0 finished creating 527 directories.
2392 Mon Apr 11 11:33:05 2011 Child 0 will create 16118 files.
2393 Mon Apr 11 11:33:10 2011 Child 3 finished creating 527 directories.
2394 Mon Apr 11 11:33:10 2011 Child 3 will create 16118 files.
2395 Mon Apr 11 11:33:16 2011 Child 10 finished creating 527 directories.
2396 Mon Apr 11 11:33:16 2011 Child 10 will create 16118 files.
2397 Mon Apr 11 11:36:50 2011 Child 4 verified 16118 files, created 1517
2398 Mon Apr 11 11:36:50 2011 Child 4 will create 2 symlinks.
2399 Mon Apr 11 11:36:50 2011 Child 4 finished creating 2 symlinks.
2400 Mon Apr 11 11:37:23 2011 Child 14 verified 16118 files, created 1517
2401 Mon Apr 11 11:37:23 2011 Child 14 will create 2 symlinks.
2402 Mon Apr 11 11:37:23 2011 Child 14 finished creating 2 symlinks.
2403 Mon Apr 11 11:38:07 2011 Child 7 verified 15374 files (931 creates).
2404 Mon Apr 11 11:38:13 2011 Child 15 verified 15072 files (629 creates).
2405 Mon Apr 11 11:38:13 2011 Child 9 verified 14980 files (537 creates).
2406 Mon Apr 11 11:38:15 2011 Child 12 verified 15071 files (628 creates).
2407 Mon Apr 11 11:38:16 2011 Child 8 verified 15181 files (738 creates).
2408 Mon Apr 11 11:38:16 2011 Child 1 verified 15512 files (1069 creates).
2409 Mon Apr 11 11:38:16 2011 Child 2 verified 15672 files (1229 creates).
2410 Mon Apr 11 11:38:16 2011 Child 13 verified 14980 files (537 creates).
2411 Mon Apr 11 11:38:27 2011 Child 3 verified 14980 files (537 creates).
2412 Mon Apr 11 11:38:27 2011 Child 5 verified 14980 files (537 creates).
2413 Mon Apr 11 11:38:27 2011 Child 11 verified 14980 files (537 creates).
2414 Mon Apr 11 11:38:27 2011 Child 10 verified 14916 files (473 creates).
2415 Mon Apr 11 11:38:27 2011 Child 6 verified 15279 files (836 creates).
2416 Mon Apr 11 11:38:27 2011 Child 0 verified 14916 files (473 creates).
2417 Mon Apr 11 11:39:01 2011 Child 2 verified 16118 files, created 1517
2418 Mon Apr 11 11:39:01 2011 Child 2 will create 2 symlinks.
2419 Mon Apr 11 11:39:01 2011 Child 2 finished creating 2 symlinks.
2420 Mon Apr 11 11:39:34 2011 Child 1 verified 16118 files, created 1517
2421 Mon Apr 11 11:39:34 2011 Child 1 will create 2 symlinks.
2422 Mon Apr 11 11:39:34 2011 Child 1 finished creating 2 symlinks.
2423 Mon Apr 11 11:39:48 2011 Child 7 verified 16118 files, created 1517
2424 Mon Apr 11 11:39:48 2011 Child 7 will create 2 symlinks.
2425 Mon Apr 11 11:39:48 2011 Child 7 finished creating 2 symlinks.
2426 Mon Apr 11 11:40:10 2011 Child 6 verified 16118 files, created 1517
2427 Mon Apr 11 11:40:10 2011 Child 6 will create 2 symlinks.
2428 Mon Apr 11 11:40:10 2011 Child 6 finished creating 2 symlinks.
2429 Mon Apr 11 11:40:20 2011 Child 8 verified 16118 files, created 1517
2430 Mon Apr 11 11:40:20 2011 Child 8 will create 2 symlinks.
2431 Mon Apr 11 11:40:20 2011 Child 8 finished creating 2 symlinks.
2432 Mon Apr 11 11:40:39 2011 Child 12 verified 16118 files, created 1517
2433 Mon Apr 11 11:40:39 2011 Child 12 will create 2 symlinks.
2434 Mon Apr 11 11:40:39 2011 Child 12 finished creating 2 symlinks.
2435 Mon Apr 11 11:40:42 2011 Child 15 verified 16118 files, created 1517
2436 Mon Apr 11 11:40:42 2011 Child 15 will create 2 symlinks.
2437 Mon Apr 11 11:40:42 2011 Child 15 finished creating 2 symlinks.
2438 Mon Apr 11 11:40:50 2011 Child 13 verified 16118 files, created 1517
2439 Mon Apr 11 11:40:50 2011 Child 13 will create 2 symlinks.
2440 Mon Apr 11 11:40:50 2011 Child 13 finished creating 2 symlinks.
2441 Mon Apr 11 11:40:52 2011 Child 10 verified 16118 files, created 1517
2442 Mon Apr 11 11:40:52 2011 Child 10 will create 2 symlinks.
2443 Mon Apr 11 11:40:53 2011 Child 10 finished creating 2 symlinks.
2444 Mon Apr 11 11:40:53 2011 Child 5 verified 16118 files, created 1517
2445 Mon Apr 11 11:40:53 2011 Child 5 will create 2 symlinks.
2446 Mon Apr 11 11:40:53 2011 Child 5 finished creating 2 symlinks.
2447 Mon Apr 11 11:40:53 2011 Child 3 verified 16118 files, created 1517
2448 Mon Apr 11 11:40:53 2011 Child 3 will create 2 symlinks.
2449 Mon Apr 11 11:40:53 2011 Child 3 finished creating 2 symlinks.
2450 Mon Apr 11 11:40:53 2011 Child 11 verified 16118 files, created 1517
2451 Mon Apr 11 11:40:53 2011 Child 11 will create 2 symlinks.
2452 Mon Apr 11 11:40:53 2011 Child 11 finished creating 2 symlinks.
2453 Mon Apr 11 11:40:54 2011 Child 0 verified 16118 files, created 1517
2454 Mon Apr 11 11:40:54 2011 Child 0 will create 2 symlinks.
2455 Mon Apr 11 11:40:55 2011 Child 0 finished creating 2 symlinks.
2456 Mon Apr 11 11:41:00 2011 Child 9 verified 16118 files, created 1517
2457 Mon Apr 11 11:41:00 2011 Child 9 will create 2 symlinks.
2458 Mon Apr 11 11:41:00 2011 Child 9 finished creating 2 symlinks.
2459 Mon Apr 11 11:41:01 2011 Completed.
2460 Mon Apr 11 11:41:01 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2461 Mon Apr 11 11:41:01 2011 Completed.
2462 Mon Apr 11 11:41:01 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2463 Mon Apr 11 11:41:01 2011 Received.
2464 Mon Apr 11 11:41:01 2011 Performing 300 seconds pretest warmup.
2465 Mon Apr 11 11:46:01 2011 Completed.
2466 Mon Apr 11 11:46:01 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2467 Mon Apr 11 11:46:01 2011 Completed.
2468 Mon Apr 11 11:46:01 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2469 Mon Apr 11 11:46:02 2011 Received.
2470 Mon Apr 11 11:46:02 2011 Starting 300 seconds test run.
2471 SFS Single Client (c09-44) Results, Mon Apr 11 11:51:12 2011
2472 --------------------------------------------------------------------------------------
2473 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2474 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2475 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2476            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2477 --------------------------------------------------------------------------------------
2478 getattr     26.0%    25.9%    48526    48526      0      3.88    16.55      0.04  3.2%
2479 setattr      4.0%     3.9%     7365     7365      0     67.94   122.58      0.25  8.6%
2480 lookup      24.0%    24.3%    45400    45400      0      4.23    68.49      0.08  3.3%
2481 readlink     1.0%     1.0%     1881     1881      0      3.56    10.02      0.14  0.1%
2482 read        18.0%    17.9%    33408    40677      0     76.54   345.84      0.20 44.0%
2483 write       10.0%    10.0%    18691    22504      0     72.40   321.99      0.26 23.3%
2484 create       1.0%     0.9%     1751     1751      0    132.85   182.42      0.63  4.0%
2485 remove       1.0%     1.0%     1916     1916      0     81.43   151.55      0.55  2.7%
2486 readdir      1.0%     1.0%     1910     1910      0      4.74    14.44      0.17  0.2%
2487 fsstat       1.0%     1.0%     1894     1894      0      4.17    27.77      0.24  0.1%
2488 access      11.0%    11.0%    20511    20511      0      3.69    11.25      0.05  1.3%
2489 commit       0.0%     0.0%        0     7016      0      0.00     0.00      0.00  8.9%
2490 readdirplus  2.0%     2.1%     3854     3854      0      4.65    12.07      0.11  0.3%
2491 --------------------------------------------------------------------------------------
2492
2493       --------------------------------------------------
2494       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2495       --------------------------+-----------------------
2496 SFS NFS V3 THROUGHPUT:  625.77 Ops/Sec   AVG. RESPONSE TIME:   31.05 Msec/Op
2497 TCP PROTOCOL
2498 FAST CALLS: 0
2499 NFS V3 MIXFILE: [ SFS Default ]
2500 CLIENT REQUESTED LOAD: 1100 Ops/Sec
2501 TOTAL LOGICAL NFS V3 OPERATIONS:  187107         TEST TIME: 299 Sec
2502 TOTAL PHYSICAL NFS V3 OPERATIONS: 205205
2503 PHYSICAL NFS V3 THROUGHPUT:  686.30 Ops/Sec
2504 FILE SET SIZE CREATED: 134245296.0 KB
2505 FILE SET SIZE ACCESSED: 40279536.0 - 40618232.0 KB  (100.00% to 100.84% of Base)
2506
2507 ------------------------------------------------------------------------
2508
2509 Mon Apr 11 11:51:12 2011 Completed.
2510
2511 Client Test Parameters:
2512         Number of processes = 16
2513         Requested Load (NFS V3 operations/second) = 1100
2514         Maximum number of outstanding biod writes = 8
2515         Maximum number of outstanding biod reads = 8
2516         Warm-up time (seconds) = 300
2517         Run time (seconds) = 300
2518         File Set = 252816 Files created for I/O operations
2519                    75840 Files accessed for I/O operations
2520                    5072 Files for non-I/O operations
2521                      32 Symlinks
2522                    8416 Directories
2523                         Additional non-I/O files created as necessary
2524
2525
2526 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2527 Mon Apr 11 11:51:12 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2528 Mon Apr 11 11:51:12 2011 Completed.
2529 Mon Apr 11 11:51:12 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2530
2531 ************************************************************************
2532 Mon Apr 11 11:51:13 2011 Received.
2533 Mon Apr 11 11:51:13 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2534 Mon Apr 11 11:51:13 2011 Completed.
2535 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1200  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2536 NFS V3 Protocol Version
2537
2538 ************************************************************************
2539 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2540 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2541
2542 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2543 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2544
2545 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 11:51:15 2011
2546         Client hostname = c09-44
2547         Prime Client hostname = c09-44.sysnet.ucsd.edu
2548
2549 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2550 NFS V3 Protocol
2551 Mon Apr 11 11:51:15 2011 Mounting 16 remote test directories.
2552 Child[000]: NFS negotiated block size 32768
2553 Child[001]: NFS negotiated block size 32768
2554 Child[002]: NFS negotiated block size 32768
2555 Child[003]: NFS negotiated block size 32768
2556 Child[004]: NFS negotiated block size 32768
2557 Child[005]: NFS negotiated block size 32768
2558 Child[006]: NFS negotiated block size 32768
2559 Child[007]: NFS negotiated block size 32768
2560 Child[008]: NFS negotiated block size 32768
2561 Child[009]: NFS negotiated block size 32768
2562 Child[010]: NFS negotiated block size 32768
2563 Child[011]: NFS negotiated block size 32768
2564 Child[012]: NFS negotiated block size 32768
2565 Child[013]: NFS negotiated block size 32768
2566 Child[014]: NFS negotiated block size 32768
2567 Child[015]: NFS negotiated block size 32768
2568 Mon Apr 11 11:51:29 2011 Completed.
2569 Mon Apr 11 11:51:29 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2570 Mon Apr 11 11:51:29 2011 Completed.
2571 Mon Apr 11 11:51:29 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2572 Mon Apr 11 11:51:31 2011 Received.
2573
2574 Mon Apr 11 11:51:31 2011 Initializing test directories.
2575 Mon Apr 11 11:51:31 2011 Child 14 will create 577 directories.
2576 Mon Apr 11 11:51:31 2011 Child 15 will create 577 directories.
2577 Mon Apr 11 11:51:31 2011 Child 9 will create 577 directories.
2578 Mon Apr 11 11:51:31 2011 Child 0 will create 577 directories.
2579 Mon Apr 11 11:51:31 2011 Child 4 will create 577 directories.
2580 Mon Apr 11 11:51:31 2011 Child 12 will create 577 directories.
2581 Mon Apr 11 11:51:31 2011 Child 6 will create 577 directories.
2582 Mon Apr 11 11:51:31 2011 Child 3 will create 577 directories.
2583 Mon Apr 11 11:51:31 2011 Child 11 will create 577 directories.
2584 Mon Apr 11 11:51:31 2011 Child 10 will create 577 directories.
2585 Mon Apr 11 11:51:31 2011 Child 5 will create 577 directories.
2586 Mon Apr 11 11:51:31 2011 Child 2 will create 577 directories.
2587 Mon Apr 11 11:51:31 2011 Child 1 will create 577 directories.
2588 Mon Apr 11 11:51:31 2011 Child 8 will create 577 directories.
2589 Mon Apr 11 11:51:31 2011 Child 7 will create 577 directories.
2590 Mon Apr 11 11:51:31 2011 Child 13 will create 577 directories.
2591 Mon Apr 11 11:51:32 2011 Child 6 finished creating 577 directories.
2592 Mon Apr 11 11:51:32 2011 Child 6 will create 17628 files.
2593 Mon Apr 11 11:51:35 2011 Child 9 finished creating 577 directories.
2594 Mon Apr 11 11:51:35 2011 Child 9 will create 17628 files.
2595 Mon Apr 11 11:51:37 2011 Child 10 finished creating 577 directories.
2596 Mon Apr 11 11:51:37 2011 Child 10 will create 17628 files.
2597 Mon Apr 11 11:51:40 2011 Child 13 finished creating 577 directories.
2598 Mon Apr 11 11:51:40 2011 Child 13 will create 17628 files.
2599 Mon Apr 11 11:51:42 2011 Child 0 finished creating 577 directories.
2600 Mon Apr 11 11:51:42 2011 Child 0 will create 17628 files.
2601 Mon Apr 11 11:51:43 2011 Child 14 finished creating 577 directories.
2602 Mon Apr 11 11:51:43 2011 Child 14 will create 17628 files.
2603 Mon Apr 11 11:51:46 2011 Child 1 finished creating 577 directories.
2604 Mon Apr 11 11:51:46 2011 Child 1 will create 17628 files.
2605 Mon Apr 11 11:51:54 2011 Child 3 finished creating 577 directories.
2606 Mon Apr 11 11:51:54 2011 Child 3 will create 17628 files.
2607 Mon Apr 11 11:51:57 2011 Child 4 finished creating 577 directories.
2608 Mon Apr 11 11:51:57 2011 Child 4 will create 17628 files.
2609 Mon Apr 11 11:52:01 2011 Child 7 finished creating 577 directories.
2610 Mon Apr 11 11:52:01 2011 Child 7 will create 17628 files.
2611 Mon Apr 11 11:52:04 2011 Child 8 finished creating 577 directories.
2612 Mon Apr 11 11:52:04 2011 Child 8 will create 17628 files.
2613 Mon Apr 11 11:52:07 2011 Child 11 finished creating 577 directories.
2614 Mon Apr 11 11:52:07 2011 Child 11 will create 17628 files.
2615 Mon Apr 11 11:52:08 2011 Child 2 finished creating 577 directories.
2616 Mon Apr 11 11:52:08 2011 Child 2 will create 17628 files.
2617 Mon Apr 11 11:52:08 2011 Child 12 finished creating 577 directories.
2618 Mon Apr 11 11:52:08 2011 Child 12 will create 17628 files.
2619 Mon Apr 11 11:52:11 2011 Child 15 finished creating 577 directories.
2620 Mon Apr 11 11:52:11 2011 Child 15 will create 17628 files.
2621 Mon Apr 11 11:52:27 2011 Child 5 finished creating 577 directories.
2622 Mon Apr 11 11:52:27 2011 Child 5 will create 17628 files.
2623 Mon Apr 11 11:55:22 2011 Child 6 verified 17628 files, created 1655
2624 Mon Apr 11 11:55:22 2011 Child 6 will create 2 symlinks.
2625 Mon Apr 11 11:55:23 2011 Child 6 finished creating 2 symlinks.
2626 Mon Apr 11 11:56:15 2011 Child 9 verified 17628 files, created 1655
2627 Mon Apr 11 11:56:15 2011 Child 9 will create 2 symlinks.
2628 Mon Apr 11 11:56:16 2011 Child 9 finished creating 2 symlinks.
2629 Mon Apr 11 11:56:42 2011 Child 13 verified 17187 files (1386 creates).
2630 Mon Apr 11 11:56:43 2011 Child 0 verified 17067 files (1266 creates).
2631 Mon Apr 11 11:56:46 2011 Child 10 verified 17628 files, created 1655
2632 Mon Apr 11 11:56:46 2011 Child 10 will create 2 symlinks.
2633 Mon Apr 11 11:56:50 2011 Child 14 verified 17073 files (1272 creates).
2634 Mon Apr 11 11:56:51 2011 Child 1 verified 17073 files (1272 creates).
2635 Mon Apr 11 11:56:53 2011 Child 10 finished creating 2 symlinks.
2636 Mon Apr 11 11:56:55 2011 Child 3 verified 16925 files (1124 creates).
2637 Mon Apr 11 11:56:58 2011 Child 4 verified 16860 files (1059 creates).
2638 Mon Apr 11 11:57:03 2011 Child 7 verified 16804 files (1003 creates).
2639 Mon Apr 11 11:57:05 2011 Child 8 verified 16850 files (1049 creates).
2640 Mon Apr 11 11:57:08 2011 Child 11 verified 16874 files (1073 creates).
2641 Mon Apr 11 11:57:10 2011 Child 12 verified 16734 files (933 creates).
2642 Mon Apr 11 11:57:10 2011 Child 2 verified 16735 files (934 creates).
2643 Mon Apr 11 11:57:12 2011 Child 15 verified 16793 files (992 creates).
2644 Mon Apr 11 11:57:24 2011 Child 13 verified 17628 files, created 1655
2645 Mon Apr 11 11:57:24 2011 Child 13 will create 2 symlinks.
2646 Mon Apr 11 11:57:26 2011 Child 13 finished creating 2 symlinks.
2647 Mon Apr 11 11:57:30 2011 Child 5 verified 16857 files (1056 creates).
2648 Mon Apr 11 11:57:41 2011 Child 0 verified 17628 files, created 1655
2649 Mon Apr 11 11:57:41 2011 Child 0 will create 2 symlinks.
2650 Mon Apr 11 11:57:42 2011 Child 0 finished creating 2 symlinks.
2651 Mon Apr 11 11:57:43 2011 Child 14 verified 17628 files, created 1655
2652 Mon Apr 11 11:57:43 2011 Child 14 will create 2 symlinks.
2653 Mon Apr 11 11:57:44 2011 Child 14 finished creating 2 symlinks.
2654 Mon Apr 11 11:57:48 2011 Child 3 verified 17628 files, created 1655
2655 Mon Apr 11 11:57:48 2011 Child 3 will create 2 symlinks.
2656 Mon Apr 11 11:57:48 2011 Child 3 finished creating 2 symlinks.
2657 Mon Apr 11 11:57:54 2011 Child 7 verified 17628 files, created 1655
2658 Mon Apr 11 11:57:54 2011 Child 7 will create 2 symlinks.
2659 Mon Apr 11 11:57:54 2011 Child 7 finished creating 2 symlinks.
2660 Mon Apr 11 11:57:54 2011 Child 11 verified 17628 files, created 1655
2661 Mon Apr 11 11:57:54 2011 Child 11 will create 2 symlinks.
2662 Mon Apr 11 11:57:54 2011 Child 11 finished creating 2 symlinks.
2663 Mon Apr 11 11:57:55 2011 Child 8 verified 17628 files, created 1655
2664 Mon Apr 11 11:57:55 2011 Child 8 will create 2 symlinks.
2665 Mon Apr 11 11:57:55 2011 Child 8 finished creating 2 symlinks.
2666 Mon Apr 11 11:57:56 2011 Child 1 verified 17628 files, created 1655
2667 Mon Apr 11 11:57:56 2011 Child 1 will create 2 symlinks.
2668 Mon Apr 11 11:57:57 2011 Child 1 finished creating 2 symlinks.
2669 Mon Apr 11 11:57:57 2011 Child 15 verified 17628 files, created 1655
2670 Mon Apr 11 11:57:57 2011 Child 15 will create 2 symlinks.
2671 Mon Apr 11 11:57:57 2011 Child 15 finished creating 2 symlinks.
2672 Mon Apr 11 11:57:58 2011 Child 4 verified 17628 files, created 1655
2673 Mon Apr 11 11:57:58 2011 Child 4 will create 2 symlinks.
2674 Mon Apr 11 11:57:58 2011 Child 4 finished creating 2 symlinks.
2675 Mon Apr 11 11:57:58 2011 Child 12 verified 17628 files, created 1655
2676 Mon Apr 11 11:57:58 2011 Child 12 will create 2 symlinks.
2677 Mon Apr 11 11:57:58 2011 Child 12 finished creating 2 symlinks.
2678 Mon Apr 11 11:57:58 2011 Child 2 verified 17628 files, created 1655
2679 Mon Apr 11 11:57:58 2011 Child 2 will create 2 symlinks.
2680 Mon Apr 11 11:57:58 2011 Child 2 finished creating 2 symlinks.
2681 Mon Apr 11 11:57:59 2011 Child 5 verified 17628 files, created 1655
2682 Mon Apr 11 11:57:59 2011 Child 5 will create 2 symlinks.
2683 Mon Apr 11 11:57:59 2011 Child 5 finished creating 2 symlinks.
2684 Mon Apr 11 11:57:59 2011 Completed.
2685 Mon Apr 11 11:57:59 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2686 Mon Apr 11 11:57:59 2011 Completed.
2687 Mon Apr 11 11:57:59 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2688 Mon Apr 11 11:58:00 2011 Received.
2689 Mon Apr 11 11:58:00 2011 Performing 300 seconds pretest warmup.
2690 Mon Apr 11 12:03:00 2011 Completed.
2691 Mon Apr 11 12:03:00 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2692 Mon Apr 11 12:03:00 2011 Completed.
2693 Mon Apr 11 12:03:00 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2694 Mon Apr 11 12:03:01 2011 Received.
2695 Mon Apr 11 12:03:01 2011 Starting 300 seconds test run.
2696 SFS Single Client (c09-44) Results, Mon Apr 11 12:08:11 2011
2697 --------------------------------------------------------------------------------------
2698 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2699 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2700 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2701            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2702 --------------------------------------------------------------------------------------
2703 getattr     26.0%    26.1%    34978    34978      0      4.90    16.03      0.04  3.0%
2704 setattr      4.0%     3.9%     5297     5297      0     90.70   139.09      0.32  8.4%
2705 lookup      24.0%    24.2%    32462    32462      0      5.21    22.58      0.05  3.0%
2706 readlink     1.0%     0.9%     1271     1271      0      4.85    13.60      0.20  0.1%
2707 read        18.0%    17.8%    23931    29322      0    115.08   914.77      0.38 48.4%
2708 write       10.0%     9.9%    13247    15839      0     86.98   591.95      0.41 20.3%
2709 create       1.0%     1.0%     1297     1297      0    175.72   168.00      0.71  4.0%
2710 remove       1.0%     1.0%     1324     1324      0    103.95   148.95      0.66  2.4%
2711 readdir      1.0%     1.0%     1340     1340      0      4.46    11.05      0.18  0.1%
2712 fsstat       1.0%     1.0%     1376     1376      0      5.09    13.43      0.19  0.1%
2713 access      11.0%    11.0%    14811    14811      0      5.00    19.21      0.07  1.3%
2714 commit       0.0%     0.0%        0     4749      0      0.00     0.00      0.00  8.6%
2715 readdirplus  2.0%     2.1%     2789     2789      0      5.17    12.02      0.13  0.3%
2716 --------------------------------------------------------------------------------------
2717
2718       --------------------------------------------------
2719       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2720       --------------------------+-----------------------
2721 SFS NFS V3 THROUGHPUT:  448.57 Ops/Sec   AVG. RESPONSE TIME:   42.40 Msec/Op
2722 TCP PROTOCOL
2723 FAST CALLS: 0
2724 NFS V3 MIXFILE: [ SFS Default ]
2725 CLIENT REQUESTED LOAD: 1200 Ops/Sec
2726 TOTAL LOGICAL NFS V3 OPERATIONS:  134123         TEST TIME: 299 Sec
2727 TOTAL PHYSICAL NFS V3 OPERATIONS: 146855
2728 PHYSICAL NFS V3 THROUGHPUT:  491.15 Ops/Sec
2729 FILE SET SIZE CREATED: 146827872.0 KB
2730 FILE SET SIZE ACCESSED: 44051760.0 - 44297180.0 KB  (100.00% to 100.56% of Base)
2731
2732 ------------------------------------------------------------------------
2733
2734 Mon Apr 11 12:08:11 2011 Completed.
2735
2736 Client Test Parameters:
2737         Number of processes = 16
2738         Requested Load (NFS V3 operations/second) = 1200
2739         Maximum number of outstanding biod writes = 8
2740         Maximum number of outstanding biod reads = 8
2741         Warm-up time (seconds) = 300
2742         Run time (seconds) = 300
2743         File Set = 276512 Files created for I/O operations
2744                    82944 Files accessed for I/O operations
2745                    5536 Files for non-I/O operations
2746                      32 Symlinks
2747                    9216 Directories
2748                         Additional non-I/O files created as necessary
2749
2750
2751 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2752 Mon Apr 11 12:08:11 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2753 Mon Apr 11 12:08:11 2011 Completed.
2754 Mon Apr 11 12:08:11 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2755
2756 ************************************************************************
2757 Mon Apr 11 12:08:12 2011 Received.
2758 Mon Apr 11 12:08:12 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2759 Mon Apr 11 12:08:12 2011 Completed.
2760 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1300  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2761 NFS V3 Protocol Version
2762
2763 ************************************************************************
2764 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2765 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2766
2767 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2768 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2769
2770 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 12:08:14 2011
2771         Client hostname = c09-44
2772         Prime Client hostname = c09-44.sysnet.ucsd.edu
2773
2774 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2775 NFS V3 Protocol
2776 Mon Apr 11 12:08:14 2011 Mounting 16 remote test directories.
2777 Child[000]: NFS negotiated block size 32768
2778 Child[001]: NFS negotiated block size 32768
2779 Child[002]: NFS negotiated block size 32768
2780 Child[003]: NFS negotiated block size 32768
2781 Child[004]: NFS negotiated block size 32768
2782 Child[005]: NFS negotiated block size 32768
2783 Child[006]: NFS negotiated block size 32768
2784 Child[007]: NFS negotiated block size 32768
2785 Child[008]: NFS negotiated block size 32768
2786 Child[009]: NFS negotiated block size 32768
2787 Child[010]: NFS negotiated block size 32768
2788 Child[011]: NFS negotiated block size 32768
2789 Child[012]: NFS negotiated block size 32768
2790 Child[013]: NFS negotiated block size 32768
2791 Child[014]: NFS negotiated block size 32768
2792 Child[015]: NFS negotiated block size 32768
2793 Mon Apr 11 12:08:28 2011 Completed.
2794 Mon Apr 11 12:08:28 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2795 Mon Apr 11 12:08:28 2011 Completed.
2796 Mon Apr 11 12:08:28 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2797 Mon Apr 11 12:08:30 2011 Received.
2798
2799 Mon Apr 11 12:08:30 2011 Initializing test directories.
2800 Mon Apr 11 12:08:30 2011 Child 14 will create 626 directories.
2801 Mon Apr 11 12:08:30 2011 Child 15 will create 626 directories.
2802 Mon Apr 11 12:08:30 2011 Child 12 will create 626 directories.
2803 Mon Apr 11 12:08:30 2011 Child 8 will create 626 directories.
2804 Mon Apr 11 12:08:30 2011 Child 11 will create 626 directories.
2805 Mon Apr 11 12:08:30 2011 Child 13 will create 626 directories.
2806 Mon Apr 11 12:08:30 2011 Child 7 will create 626 directories.
2807 Mon Apr 11 12:08:30 2011 Child 1 will create 626 directories.
2808 Mon Apr 11 12:08:30 2011 Child 10 will create 626 directories.
2809 Mon Apr 11 12:08:30 2011 Child 9 will create 626 directories.
2810 Mon Apr 11 12:08:30 2011 Child 5 will create 626 directories.
2811 Mon Apr 11 12:08:30 2011 Child 4 will create 626 directories.
2812 Mon Apr 11 12:08:30 2011 Child 6 will create 626 directories.
2813 Mon Apr 11 12:08:30 2011 Child 3 will create 626 directories.
2814 Mon Apr 11 12:08:30 2011 Child 0 will create 626 directories.
2815 Mon Apr 11 12:08:30 2011 Child 2 will create 626 directories.
2816 Mon Apr 11 12:08:31 2011 Child 12 finished creating 626 directories.
2817 Mon Apr 11 12:08:31 2011 Child 12 will create 19139 files.
2818 Mon Apr 11 12:08:32 2011 Child 5 finished creating 626 directories.
2819 Mon Apr 11 12:08:32 2011 Child 5 will create 19139 files.
2820 Mon Apr 11 12:08:35 2011 Child 13 finished creating 626 directories.
2821 Mon Apr 11 12:08:35 2011 Child 13 will create 19139 files.
2822 Mon Apr 11 12:08:37 2011 Child 7 finished creating 626 directories.
2823 Mon Apr 11 12:08:37 2011 Child 7 will create 19139 files.
2824 Mon Apr 11 12:08:37 2011 Child 4 finished creating 626 directories.
2825 Mon Apr 11 12:08:37 2011 Child 4 will create 19139 files.
2826 Mon Apr 11 12:08:39 2011 Child 1 finished creating 626 directories.
2827 Mon Apr 11 12:08:39 2011 Child 1 will create 19139 files.
2828 Mon Apr 11 12:09:06 2011 Child 10 finished creating 626 directories.
2829 Mon Apr 11 12:09:06 2011 Child 10 will create 19139 files.
2830 Mon Apr 11 12:09:06 2011 Child 6 finished creating 626 directories.
2831 Mon Apr 11 12:09:06 2011 Child 6 will create 19139 files.
2832 Mon Apr 11 12:09:07 2011 Child 14 finished creating 626 directories.
2833 Mon Apr 11 12:09:07 2011 Child 14 will create 19139 files.
2834 Mon Apr 11 12:09:09 2011 Child 0 finished creating 626 directories.
2835 Mon Apr 11 12:09:09 2011 Child 0 will create 19139 files.
2836 Mon Apr 11 12:09:10 2011 Child 3 finished creating 626 directories.
2837 Mon Apr 11 12:09:10 2011 Child 3 will create 19139 files.
2838 Mon Apr 11 12:09:20 2011 Child 11 finished creating 626 directories.
2839 Mon Apr 11 12:09:20 2011 Child 11 will create 19139 files.
2840 Mon Apr 11 12:09:20 2011 Child 8 finished creating 626 directories.
2841 Mon Apr 11 12:09:20 2011 Child 8 will create 19139 files.
2842 Mon Apr 11 12:09:27 2011 Child 2 finished creating 626 directories.
2843 Mon Apr 11 12:09:27 2011 Child 2 will create 19139 files.
2844 Mon Apr 11 12:09:27 2011 Child 15 finished creating 626 directories.
2845 Mon Apr 11 12:09:27 2011 Child 15 will create 19139 files.
2846 Mon Apr 11 12:09:28 2011 Child 9 finished creating 626 directories.
2847 Mon Apr 11 12:09:28 2011 Child 9 will create 19139 files.
2848 Mon Apr 11 12:13:22 2011 Child 12 verified 19139 files, created 1670
2849 Mon Apr 11 12:13:22 2011 Child 12 will create 2 symlinks.
2850 Mon Apr 11 12:13:22 2011 Child 12 finished creating 2 symlinks.
2851 Mon Apr 11 12:13:25 2011 Child 5 verified 19139 files, created 1670
2852 Mon Apr 11 12:13:25 2011 Child 5 will create 2 symlinks.
2853 Mon Apr 11 12:13:25 2011 Child 5 finished creating 2 symlinks.
2854 Mon Apr 11 12:13:57 2011 Child 4 verified 18498 files (1216 creates).
2855 Mon Apr 11 12:13:58 2011 Child 13 verified 18558 files (1276 creates).
2856 Mon Apr 11 12:13:58 2011 Child 1 verified 18326 files (1044 creates).
2857 Mon Apr 11 12:13:58 2011 Child 7 verified 18558 files (1276 creates).
2858 Mon Apr 11 12:14:16 2011 Child 3 verified 17958 files (676 creates).
2859 Mon Apr 11 12:14:16 2011 Child 0 verified 17936 files (654 creates).
2860 Mon Apr 11 12:14:16 2011 Child 10 verified 17991 files (709 creates).
2861 Mon Apr 11 12:14:28 2011 Child 15 verified 17857 files (575 creates).
2862 Mon Apr 11 12:14:42 2011 Child 9 verified 17859 files (577 creates).
2863 Mon Apr 11 12:14:42 2011 Child 8 verified 17866 files (584 creates).
2864 Mon Apr 11 12:14:42 2011 Child 11 verified 17991 files (709 creates).
2865 Mon Apr 11 12:14:42 2011 Child 14 verified 17936 files (654 creates).
2866 Mon Apr 11 12:14:42 2011 Child 6 verified 17936 files (654 creates).
2867 Mon Apr 11 12:15:02 2011 Child 2 verified 17866 files (584 creates).
2868 Mon Apr 11 12:15:40 2011 Child 13 verified 19139 files, created 1670
2869 Mon Apr 11 12:15:40 2011 Child 13 will create 2 symlinks.
2870 Mon Apr 11 12:15:40 2011 Child 7 verified 19139 files, created 1670
2871 Mon Apr 11 12:15:40 2011 Child 7 will create 2 symlinks.
2872 Mon Apr 11 12:15:45 2011 Child 13 finished creating 2 symlinks.
2873 Mon Apr 11 12:15:45 2011 Child 7 finished creating 2 symlinks.
2874 Mon Apr 11 12:16:06 2011 Child 1 verified 19139 files, created 1670
2875 Mon Apr 11 12:16:06 2011 Child 1 will create 2 symlinks.
2876 Mon Apr 11 12:16:06 2011 Child 1 finished creating 2 symlinks.
2877 Mon Apr 11 12:16:09 2011 Child 4 verified 19139 files, created 1670
2878 Mon Apr 11 12:16:09 2011 Child 4 will create 2 symlinks.
2879 Mon Apr 11 12:16:10 2011 Child 4 finished creating 2 symlinks.
2880 Mon Apr 11 12:17:33 2011 Child 11 verified 19139 files, created 1670
2881 Mon Apr 11 12:17:33 2011 Child 11 will create 2 symlinks.
2882 Mon Apr 11 12:17:33 2011 Child 11 finished creating 2 symlinks.
2883 Mon Apr 11 12:17:57 2011 Child 3 verified 19139 files, created 1670
2884 Mon Apr 11 12:17:57 2011 Child 3 will create 2 symlinks.
2885 Mon Apr 11 12:17:57 2011 Child 3 finished creating 2 symlinks.
2886 Mon Apr 11 12:17:58 2011 Child 10 verified 19139 files, created 1670
2887 Mon Apr 11 12:17:58 2011 Child 10 will create 2 symlinks.
2888 Mon Apr 11 12:17:58 2011 Child 10 finished creating 2 symlinks.
2889 Mon Apr 11 12:18:00 2011 Child 0 verified 19139 files, created 1670
2890 Mon Apr 11 12:18:00 2011 Child 0 will create 2 symlinks.
2891 Mon Apr 11 12:18:00 2011 Child 0 finished creating 2 symlinks.
2892 Mon Apr 11 12:18:05 2011 Child 6 verified 19139 files, created 1670
2893 Mon Apr 11 12:18:05 2011 Child 6 will create 2 symlinks.
2894 Mon Apr 11 12:18:05 2011 Child 6 finished creating 2 symlinks.
2895 Mon Apr 11 12:18:07 2011 Child 14 verified 19139 files, created 1670
2896 Mon Apr 11 12:18:07 2011 Child 14 will create 2 symlinks.
2897 Mon Apr 11 12:18:07 2011 Child 14 finished creating 2 symlinks.
2898 Mon Apr 11 12:18:07 2011 Child 8 verified 19139 files, created 1670
2899 Mon Apr 11 12:18:07 2011 Child 8 will create 2 symlinks.
2900 Mon Apr 11 12:18:07 2011 Child 8 finished creating 2 symlinks.
2901 Mon Apr 11 12:18:10 2011 Child 9 verified 19139 files, created 1670
2902 Mon Apr 11 12:18:10 2011 Child 9 will create 2 symlinks.
2903 Mon Apr 11 12:18:10 2011 Child 15 verified 19139 files, created 1670
2904 Mon Apr 11 12:18:10 2011 Child 15 will create 2 symlinks.
2905 Mon Apr 11 12:18:10 2011 Child 9 finished creating 2 symlinks.
2906 Mon Apr 11 12:18:10 2011 Child 15 finished creating 2 symlinks.
2907 Mon Apr 11 12:18:18 2011 Child 2 verified 19139 files, created 1670
2908 Mon Apr 11 12:18:18 2011 Child 2 will create 2 symlinks.
2909 Mon Apr 11 12:18:18 2011 Child 2 finished creating 2 symlinks.
2910 Mon Apr 11 12:18:19 2011 Completed.
2911 Mon Apr 11 12:18:19 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2912 Mon Apr 11 12:18:19 2011 Completed.
2913 Mon Apr 11 12:18:19 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2914 Mon Apr 11 12:18:19 2011 Received.
2915 Mon Apr 11 12:18:19 2011 Performing 300 seconds pretest warmup.
2916 Mon Apr 11 12:23:19 2011 Completed.
2917 Mon Apr 11 12:23:19 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2918 Mon Apr 11 12:23:19 2011 Completed.
2919 Mon Apr 11 12:23:19 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2920 Mon Apr 11 12:23:20 2011 Received.
2921 Mon Apr 11 12:23:20 2011 Starting 300 seconds test run.
2922 SFS Single Client (c09-44) Results, Mon Apr 11 12:28:30 2011
2923 --------------------------------------------------------------------------------------
2924 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2925 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2926 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2927            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2928 --------------------------------------------------------------------------------------
2929 getattr     26.0%    25.8%    33069    33069      0      5.92    31.10      0.06  3.3%
2930 setattr      4.0%     4.0%     5077     5077      0    102.86   167.64      0.36  8.9%
2931 lookup      24.0%    24.2%    30934    30934      0      6.12    36.97      0.07  3.2%
2932 readlink     1.0%     1.0%     1230     1230      0      6.16    15.73      0.22  0.1%
2933 read        18.0%    17.9%    22911    28324      0    112.62   347.84      0.24 43.9%
2934 write       10.0%    10.0%    12765    15260      0    104.16   465.57      0.37 22.6%
2935 create       1.0%     1.0%     1288     1288      0    211.93   263.12      0.89  4.6%
2936 remove       1.0%     0.9%     1213     1213      0    122.69   183.46      0.76  2.5%
2937 readdir      1.0%     1.0%     1297     1297      0      5.89    14.57      0.21  0.1%
2938 fsstat       1.0%     1.0%     1322     1322      0      5.99    14.49      0.21  0.1%
2939 access      11.0%    11.1%    14264    14264      0      6.31    42.01      0.11  1.5%
2940 commit       0.0%     0.0%        0     4577      0      0.00     0.00      0.00  8.7%
2941 readdirplus  2.0%     2.0%     2615     2615      0      6.07    14.00      0.14  0.3%
2942 --------------------------------------------------------------------------------------
2943
2944       --------------------------------------------------
2945       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2946       --------------------------+-----------------------
2947 SFS NFS V3 THROUGHPUT:  428.04 Ops/Sec   AVG. RESPONSE TIME:   45.93 Msec/Op
2948 TCP PROTOCOL
2949 FAST CALLS: 0
2950 NFS V3 MIXFILE: [ SFS Default ]
2951 CLIENT REQUESTED LOAD: 1300 Ops/Sec
2952 TOTAL LOGICAL NFS V3 OPERATIONS:  127985         TEST TIME: 299 Sec
2953 TOTAL PHYSICAL NFS V3 OPERATIONS: 140470
2954 PHYSICAL NFS V3 THROUGHPUT:  469.79 Ops/Sec
2955 FILE SET SIZE CREATED: 159410448.0 KB
2956 FILE SET SIZE ACCESSED: 47823984.0 - 48074632.0 KB  (100.00% to 100.52% of Base)
2957
2958 ------------------------------------------------------------------------
2959
2960 Mon Apr 11 12:28:30 2011 Completed.
2961
2962 Client Test Parameters:
2963         Number of processes = 16
2964         Requested Load (NFS V3 operations/second) = 1300
2965         Maximum number of outstanding biod writes = 8
2966         Maximum number of outstanding biod reads = 8
2967         Warm-up time (seconds) = 300
2968         Run time (seconds) = 300
2969         File Set = 300208 Files created for I/O operations
2970                    90048 Files accessed for I/O operations
2971                    6016 Files for non-I/O operations
2972                      32 Symlinks
2973                    10000 Directories
2974                         Additional non-I/O files created as necessary
2975
2976
2977 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2978 Mon Apr 11 12:28:30 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2979 Mon Apr 11 12:28:30 2011 Completed.
2980 Mon Apr 11 12:28:30 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2981
2982 ************************************************************************
2983 Mon Apr 11 12:28:31 2011 Received.
2984 Mon Apr 11 12:28:31 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2985 Mon Apr 11 12:28:31 2011 Completed.
2986 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1400  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2987 NFS V3 Protocol Version
2988
2989 ************************************************************************
2990 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2991 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2992
2993 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2994 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2995
2996 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 12:28:33 2011
2997         Client hostname = c09-44
2998         Prime Client hostname = c09-44.sysnet.ucsd.edu
2999
3000 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3001 NFS V3 Protocol
3002 Mon Apr 11 12:28:33 2011 Mounting 16 remote test directories.
3003 Child[000]: NFS negotiated block size 32768
3004 Child[001]: NFS negotiated block size 32768
3005 Child[002]: NFS negotiated block size 32768
3006 Child[003]: NFS negotiated block size 32768
3007 Child[004]: NFS negotiated block size 32768
3008 Child[005]: NFS negotiated block size 32768
3009 Child[006]: NFS negotiated block size 32768
3010 Child[007]: NFS negotiated block size 32768
3011 Child[008]: NFS negotiated block size 32768
3012 Child[009]: NFS negotiated block size 32768
3013 Child[010]: NFS negotiated block size 32768
3014 Child[011]: NFS negotiated block size 32768
3015 Child[012]: NFS negotiated block size 32768
3016 Child[013]: NFS negotiated block size 32768
3017 Child[014]: NFS negotiated block size 32768
3018 Child[015]: NFS negotiated block size 32768
3019 Mon Apr 11 12:28:47 2011 Completed.
3020 Mon Apr 11 12:28:47 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
3021 Mon Apr 11 12:28:47 2011 Completed.
3022 Mon Apr 11 12:28:47 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
3023 Mon Apr 11 12:28:49 2011 Received.
3024
3025 Mon Apr 11 12:28:49 2011 Initializing test directories.
3026 Mon Apr 11 12:28:49 2011 Child 12 will create 675 directories.
3027 Mon Apr 11 12:28:49 2011 Child 4 will create 675 directories.
3028 Mon Apr 11 12:28:49 2011 Child 15 will create 675 directories.
3029 Mon Apr 11 12:28:49 2011 Child 1 will create 675 directories.
3030 Mon Apr 11 12:28:49 2011 Child 0 will create 675 directories.
3031 Mon Apr 11 12:28:49 2011 Child 14 will create 675 directories.
3032 Mon Apr 11 12:28:49 2011 Child 11 will create 675 directories.
3033 Mon Apr 11 12:28:49 2011 Child 3 will create 675 directories.
3034 Mon Apr 11 12:28:49 2011 Child 2 will create 675 directories.
3035 Mon Apr 11 12:28:49 2011 Child 13 will create 675 directories.
3036 Mon Apr 11 12:28:49 2011 Child 8 will create 675 directories.
3037 Mon Apr 11 12:28:49 2011 Child 5 will create 675 directories.
3038 Mon Apr 11 12:28:49 2011 Child 6 will create 675 directories.
3039 Mon Apr 11 12:28:49 2011 Child 10 will create 675 directories.
3040 Mon Apr 11 12:28:49 2011 Child 7 will create 675 directories.
3041 Mon Apr 11 12:28:49 2011 Child 9 will create 675 directories.
3042 Mon Apr 11 12:28:50 2011 Child 0 finished creating 675 directories.
3043 Mon Apr 11 12:28:50 2011 Child 0 will create 20649 files.
3044 Mon Apr 11 12:28:53 2011 Child 13 finished creating 675 directories.
3045 Mon Apr 11 12:28:53 2011 Child 13 will create 20649 files.
3046 Mon Apr 11 12:28:56 2011 Child 14 finished creating 675 directories.
3047 Mon Apr 11 12:28:56 2011 Child 14 will create 20649 files.
3048 Mon Apr 11 12:28:58 2011 Child 10 finished creating 675 directories.
3049 Mon Apr 11 12:28:58 2011 Child 10 will create 20649 files.
3050 Mon Apr 11 12:29:04 2011 Child 12 finished creating 675 directories.
3051 Mon Apr 11 12:29:04 2011 Child 12 will create 20649 files.
3052 Mon Apr 11 12:29:04 2011 Child 4 finished creating 675 directories.
3053 Mon Apr 11 12:29:04 2011 Child 4 will create 20649 files.
3054 Mon Apr 11 12:29:04 2011 Child 8 finished creating 675 directories.
3055 Mon Apr 11 12:29:04 2011 Child 8 will create 20649 files.
3056 Mon Apr 11 12:29:08 2011 Child 7 finished creating 675 directories.
3057 Mon Apr 11 12:29:08 2011 Child 7 will create 20649 files.
3058 Mon Apr 11 12:29:14 2011 Child 11 finished creating 675 directories.
3059 Mon Apr 11 12:29:14 2011 Child 11 will create 20649 files.
3060 Mon Apr 11 12:29:15 2011 Child 1 finished creating 675 directories.
3061 Mon Apr 11 12:29:15 2011 Child 1 will create 20649 files.
3062 Mon Apr 11 12:29:21 2011 Child 2 finished creating 675 directories.
3063 Mon Apr 11 12:29:21 2011 Child 2 will create 20649 files.
3064 Mon Apr 11 12:29:24 2011 Child 15 finished creating 675 directories.
3065 Mon Apr 11 12:29:24 2011 Child 15 will create 20649 files.
3066 Mon Apr 11 12:29:49 2011 Child 9 finished creating 675 directories.
3067 Mon Apr 11 12:29:49 2011 Child 9 will create 20649 files.
3068 Mon Apr 11 12:29:50 2011 Child 3 finished creating 675 directories.
3069 Mon Apr 11 12:29:50 2011 Child 3 will create 20649 files.
3070 Mon Apr 11 12:29:50 2011 Child 5 finished creating 675 directories.
3071 Mon Apr 11 12:29:50 2011 Child 5 will create 20649 files.
3072 Mon Apr 11 12:29:56 2011 Child 6 finished creating 675 directories.
3073 Mon Apr 11 12:29:56 2011 Child 6 will create 20649 files.
3074 Mon Apr 11 12:31:55 2011 Child 0 verified 20649 files, created 1684
3075 Mon Apr 11 12:31:55 2011 Child 0 will create 2 symlinks.
3076 Mon Apr 11 12:31:55 2011 Child 0 finished creating 2 symlinks.
3077 Mon Apr 11 12:32:47 2011 Child 13 verified 20649 files, created 1684
3078 Mon Apr 11 12:32:47 2011 Child 13 will create 2 symlinks.
3079 Mon Apr 11 12:32:47 2011 Child 13 finished creating 2 symlinks.
3080 Mon Apr 11 12:34:06 2011 Child 8 verified 19944 files (1181 creates).
3081 Mon Apr 11 12:34:07 2011 Child 4 verified 19943 files (1180 creates).
3082 Mon Apr 11 12:34:07 2011 Child 12 verified 20080 files (1317 creates).
3083 Mon Apr 11 12:34:10 2011 Child 7 verified 19964 files (1201 creates).
3084 Mon Apr 11 12:34:17 2011 Child 11 verified 19803 files (1040 creates).
3085 Mon Apr 11 12:34:17 2011 Child 1 verified 19824 files (1061 creates).
3086 Mon Apr 11 12:34:20 2011 Child 14 verified 20649 files, created 1684
3087 Mon Apr 11 12:34:20 2011 Child 14 will create 2 symlinks.
3088 Mon Apr 11 12:34:22 2011 Child 14 finished creating 2 symlinks.
3089 Mon Apr 11 12:34:24 2011 Child 2 verified 19631 files (868 creates).
3090 Mon Apr 11 12:34:26 2011 Child 15 verified 19592 files (829 creates).
3091 Mon Apr 11 12:34:41 2011 Child 10 verified 20649 files, created 1684
3092 Mon Apr 11 12:34:41 2011 Child 10 will create 2 symlinks.
3093 Mon Apr 11 12:34:41 2011 Child 10 finished creating 2 symlinks.
3094 Mon Apr 11 12:34:51 2011 Child 9 verified 19471 files (708 creates).
3095 Mon Apr 11 12:34:51 2011 Child 3 verified 19499 files (736 creates).
3096 Mon Apr 11 12:34:51 2011 Child 5 verified 19450 files (687 creates).
3097 Mon Apr 11 12:34:57 2011 Child 6 verified 19540 files (777 creates).
3098 Mon Apr 11 12:35:07 2011 Child 12 verified 20649 files, created 1684
3099 Mon Apr 11 12:35:07 2011 Child 12 will create 2 symlinks.
3100 Mon Apr 11 12:35:16 2011 Child 12 finished creating 2 symlinks.
3101 Mon Apr 11 12:35:23 2011 Child 4 verified 20649 files, created 1684
3102 Mon Apr 11 12:35:23 2011 Child 4 will create 2 symlinks.
3103 Mon Apr 11 12:35:23 2011 Child 7 verified 20649 files, created 1684
3104 Mon Apr 11 12:35:23 2011 Child 7 will create 2 symlinks.
3105 Mon Apr 11 12:35:23 2011 Child 7 finished creating 2 symlinks.
3106 Mon Apr 11 12:35:23 2011 Child 4 finished creating 2 symlinks.
3107 Mon Apr 11 12:35:33 2011 Child 11 verified 20649 files, created 1684
3108 Mon Apr 11 12:35:33 2011 Child 11 will create 2 symlinks.
3109 Mon Apr 11 12:35:33 2011 Child 11 finished creating 2 symlinks.
3110 Mon Apr 11 12:35:33 2011 Child 1 verified 20649 files, created 1684
3111 Mon Apr 11 12:35:33 2011 Child 1 will create 2 symlinks.
3112 Mon Apr 11 12:35:33 2011 Child 1 finished creating 2 symlinks.
3113 Mon Apr 11 12:35:36 2011 Child 8 verified 20649 files, created 1684
3114 Mon Apr 11 12:35:36 2011 Child 8 will create 2 symlinks.
3115 Mon Apr 11 12:35:36 2011 Child 8 finished creating 2 symlinks.
3116 Mon Apr 11 12:35:51 2011 Child 2 verified 20649 files, created 1684
3117 Mon Apr 11 12:35:51 2011 Child 2 will create 2 symlinks.
3118 Mon Apr 11 12:35:51 2011 Child 2 finished creating 2 symlinks.
3119 Mon Apr 11 12:35:54 2011 Child 15 verified 20649 files, created 1684
3120 Mon Apr 11 12:35:54 2011 Child 15 will create 2 symlinks.
3121 Mon Apr 11 12:35:54 2011 Child 15 finished creating 2 symlinks.
3122 Mon Apr 11 12:36:00 2011 Child 3 verified 20649 files, created 1684
3123 Mon Apr 11 12:36:00 2011 Child 3 will create 2 symlinks.
3124 Mon Apr 11 12:36:00 2011 Child 3 finished creating 2 symlinks.
3125 Mon Apr 11 12:36:00 2011 Child 6 verified 20649 files, created 1684
3126 Mon Apr 11 12:36:00 2011 Child 6 will create 2 symlinks.
3127 Mon Apr 11 12:36:00 2011 Child 6 finished creating 2 symlinks.
3128 Mon Apr 11 12:36:01 2011 Child 5 verified 20649 files, created 1684
3129 Mon Apr 11 12:36:01 2011 Child 5 will create 2 symlinks.
3130 Mon Apr 11 12:36:01 2011 Child 5 finished creating 2 symlinks.
3131 Mon Apr 11 12:36:01 2011 Child 9 verified 20649 files, created 1684
3132 Mon Apr 11 12:36:01 2011 Child 9 will create 2 symlinks.
3133 Mon Apr 11 12:36:01 2011 Child 9 finished creating 2 symlinks.
3134 Mon Apr 11 12:36:02 2011 Completed.
3135 Mon Apr 11 12:36:02 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
3136 Mon Apr 11 12:36:02 2011 Completed.
3137 Mon Apr 11 12:36:02 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
3138 Mon Apr 11 12:36:03 2011 Received.
3139 Mon Apr 11 12:36:03 2011 Performing 300 seconds pretest warmup.
3140 Mon Apr 11 12:41:03 2011 Completed.
3141 Mon Apr 11 12:41:03 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
3142 Mon Apr 11 12:41:03 2011 Completed.
3143 Mon Apr 11 12:41:03 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
3144 Mon Apr 11 12:41:04 2011 Received.
3145 Mon Apr 11 12:41:04 2011 Starting 300 seconds test run.
3146 SFS Single Client (c09-44) Results, Mon Apr 11 12:46:15 2011
3147 --------------------------------------------------------------------------------------
3148 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
3149 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
3150 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
3151            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
3152 --------------------------------------------------------------------------------------
3153 getattr     26.0%    25.9%    33468    33468      0      7.43    36.58      0.06  4.2%
3154 setattr      4.0%     4.0%     5169     5169      0    121.80   219.30      0.40 10.7%
3155 lookup      24.0%    24.2%    31330    31330      0      7.31    27.01      0.06  3.9%
3156 readlink     1.0%     1.0%     1243     1243      0      6.10    15.29      0.22  0.1%
3157 read        18.0%    17.9%    23220    28594      0    107.66   143.97      0.15 42.5%
3158 write       10.0%     9.9%    12792    15422      0     84.29    95.88      0.17 18.3%
3159 create       1.0%     1.0%     1330     1330      0    233.00   291.34      0.92  5.3%
3160 remove       1.0%     0.9%     1197     1197      0    134.06   198.52      0.80  2.7%
3161 readdir      1.0%     1.0%     1263     1263      0      7.12    17.48      0.23  0.2%
3162 fsstat       1.0%     1.0%     1347     1347      0      6.67    27.24      0.28  0.2%
3163 access      11.0%    11.2%    14444    14444      0      6.66    30.61      0.09  1.6%
3164 commit       0.0%     0.0%        0     4529      0      0.00     0.00      0.00 10.0%
3165 readdirplus  2.0%     2.0%     2580     2580      0      8.29    22.13      0.18  0.4%
3166 --------------------------------------------------------------------------------------
3167
3168       --------------------------------------------------
3169       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
3170       --------------------------+-----------------------
3171 SFS NFS V3 THROUGHPUT:  431.27 Ops/Sec   AVG. RESPONSE TIME:   45.50 Msec/Op
3172 TCP PROTOCOL
3173 FAST CALLS: 0
3174 NFS V3 MIXFILE: [ SFS Default ]
3175 CLIENT REQUESTED LOAD: 1400 Ops/Sec
3176 TOTAL LOGICAL NFS V3 OPERATIONS:  129383         TEST TIME: 300 Sec
3177 TOTAL PHYSICAL NFS V3 OPERATIONS: 141916
3178 PHYSICAL NFS V3 THROUGHPUT:  473.05 Ops/Sec
3179 FILE SET SIZE CREATED: 171993024.0 KB
3180 FILE SET SIZE ACCESSED: 51604704.0 - 51842800.0 KB  (100.00% to 100.46% of Base)
3181
3182 ------------------------------------------------------------------------
3183
3184 Mon Apr 11 12:46:15 2011 Completed.
3185
3186 Client Test Parameters:
3187         Number of processes = 16
3188         Requested Load (NFS V3 operations/second) = 1400
3189         Maximum number of outstanding biod writes = 8
3190         Maximum number of outstanding biod reads = 8
3191         Warm-up time (seconds) = 300
3192         Run time (seconds) = 300
3193         File Set = 323904 Files created for I/O operations
3194                    97168 Files accessed for I/O operations
3195                    6480 Files for non-I/O operations
3196                      32 Symlinks
3197                    10784 Directories
3198                         Additional non-I/O files created as necessary
3199
3200
3201 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
3202 Mon Apr 11 12:46:15 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
3203 Mon Apr 11 12:46:15 2011 Completed.
3204 Mon Apr 11 12:46:15 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
3205
3206 ************************************************************************
3207 Mon Apr 11 12:46:16 2011 Received.
3208 Mon Apr 11 12:46:16 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
3209 Mon Apr 11 12:46:16 2011 Completed.
3210 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1500  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
3211 NFS V3 Protocol Version
3212
3213 ************************************************************************
3214 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
3215 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
3216
3217 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
3218 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3219
3220 SFS NFS V3 Benchmark Client Logfile, Mon Apr 11 12:46:18 2011
3221         Client hostname = c09-44
3222         Prime Client hostname = c09-44.sysnet.ucsd.edu
3223
3224 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3225 NFS V3 Protocol
3226 Mon Apr 11 12:46:18 2011 Mounting 16 remote test directories.
3227 Child[000]: NFS negotiated block size 32768
3228 Child[001]: NFS negotiated block size 32768
3229 Child[002]: NFS negotiated block size 32768
3230 Child[003]: NFS negotiated block size 32768
3231 Child[004]: NFS negotiated block size 32768
3232 Child[005]: NFS negotiated block size 32768
3233 Child[006]: NFS negotiated block size 32768
3234 Child[007]: NFS negotiated block size 32768
3235 Child[008]: NFS negotiated block size 32768
3236 Child[009]: NFS negotiated block size 32768
3237 Child[010]: NFS negotiated block size 32768
3238 Child[011]: NFS negotiated block size 32768
3239 Child[012]: NFS negotiated block size 32768
3240 Child[013]: NFS negotiated block size 32768
3241 Child[014]: NFS negotiated block size 32768
3242 Child[015]: NFS negotiated block size 32768
3243 Mon Apr 11 12:46:32 2011 Completed.
3244 Mon Apr 11 12:46:32 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
3245 Mon Apr 11 12:46:32 2011 Completed.
3246 Mon Apr 11 12:46:32 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
3247 Mon Apr 11 12:46:34 2011 Received.
3248
3249 Mon Apr 11 12:46:34 2011 Initializing test directories.
3250 Mon Apr 11 12:46:34 2011 Child 15 will create 721 directories.
3251 Mon Apr 11 12:46:34 2011 Child 13 will create 721 directories.
3252 Mon Apr 11 12:46:34 2011 Child 0 will create 721 directories.
3253 Mon Apr 11 12:46:34 2011 Child 14 will create 721 directories.
3254 Mon Apr 11 12:46:34 2011 Child 2 will create 721 directories.
3255 Mon Apr 11 12:46:34 2011 Child 1 will create 721 directories.
3256 Mon Apr 11 12:46:34 2011 Child 12 will create 721 directories.
3257 Mon Apr 11 12:46:34 2011 Child 10 will create 721 directories.
3258 Mon Apr 11 12:46:34 2011 Child 3 will create 721 directories.
3259 Mon Apr 11 12:46:34 2011 Child 9 will create 721 directories.
3260 Mon Apr 11 12:46:34 2011 Child 11 will create 721 directories.
3261 Mon Apr 11 12:46:34 2011 Child 4 will create 721 directories.
3262 Mon Apr 11 12:46:34 2011 Child 6 will create 721 directories.
3263 Mon Apr 11 12:46:34 2011 Child 8 will create 721 directories.
3264 Mon Apr 11 12:46:34 2011 Child 5 will create 721 directories.
3265 Mon Apr 11 12:46:34 2011 Child 7 will create 721 directories.
3266 Mon Apr 11 12:46:34 2011 Child 9 finished creating 721 directories.
3267 Mon Apr 11 12:46:34 2011 Child 9 will create 22035 files.
3268 Mon Apr 11 12:46:53 2011 Child 11 finished creating 721 directories.
3269 Mon Apr 11 12:46:53 2011 Child 11 will create 22035 files.
3270 Mon Apr 11 12:46:53 2011 Child 5 finished creating 721 directories.
3271 Mon Apr 11 12:46:53 2011 Child 5 will create 22035 files.
3272 Mon Apr 11 12:46:53 2011 Child 15 finished creating 721 directories.
3273 Mon Apr 11 12:46:53 2011 Child 15 will create 22035 files.
3274 Mon Apr 11 12:46:54 2011 Child 1 finished creating 721 directories.
3275 Mon Apr 11 12:46:54 2011 Child 1 will create 22035 files.
3276 Mon Apr 11 12:46:59 2011 Child 3 finished creating 721 directories.
3277 Mon Apr 11 12:46:59 2011 Child 3 will create 22035 files.
3278 Mon Apr 11 12:47:01 2011 Child 8 finished creating 721 directories.
3279 Mon Apr 11 12:47:01 2011 Child 8 will create 22035 files.
3280 Mon Apr 11 12:47:03 2011 Child 0 finished creating 721 directories.
3281 Mon Apr 11 12:47:03 2011 Child 0 will create 22035 files.
3282 Mon Apr 11 12:47:04 2011 Child 4 finished creating 721 directories.
3283 Mon Apr 11 12:47:04 2011 Child 4 will create 22035 files.
3284 Mon Apr 11 12:47:08 2011 Child 7 finished creating 721 directories.
3285 Mon Apr 11 12:47:08 2011 Child 7 will create 22035 files.
3286 Mon Apr 11 12:47:08 2011 Child 10 finished creating 721 directories.
3287 Mon Apr 11 12:47:08 2011 Child 10 will create 22035 files.
3288 Mon Apr 11 12:47:13 2011 Child 6 finished creating 721 directories.
3289 Mon Apr 11 12:47:13 2011 Child 6 will create 22035 files.
3290 Mon Apr 11 12:47:18 2011 Child 13 finished creating 721 directories.
3291 Mon Apr 11 12:47:18 2011 Child 13 will create 22035 files.
3292 Mon Apr 11 12:47:32 2011 Child 14 finished creating 721 directories.
3293 Mon Apr 11 12:47:32 2011 Child 14 will create 22035 files.
3294 Mon Apr 11 12:47:43 2011 Child 12 finished creating 721 directories.
3295 Mon Apr 11 12:47:43 2011 Child 12 will create 22035 files.
3296 Mon Apr 11 12:47:45 2011 Child 2 finished creating 721 directories.
3297 Mon Apr 11 12:47:45 2011 Child 2 will create 22035 files.
3298 Mon Apr 11 12:49:01 2011 Child 9 verified 22035 files, created 1575
3299 Mon Apr 11 12:49:01 2011 Child 9 will create 2 symlinks.
3300 Mon Apr 11 12:49:01 2011 Child 9 finished creating 2 symlinks.
3301 Mon Apr 11 12:57:23 2011 Child 12 verified 20434 files (190 creates).
3302 Mon Apr 11 12:57:23 2011 Child 3 verified 20883 files (639 creates).
3303 Mon Apr 11 12:57:23 2011 Child 13 verified 20672 files (428 creates).
3304 Mon Apr 11 12:57:23 2011 Child 2 verified 20440 files (196 creates).
3305 Mon Apr 11 12:57:23 2011 Child 8 verified 20788 files (544 creates).
3306 Mon Apr 11 12:57:23 2011 Child 14 verified 20456 files (212 creates).
3307 Mon Apr 11 12:57:26 2011 Child 7 verified 20750 files (506 creates).
3308 Mon Apr 11 12:57:26 2011 Child 11 verified 20898 files (654 creates).
3309 Mon Apr 11 12:57:26 2011 Child 6 verified 20642 files (398 creates).
3310 Mon Apr 11 12:57:26 2011 Child 5 verified 20916 files (672 creates).
3311 Mon Apr 11 12:57:27 2011 Child 4 verified 20750 files (506 creates).
3312 Mon Apr 11 12:57:47 2011 Child 1 verified 20916 files (672 creates).
3313 Mon Apr 11 12:57:47 2011 Child 0 verified 20750 files (506 creates).
3314 Mon Apr 11 12:57:47 2011 Child 15 verified 20898 files (654 creates).
3315 Mon Apr 11 12:58:11 2011 Child 10 verified 20750 files (506 creates).
3316 Mon Apr 11 13:01:21 2011 Child 5 verified 22035 files, created 1575
3317 Mon Apr 11 13:01:21 2011 Child 5 will create 2 symlinks.
3318 Mon Apr 11 13:01:21 2011 Child 5 finished creating 2 symlinks.
3319 Mon Apr 11 13:01:38 2011 Child 1 verified 22035 files, created 1575
3320 Mon Apr 11 13:01:38 2011 Child 1 will create 2 symlinks.
3321 Mon Apr 11 13:01:38 2011 Child 1 finished creating 2 symlinks.
3322 Mon Apr 11 13:01:39 2011 Child 11 verified 22035 files, created 1575
3323 Mon Apr 11 13:01:39 2011 Child 11 will create 2 symlinks.
3324 Mon Apr 11 13:01:39 2011 Child 11 finished creating 2 symlinks.
3325 Mon Apr 11 13:01:47 2011 Child 3 verified 22035 files, created 1575
3326 Mon Apr 11 13:01:47 2011 Child 3 will create 2 symlinks.
3327 Mon Apr 11 13:01:47 2011 Child 3 finished creating 2 symlinks.
3328 Mon Apr 11 13:01:49 2011 Child 8 verified 22035 files, created 1575
3329 Mon Apr 11 13:01:49 2011 Child 8 will create 2 symlinks.
3330 Mon Apr 11 13:01:49 2011 Child 8 finished creating 2 symlinks.
3331 Mon Apr 11 13:01:50 2011 Child 15 verified 22035 files, created 1575
3332 Mon Apr 11 13:01:50 2011 Child 15 will create 2 symlinks.
3333 Mon Apr 11 13:01:50 2011 Child 15 finished creating 2 symlinks.
3334 Mon Apr 11 13:01:50 2011 Child 4 verified 22035 files, created 1575
3335 Mon Apr 11 13:01:50 2011 Child 4 will create 2 symlinks.
3336 Mon Apr 11 13:01:50 2011 Child 7 verified 22035 files, created 1575
3337 Mon Apr 11 13:01:50 2011 Child 7 will create 2 symlinks.
3338 Mon Apr 11 13:01:50 2011 Child 4 finished creating 2 symlinks.
3339 Mon Apr 11 13:01:50 2011 Child 7 finished creating 2 symlinks.
3340 Mon Apr 11 13:02:00 2011 Child 13 verified 22035 files, created 1575
3341 Mon Apr 11 13:02:00 2011 Child 13 will create 2 symlinks.
3342 Mon Apr 11 13:02:00 2011 Child 13 finished creating 2 symlinks.
3343 Mon Apr 11 13:02:08 2011 Child 6 verified 22035 files, created 1575
3344 Mon Apr 11 13:02:08 2011 Child 6 will create 2 symlinks.
3345 Mon Apr 11 13:02:08 2011 Child 6 finished creating 2 symlinks.
3346 Mon Apr 11 13:02:09 2011 Child 10 verified 22035 files, created 1575
3347 Mon Apr 11 13:02:09 2011 Child 10 will create 2 symlinks.
3348 Mon Apr 11 13:02:09 2011 Child 10 finished creating 2 symlinks.
3349 Mon Apr 11 13:02:11 2011 Child 0 verified 22035 files, created 1575
3350 Mon Apr 11 13:02:11 2011 Child 0 will create 2 symlinks.
3351 Mon Apr 11 13:02:11 2011 Child 0 finished creating 2 symlinks.
3352 Mon Apr 11 13:02:18 2011 Child 14 verified 22035 files, created 1575
3353 Mon Apr 11 13:02:18 2011 Child 14 will create 2 symlinks.
3354 Mon Apr 11 13:02:18 2011 Child 14 finished creating 2 symlinks.
3355 Mon Apr 11 13:02:19 2011 Child 2 verified 22035 files, created 1575
3356 Mon Apr 11 13:02:19 2011 Child 2 will create 2 symlinks.
3357 Mon Apr 11 13:02:19 2011 Child 2 finished creating 2 symlinks.
3358 Mon Apr 11 13:02:19 2011 Child 12 verified 22035 files, created 1575
3359 Mon Apr 11 13:02:19 2011 Child 12 will create 2 symlinks.
3360 Mon Apr 11 13:02:20 2011 Child 12 finished creating 2 symlinks.
3361 Mon Apr 11 13:02:20 2011 Completed.
3362 Mon Apr 11 13:02:20 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
3363 Mon Apr 11 13:02:20 2011 Completed.
3364 Mon Apr 11 13:02:20 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
3365 Mon Apr 11 13:02:20 2011 Received.
3366 Mon Apr 11 13:02:20 2011 Performing 300 seconds pretest warmup.
3367 Mon Apr 11 13:07:20 2011 Completed.
3368 Mon Apr 11 13:07:20 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
3369 Mon Apr 11 13:07:20 2011 Completed.
3370 Mon Apr 11 13:07:20 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
3371 Mon Apr 11 13:07:21 2011 Received.
3372 Mon Apr 11 13:07:21 2011 Starting 300 seconds test run.
3373 SFS Single Client (c09-44) Results, Mon Apr 11 13:12:31 2011
3374 --------------------------------------------------------------------------------------
3375 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
3376 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
3377 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
3378            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
3379 --------------------------------------------------------------------------------------
3380 getattr     26.0%    25.9%    28500    28500      0      7.92   103.07      0.12  3.9%
3381 setattr      4.0%     4.1%     4468     4468      0    140.74   250.55      0.46 10.7%
3382 lookup      24.0%    24.1%    26494    26494      0      7.57    77.28      0.11  3.4%
3383 readlink     1.0%     1.0%     1092     1092      0      6.72    16.68      0.24  0.1%
3384 read        18.0%    18.1%    19926    24321      0    127.03   304.71      0.24 43.2%
3385 write       10.0%     9.8%    10792    13041      0    100.86   378.27      0.37 18.6%
3386 create       1.0%     1.0%     1108     1108      0    271.43   324.41      1.06  5.1%
3387 remove       1.0%     1.0%     1063     1063      0    179.75   290.24      1.02  3.3%
3388 readdir      1.0%     1.0%     1053     1053      0      7.01    17.91      0.26  0.1%
3389 fsstat       1.0%     1.0%     1118     1118      0      6.93    20.35      0.26  0.1%
3390 access      11.0%    11.1%    12162    12162      0      7.38    25.95      0.09  1.5%
3391 commit       0.0%     0.0%        0     3772      0      0.00     0.00      0.00  9.6%
3392 readdirplus  2.0%     2.0%     2187     2187      0      7.21    16.46      0.17  0.3%
3393 --------------------------------------------------------------------------------------
3394
3395       --------------------------------------------------
3396       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
3397       --------------------------+-----------------------
3398 SFS NFS V3 THROUGHPUT:  367.76 Ops/Sec   AVG. RESPONSE TIME:   53.23 Msec/Op
3399 TCP PROTOCOL
3400 FAST CALLS: 0
3401 NFS V3 MIXFILE: [ SFS Default ]
3402 CLIENT REQUESTED LOAD: 1500 Ops/Sec
3403 TOTAL LOGICAL NFS V3 OPERATIONS:  109963         TEST TIME: 299 Sec
3404 TOTAL PHYSICAL NFS V3 OPERATIONS: 120379
3405 PHYSICAL NFS V3 THROUGHPUT:  402.60 Ops/Sec
3406 FILE SET SIZE CREATED: 183530592.0 KB
3407 FILE SET SIZE ACCESSED: 55062576.0 - 55279784.0 KB  (100.00% to 100.39% of Base)
3408
3409 ------------------------------------------------------------------------
3410
3411 Mon Apr 11 13:12:31 2011 Completed.
3412
3413 Client Test Parameters:
3414         Number of processes = 16
3415         Requested Load (NFS V3 operations/second) = 1500
3416         Maximum number of outstanding biod writes = 8
3417         Maximum number of outstanding biod reads = 8
3418         Warm-up time (seconds) = 300
3419         Run time (seconds) = 300
3420         File Set = 345632 Files created for I/O operations
3421                    103680 Files accessed for I/O operations
3422                    6928 Files for non-I/O operations
3423                      32 Symlinks
3424                    11520 Directories
3425                         Additional non-I/O files created as necessary
3426
3427
3428 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
3429 Mon Apr 11 13:12:31 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
3430 Mon Apr 11 13:12:31 2011 Completed.
3431 Mon Apr 11 13:12:31 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
3432
3433 ************************************************************************
3434 Mon Apr 11 13:12:32 2011 Received.
3435 Mon Apr 11 13:12:32 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
3436 Mon Apr 11 13:12:32 2011 Completed.