results/20110409/sfsc001.20110430-s3-west-hi16-nosegments

   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, Sat Apr 30 18:58:07 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 Sat Apr 30 18:58:07 2011 Mounting 16 remote test directories.
  18 Child[005]: NFS negotiated block size 32768
  19 Child[006]: NFS negotiated block size 32768
  20 Child[009]: NFS negotiated block size 32768
  21 Child[015]: NFS negotiated block size 32768
  22 Child[000]: NFS negotiated block size 32768
  23 Child[001]: NFS negotiated block size 32768
  24 Child[002]: NFS negotiated block size 32768
  25 Child[003]: NFS negotiated block size 32768
  26 Child[004]: NFS negotiated block size 32768
  27 Child[007]: NFS negotiated block size 32768
  28 Child[008]: NFS negotiated block size 32768
  29 Child[010]: NFS negotiated block size 32768
  30 Child[011]: NFS negotiated block size 32768
  31 Child[012]: NFS negotiated block size 32768
  32 Child[013]: NFS negotiated block size 32768
  33 Child[014]: NFS negotiated block size 32768
  34 Sat Apr 30 18:58:25 2011 Completed.
  35 Sat Apr 30 18:58:25 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
  36 Sat Apr 30 18:58:25 2011 Completed.
  37 Sat Apr 30 18:58:25 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
  38 Sat Apr 30 18:58:25 2011 Received.
  39
  40 Sat Apr 30 18:58:25 2011 Initializing test directories.
  41 Sat Apr 30 18:58:25 2011 Child 15 will create 46 directories.
  42 Sat Apr 30 18:58:25 2011 Child 6 will create 46 directories.
  43 Sat Apr 30 18:58:25 2011 Child 5 will create 46 directories.
  44 Sat Apr 30 18:58:25 2011 Child 2 will create 46 directories.
  45 Sat Apr 30 18:58:25 2011 Child 10 will create 46 directories.
  46 Sat Apr 30 18:58:25 2011 Child 9 will create 46 directories.
  47 Sat Apr 30 18:58:25 2011 Child 14 will create 46 directories.
  48 Sat Apr 30 18:58:25 2011 Child 12 will create 46 directories.
  49 Sat Apr 30 18:58:25 2011 Child 8 will create 46 directories.
  50 Sat Apr 30 18:58:25 2011 Child 0 will create 46 directories.
  51 Sat Apr 30 18:58:25 2011 Child 7 will create 46 directories.
  52 Sat Apr 30 18:58:25 2011 Child 3 will create 46 directories.
  53 Sat Apr 30 18:58:25 2011 Child 1 will create 46 directories.
  54 Sat Apr 30 18:58:25 2011 Child 13 will create 46 directories.
  55 Sat Apr 30 18:58:25 2011 Child 11 will create 46 directories.
  56 Sat Apr 30 18:58:25 2011 Child 4 will create 46 directories.
  57 Sat Apr 30 18:58:25 2011 Child 13 finished creating 46 directories.
  58 Sat Apr 30 18:58:25 2011 Child 13 will create 1386 files.
  59 Sat Apr 30 18:58:25 2011 Child 8 finished creating 46 directories.
  60 Sat Apr 30 18:58:25 2011 Child 8 will create 1386 files.
  61 Sat Apr 30 18:58:25 2011 Child 9 finished creating 46 directories.
  62 Sat Apr 30 18:58:25 2011 Child 9 will create 1386 files.
  63 Sat Apr 30 18:58:25 2011 Child 12 finished creating 46 directories.
  64 Sat Apr 30 18:58:25 2011 Child 12 will create 1386 files.
  65 Sat Apr 30 18:58:25 2011 Child 11 finished creating 46 directories.
  66 Sat Apr 30 18:58:25 2011 Child 11 will create 1386 files.
  67 Sat Apr 30 18:58:25 2011 Child 6 finished creating 46 directories.
  68 Sat Apr 30 18:58:25 2011 Child 6 will create 1386 files.
  69 Sat Apr 30 18:58:25 2011 Child 4 finished creating 46 directories.
  70 Sat Apr 30 18:58:25 2011 Child 4 will create 1386 files.
  71 Sat Apr 30 18:58:25 2011 Child 5 finished creating 46 directories.
  72 Sat Apr 30 18:58:25 2011 Child 5 will create 1386 files.
  73 Sat Apr 30 18:58:25 2011 Child 1 finished creating 46 directories.
  74 Sat Apr 30 18:58:25 2011 Child 1 will create 1386 files.
  75 Sat Apr 30 18:58:25 2011 Child 3 finished creating 46 directories.
  76 Sat Apr 30 18:58:25 2011 Child 3 will create 1386 files.
  77 Sat Apr 30 18:58:25 2011 Child 10 finished creating 46 directories.
  78 Sat Apr 30 18:58:25 2011 Child 10 will create 1386 files.
  79 Sat Apr 30 18:58:25 2011 Child 0 finished creating 46 directories.
  80 Sat Apr 30 18:58:25 2011 Child 0 will create 1386 files.
  81 Sat Apr 30 18:58:25 2011 Child 2 finished creating 46 directories.
  82 Sat Apr 30 18:58:25 2011 Child 2 will create 1386 files.
  83 Sat Apr 30 18:58:25 2011 Child 7 finished creating 46 directories.
  84 Sat Apr 30 18:58:25 2011 Child 7 will create 1386 files.
  85 Sat Apr 30 18:58:25 2011 Child 15 finished creating 46 directories.
  86 Sat Apr 30 18:58:25 2011 Child 15 will create 1386 files.
  87 Sat Apr 30 18:58:25 2011 Child 14 finished creating 46 directories.
  88 Sat Apr 30 18:58:25 2011 Child 14 will create 1386 files.
  89 Sat Apr 30 19:06:13 2011 Child 10 verified 944 files (944 creates).
  90 Sat Apr 30 19:06:13 2011 Child 1 verified 899 files (899 creates).
  91 Sat Apr 30 19:06:14 2011 Child 15 verified 894 files (894 creates).
  92 Sat Apr 30 19:06:14 2011 Child 8 verified 1005 files (1005 creates).
  93 Sat Apr 30 19:06:23 2011 Child 14 verified 996 files (996 creates).
  94 Sat Apr 30 19:06:23 2011 Child 0 verified 872 files (872 creates).
  95 Sat Apr 30 19:06:27 2011 Child 4 verified 903 files (903 creates).
  96 Sat Apr 30 19:06:27 2011 Child 2 verified 996 files (996 creates).
  97 Sat Apr 30 19:06:27 2011 Child 9 verified 996 files (996 creates).
  98 Sat Apr 30 19:06:27 2011 Child 6 verified 996 files (996 creates).
  99 Sat Apr 30 19:06:27 2011 Child 11 verified 996 files (996 creates).
 100 Sat Apr 30 19:06:27 2011 Child 7 verified 996 files (996 creates).
 101 Sat Apr 30 19:06:27 2011 Child 13 verified 996 files (996 creates).
 102 Sat Apr 30 19:06:27 2011 Child 3 verified 996 files (996 creates).
 103 Sat Apr 30 19:07:28 2011 Child 12 verified 1118 files (1118 creates).
 104 Sat Apr 30 19:07:35 2011 Child 5 verified 1118 files (1118 creates).
 105 Sat Apr 30 19:08:45 2011 Child 12 verified 1386 files, created 1373
 106 Sat Apr 30 19:08:45 2011 Child 12 will create 2 symlinks.
 107 Sat Apr 30 19:08:45 2011 Child 12 finished creating 2 symlinks.
 108 Sat Apr 30 19:08:45 2011 Child 7 verified 1386 files, created 1373
 109 Sat Apr 30 19:08:45 2011 Child 7 will create 2 symlinks.
 110 Sat Apr 30 19:08:45 2011 Child 7 finished creating 2 symlinks.
 111 Sat Apr 30 19:08:45 2011 Child 9 verified 1386 files, created 1373
 112 Sat Apr 30 19:08:45 2011 Child 9 will create 2 symlinks.
 113 Sat Apr 30 19:08:45 2011 Child 9 finished creating 2 symlinks.
 114 Sat Apr 30 19:08:45 2011 Child 5 verified 1386 files, created 1373
 115 Sat Apr 30 19:08:45 2011 Child 5 will create 2 symlinks.
 116 Sat Apr 30 19:08:46 2011 Child 5 finished creating 2 symlinks.
 117 Sat Apr 30 19:08:46 2011 Child 8 verified 1386 files, created 1373
 118 Sat Apr 30 19:08:46 2011 Child 8 will create 2 symlinks.
 119 Sat Apr 30 19:08:46 2011 Child 8 finished creating 2 symlinks.
 120 Sat Apr 30 19:08:49 2011 Child 2 verified 1386 files, created 1373
 121 Sat Apr 30 19:08:49 2011 Child 2 will create 2 symlinks.
 122 Sat Apr 30 19:08:49 2011 Child 2 finished creating 2 symlinks.
 123 Sat Apr 30 19:08:49 2011 Child 4 verified 1386 files, created 1373
 124 Sat Apr 30 19:08:49 2011 Child 4 will create 2 symlinks.
 125 Sat Apr 30 19:08:49 2011 Child 14 verified 1386 files, created 1373
 126 Sat Apr 30 19:08:49 2011 Child 14 will create 2 symlinks.
 127 Sat Apr 30 19:08:49 2011 Child 4 finished creating 2 symlinks.
 128 Sat Apr 30 19:08:49 2011 Child 14 finished creating 2 symlinks.
 129 Sat Apr 30 19:08:49 2011 Child 1 verified 1386 files, created 1373
 130 Sat Apr 30 19:08:49 2011 Child 1 will create 2 symlinks.
 131 Sat Apr 30 19:08:49 2011 Child 10 verified 1386 files, created 1373
 132 Sat Apr 30 19:08:49 2011 Child 10 will create 2 symlinks.
 133 Sat Apr 30 19:08:49 2011 Child 1 finished creating 2 symlinks.
 134 Sat Apr 30 19:08:49 2011 Child 10 finished creating 2 symlinks.
 135 Sat Apr 30 19:08:49 2011 Child 13 verified 1386 files, created 1373
 136 Sat Apr 30 19:08:49 2011 Child 13 will create 2 symlinks.
 137 Sat Apr 30 19:08:49 2011 Child 11 verified 1386 files, created 1373
 138 Sat Apr 30 19:08:49 2011 Child 11 will create 2 symlinks.
 139 Sat Apr 30 19:08:49 2011 Child 6 verified 1386 files, created 1373
 140 Sat Apr 30 19:08:49 2011 Child 6 will create 2 symlinks.
 141 Sat Apr 30 19:08:49 2011 Child 13 finished creating 2 symlinks.
 142 Sat Apr 30 19:08:49 2011 Child 11 finished creating 2 symlinks.
 143 Sat Apr 30 19:08:49 2011 Child 6 finished creating 2 symlinks.
 144 Sat Apr 30 19:08:49 2011 Child 3 verified 1386 files, created 1373
 145 Sat Apr 30 19:08:49 2011 Child 3 will create 2 symlinks.
 146 Sat Apr 30 19:08:49 2011 Child 3 finished creating 2 symlinks.
 147 Sat Apr 30 19:08:49 2011 Child 15 verified 1386 files, created 1373
 148 Sat Apr 30 19:08:49 2011 Child 15 will create 2 symlinks.
 149 Sat Apr 30 19:08:49 2011 Child 0 verified 1386 files, created 1373
 150 Sat Apr 30 19:08:49 2011 Child 0 will create 2 symlinks.
 151 Sat Apr 30 19:08:49 2011 Child 15 finished creating 2 symlinks.
 152 Sat Apr 30 19:08:49 2011 Child 0 finished creating 2 symlinks.
 153 Sat Apr 30 19:08:50 2011 Completed.
 154 Sat Apr 30 19:08:50 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 155 Sat Apr 30 19:08:50 2011 Completed.
 156 Sat Apr 30 19:08:50 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 157 Sat Apr 30 19:08:50 2011 Received.
 158 Sat Apr 30 19:08:50 2011 Performing 300 seconds pretest warmup.
 159 Sat Apr 30 19:13:50 2011 Completed.
 160 Sat Apr 30 19:13:50 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 161 Sat Apr 30 19:13:50 2011 Completed.
 162 Sat Apr 30 19:13:50 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 163 Sat Apr 30 19:13:51 2011 Received.
 164 Sat Apr 30 19:13:51 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, Sat Apr 30 19:19:01 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.02      0.00  5.0%
 175 setattr      4.0%     4.0%     1190     1190      0      0.97     1.10      0.06  3.9%
 176 lookup      24.0%    23.7%     7103     7103      0      0.19     0.02      0.00  4.7%
 177 readlink     1.0%     1.0%      302      302      0      0.19     0.06      0.03  0.2%
 178 read        18.0%    18.1%     5442     6647      0      1.48     4.08      0.05 27.6%
 179 write       10.0%    10.2%     3052     3705      0      3.88    13.85      0.13 40.7%
 180 create       1.0%     1.0%      302      302      0      1.93     0.93      0.11  2.0%
 181 remove       1.0%     1.0%      296      296      0      1.13     1.09      0.12  1.1%
 182 readdir      1.0%     1.0%      288      288      0      0.35     0.05      0.03  0.4%
 183 fsstat       1.0%     1.0%      292      292      0      0.18     0.01      0.01  0.2%
 184 access      11.0%    11.1%     3339     3339      0      0.19     0.03      0.01  2.2%
 185 commit       0.0%     0.0%        0     1342      0      0.00     0.00      0.00 10.8%
 186 readdirplus  2.0%     2.1%      629      629      0      0.56     0.06      0.02  1.2%
 187 --------------------------------------------------------------------------------------
 188
 189       --------------------------------------------------
 190       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 191       --------------------------+-----------------------
 192 SFS NFS V3 THROUGHPUT:   99.95 Ops/Sec   AVG. RESPONSE TIME:    0.97 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:  29987          TEST TIME: 300 Sec
 198 TOTAL PHYSICAL NFS V3 OPERATIONS: 33187
 199 PHYSICAL NFS V3 THROUGHPUT:  110.62 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 Sat Apr 30 19:19:01 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 Sat Apr 30 19:19:01 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 224 Sat Apr 30 19:19:01 2011 Completed.
 225 Sat Apr 30 19:19:01 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 226
 227 ************************************************************************
 228 Sat Apr 30 19:19:02 2011 Received.
 229 Sat Apr 30 19:19:02 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 230 Sat Apr 30 19:19:02 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, Sat Apr 30 19:19:04 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 Sat Apr 30 19:19:04 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 Sat Apr 30 19:19:18 2011 Completed.
 265 Sat Apr 30 19:19:18 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 266 Sat Apr 30 19:19:18 2011 Completed.
 267 Sat Apr 30 19:19:18 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 268 Sat Apr 30 19:19:20 2011 Received.
 269
 270 Sat Apr 30 19:19:20 2011 Initializing test directories.
 271 Sat Apr 30 19:19:20 2011 Child 13 will create 95 directories.
 272 Sat Apr 30 19:19:20 2011 Child 15 will create 95 directories.
 273 Sat Apr 30 19:19:20 2011 Child 12 will create 95 directories.
 274 Sat Apr 30 19:19:20 2011 Child 0 will create 95 directories.
 275 Sat Apr 30 19:19:20 2011 Child 4 will create 95 directories.
 276 Sat Apr 30 19:19:20 2011 Child 14 will create 95 directories.
 277 Sat Apr 30 19:19:20 2011 Child 11 will create 95 directories.
 278 Sat Apr 30 19:19:20 2011 Child 1 will create 95 directories.
 279 Sat Apr 30 19:19:20 2011 Child 10 will create 95 directories.
 280 Sat Apr 30 19:19:20 2011 Child 5 will create 95 directories.
 281 Sat Apr 30 19:19:20 2011 Child 9 will create 95 directories.
 282 Sat Apr 30 19:19:20 2011 Child 2 will create 95 directories.
 283 Sat Apr 30 19:19:20 2011 Child 6 will create 95 directories.
 284 Sat Apr 30 19:19:20 2011 Child 7 will create 95 directories.
 285 Sat Apr 30 19:19:20 2011 Child 8 will create 95 directories.
 286 Sat Apr 30 19:19:20 2011 Child 3 will create 95 directories.
 287 Sat Apr 30 19:19:20 2011 Child 15 finished creating 95 directories.
 288 Sat Apr 30 19:19:20 2011 Child 15 will create 2897 files.
 289 Sat Apr 30 19:19:20 2011 Child 1 finished creating 95 directories.
 290 Sat Apr 30 19:19:20 2011 Child 1 will create 2897 files.
 291 Sat Apr 30 19:19:20 2011 Child 0 finished creating 95 directories.
 292 Sat Apr 30 19:19:20 2011 Child 0 will create 2897 files.
 293 Sat Apr 30 19:19:20 2011 Child 3 finished creating 95 directories.
 294 Sat Apr 30 19:19:20 2011 Child 3 will create 2897 files.
 295 Sat Apr 30 19:19:21 2011 Child 12 finished creating 95 directories.
 296 Sat Apr 30 19:19:21 2011 Child 12 will create 2897 files.
 297 Sat Apr 30 19:19:21 2011 Child 5 finished creating 95 directories.
 298 Sat Apr 30 19:19:21 2011 Child 5 will create 2897 files.
 299 Sat Apr 30 19:19:22 2011 Child 11 finished creating 95 directories.
 300 Sat Apr 30 19:19:22 2011 Child 11 will create 2897 files.
 301 Sat Apr 30 19:19:22 2011 Child 13 finished creating 95 directories.
 302 Sat Apr 30 19:19:22 2011 Child 13 will create 2897 files.
 303 Sat Apr 30 19:19:24 2011 Child 7 finished creating 95 directories.
 304 Sat Apr 30 19:19:24 2011 Child 7 will create 2897 files.
 305 Sat Apr 30 19:19:25 2011 Child 10 finished creating 95 directories.
 306 Sat Apr 30 19:19:25 2011 Child 10 will create 2897 files.
 307 Sat Apr 30 19:19:25 2011 Child 14 finished creating 95 directories.
 308 Sat Apr 30 19:19:25 2011 Child 14 will create 2897 files.
 309 Sat Apr 30 19:19:25 2011 Child 9 finished creating 95 directories.
 310 Sat Apr 30 19:19:25 2011 Child 9 will create 2897 files.
 311 Sat Apr 30 19:19:25 2011 Child 2 finished creating 95 directories.
 312 Sat Apr 30 19:19:25 2011 Child 2 will create 2897 files.
 313 Sat Apr 30 19:19:25 2011 Child 4 finished creating 95 directories.
 314 Sat Apr 30 19:19:25 2011 Child 4 will create 2897 files.
 315 Sat Apr 30 19:19:25 2011 Child 8 finished creating 95 directories.
 316 Sat Apr 30 19:19:25 2011 Child 8 will create 2897 files.
 317 Sat Apr 30 19:19:25 2011 Child 6 finished creating 95 directories.
 318 Sat Apr 30 19:19:25 2011 Child 6 will create 2897 files.
 319 Sat Apr 30 19:24:23 2011 Child 15 verified 1784 files (426 creates).
 320 Sat Apr 30 19:24:30 2011 Child 14 verified 1719 files (361 creates).
 321 Sat Apr 30 19:24:30 2011 Child 12 verified 1687 files (329 creates).
 322 Sat Apr 30 19:24:30 2011 Child 7 verified 1687 files (329 creates).
 323 Sat Apr 30 19:24:30 2011 Child 4 verified 1687 files (329 creates).
 324 Sat Apr 30 19:24:31 2011 Child 13 verified 1687 files (329 creates).
 325 Sat Apr 30 19:24:31 2011 Child 2 verified 1687 files (329 creates).
 326 Sat Apr 30 19:24:31 2011 Child 9 verified 1687 files (329 creates).
 327 Sat Apr 30 19:24:54 2011 Child 1 verified 1784 files (426 creates).
 328 Sat Apr 30 19:24:54 2011 Child 6 verified 1687 files (329 creates).
 329 Sat Apr 30 19:25:05 2011 Child 0 verified 1866 files (508 creates).
 330 Sat Apr 30 19:25:05 2011 Child 5 verified 1780 files (422 creates).
 331 Sat Apr 30 19:25:05 2011 Child 11 verified 1780 files (422 creates).
 332 Sat Apr 30 19:25:19 2011 Child 10 verified 1687 files (329 creates).
 333 Sat Apr 30 19:25:23 2011 Child 8 verified 1687 files (329 creates).
 334 Sat Apr 30 19:25:28 2011 Child 3 verified 1774 files (416 creates).
 335 Sat Apr 30 19:29:45 2011 Child 2 verified 1907 files (549 creates).
 336 Sat Apr 30 19:29:45 2011 Child 4 verified 1907 files (549 creates).
 337 Sat Apr 30 19:29:45 2011 Child 14 verified 1998 files (640 creates).
 338 Sat Apr 30 19:29:49 2011 Child 12 verified 1907 files (549 creates).
 339 Sat Apr 30 19:29:49 2011 Child 9 verified 1907 files (549 creates).
 340 Sat Apr 30 19:29:49 2011 Child 7 verified 1907 files (549 creates).
 341 Sat Apr 30 19:29:49 2011 Child 13 verified 1907 files (549 creates).
 342 Sat Apr 30 19:29:49 2011 Child 15 verified 2141 files (783 creates).
 343 Sat Apr 30 19:29:55 2011 Child 6 verified 1933 files (575 creates).
 344 Sat Apr 30 19:29:58 2011 Child 1 verified 2141 files (783 creates).
 345 Sat Apr 30 19:30:06 2011 Child 5 verified 2199 files (841 creates).
 346 Sat Apr 30 19:30:06 2011 Child 0 verified 2362 files (1004 creates).
 347 Sat Apr 30 19:30:06 2011 Child 11 verified 2193 files (835 creates).
 348 Sat Apr 30 19:30:19 2011 Child 10 verified 2100 files (742 creates).
 349 Sat Apr 30 19:30:20 2011 Child 8 verified 2028 files (670 creates).
 350 Sat Apr 30 19:32:00 2011 Child 3 verified 2141 files (783 creates).
 351 Sat Apr 30 19:35:25 2011 Child 4 verified 2402 files (1044 creates).
 352 Sat Apr 30 19:35:25 2011 Child 13 verified 2339 files (981 creates).
 353 Sat Apr 30 19:35:25 2011 Child 10 verified 2356 files (998 creates).
 354 Sat Apr 30 19:35:25 2011 Child 2 verified 2404 files (1046 creates).
 355 Sat Apr 30 19:35:25 2011 Child 7 verified 2370 files (1012 creates).
 356 Sat Apr 30 19:35:33 2011 Child 0 verified 2643 files (1285 creates).
 357 Sat Apr 30 19:35:43 2011 Child 15 verified 2560 files (1202 creates).
 358 Sat Apr 30 19:35:52 2011 Child 5 verified 2501 files (1143 creates).
 359 Sat Apr 30 19:35:52 2011 Child 14 verified 2501 files (1143 creates).
 360 Sat Apr 30 19:35:52 2011 Child 8 verified 2364 files (1006 creates).
 361 Sat Apr 30 19:35:52 2011 Child 11 verified 2501 files (1143 creates).
 362 Sat Apr 30 19:35:52 2011 Child 9 verified 2364 files (1006 creates).
 363 Sat Apr 30 19:35:52 2011 Child 12 verified 2364 files (1006 creates).
 364 Sat Apr 30 19:35:52 2011 Child 1 verified 2501 files (1143 creates).
 365 Sat Apr 30 19:35:52 2011 Child 6 verified 2364 files (1006 creates).
 366 Sat Apr 30 19:37:05 2011 Child 3 verified 2399 files (1041 creates).
 367 Sat Apr 30 19:38:05 2011 Child 0 verified 2897 files, created 1511
 368 Sat Apr 30 19:38:05 2011 Child 0 will create 2 symlinks.
 369 Sat Apr 30 19:38:05 2011 Child 0 finished creating 2 symlinks.
 370 Sat Apr 30 19:40:22 2011 Child 15 verified 2897 files, created 1511
 371 Sat Apr 30 19:40:22 2011 Child 15 will create 2 symlinks.
 372 Sat Apr 30 19:40:22 2011 Child 15 finished creating 2 symlinks.
 373 Sat Apr 30 19:40:48 2011 Child 4 verified 2603 files (1245 creates).
 374 Sat Apr 30 19:40:49 2011 Child 7 verified 2542 files (1184 creates).
 375 Sat Apr 30 19:41:04 2011 Child 12 verified 2542 files (1184 creates).
 376 Sat Apr 30 19:41:04 2011 Child 9 verified 2542 files (1184 creates).
 377 Sat Apr 30 19:41:04 2011 Child 10 verified 2542 files (1184 creates).
 378 Sat Apr 30 19:41:04 2011 Child 2 verified 2643 files (1285 creates).
 379 Sat Apr 30 19:41:04 2011 Child 8 verified 2542 files (1184 creates).
 380 Sat Apr 30 19:41:04 2011 Child 6 verified 2542 files (1184 creates).
 381 Sat Apr 30 19:41:04 2011 Child 13 verified 2539 files (1181 creates).
 382 Sat Apr 30 19:41:08 2011 Child 1 verified 2897 files, created 1511
 383 Sat Apr 30 19:41:08 2011 Child 1 will create 2 symlinks.
 384 Sat Apr 30 19:41:08 2011 Child 1 finished creating 2 symlinks.
 385 Sat Apr 30 19:41:08 2011 Child 11 verified 2897 files, created 1511
 386 Sat Apr 30 19:41:08 2011 Child 11 will create 2 symlinks.
 387 Sat Apr 30 19:41:08 2011 Child 11 finished creating 2 symlinks.
 388 Sat Apr 30 19:41:10 2011 Child 5 verified 2897 files, created 1511
 389 Sat Apr 30 19:41:10 2011 Child 5 will create 2 symlinks.
 390 Sat Apr 30 19:41:10 2011 Child 14 verified 2897 files, created 1511
 391 Sat Apr 30 19:41:10 2011 Child 14 will create 2 symlinks.
 392 Sat Apr 30 19:41:11 2011 Child 5 finished creating 2 symlinks.
 393 Sat Apr 30 19:41:15 2011 Child 14 finished creating 2 symlinks.
 394 Sat Apr 30 19:41:24 2011 Child 2 verified 2897 files, created 1511
 395 Sat Apr 30 19:41:24 2011 Child 2 will create 2 symlinks.
 396 Sat Apr 30 19:41:24 2011 Child 2 finished creating 2 symlinks.
 397 Sat Apr 30 19:41:32 2011 Child 3 verified 2897 files, created 1511
 398 Sat Apr 30 19:41:32 2011 Child 3 will create 2 symlinks.
 399 Sat Apr 30 19:41:32 2011 Child 3 finished creating 2 symlinks.
 400 Sat Apr 30 19:41:38 2011 Child 7 verified 2897 files, created 1511
 401 Sat Apr 30 19:41:38 2011 Child 7 will create 2 symlinks.
 402 Sat Apr 30 19:41:38 2011 Child 7 finished creating 2 symlinks.
 403 Sat Apr 30 19:41:40 2011 Child 4 verified 2897 files, created 1511
 404 Sat Apr 30 19:41:40 2011 Child 4 will create 2 symlinks.
 405 Sat Apr 30 19:41:40 2011 Child 4 finished creating 2 symlinks.
 406 Sat Apr 30 19:41:45 2011 Child 10 verified 2897 files, created 1511
 407 Sat Apr 30 19:41:45 2011 Child 10 will create 2 symlinks.
 408 Sat Apr 30 19:41:45 2011 Child 10 finished creating 2 symlinks.
 409 Sat Apr 30 19:41:45 2011 Child 6 verified 2897 files, created 1511
 410 Sat Apr 30 19:41:45 2011 Child 6 will create 2 symlinks.
 411 Sat Apr 30 19:41:45 2011 Child 6 finished creating 2 symlinks.
 412 Sat Apr 30 19:41:46 2011 Child 8 verified 2897 files, created 1511
 413 Sat Apr 30 19:41:46 2011 Child 8 will create 2 symlinks.
 414 Sat Apr 30 19:41:46 2011 Child 8 finished creating 2 symlinks.
 415 Sat Apr 30 19:41:48 2011 Child 12 verified 2897 files, created 1511
 416 Sat Apr 30 19:41:48 2011 Child 12 will create 2 symlinks.
 417 Sat Apr 30 19:41:48 2011 Child 12 finished creating 2 symlinks.
 418 Sat Apr 30 19:41:49 2011 Child 9 verified 2897 files, created 1511
 419 Sat Apr 30 19:41:49 2011 Child 9 will create 2 symlinks.
 420 Sat Apr 30 19:41:49 2011 Child 9 finished creating 2 symlinks.
 421 Sat Apr 30 19:41:49 2011 Child 13 verified 2897 files, created 1511
 422 Sat Apr 30 19:41:49 2011 Child 13 will create 2 symlinks.
 423 Sat Apr 30 19:41:49 2011 Child 13 finished creating 2 symlinks.
 424 Sat Apr 30 19:41:50 2011 Completed.
 425 Sat Apr 30 19:41:50 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 426 Sat Apr 30 19:41:50 2011 Completed.
 427 Sat Apr 30 19:41:50 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 428 Sat Apr 30 19:41:50 2011 Received.
 429 Sat Apr 30 19:41:50 2011 Performing 300 seconds pretest warmup.
 430 Sat Apr 30 19:46:50 2011 Completed.
 431 Sat Apr 30 19:46:50 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 432 Sat Apr 30 19:46:50 2011 Completed.
 433 Sat Apr 30 19:46:50 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 434 Sat Apr 30 19:46:51 2011 Received.
 435 Sat Apr 30 19:46:51 2011 Starting 300 seconds test run.
 436 SFS Single Client (c09-44) Results, Sat Apr 30 19:52:01 2011
 437 --------------------------------------------------------------------------------------
 438 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 439 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 440 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 441            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 442 --------------------------------------------------------------------------------------
 443 getattr     26.0%    26.1%    15638    15638      0      0.19     0.04      0.00  3.9%
 444 setattr      4.0%     4.1%     2445     2445      0      1.59     3.13      0.07  5.0%
 445 lookup      24.0%    23.9%    14315    14315      0      0.20     0.04      0.00  3.7%
 446 readlink     1.0%     1.0%      622      622      0      0.19     0.03      0.01  0.2%
 447 read        18.0%    18.1%    10840    13423      0      2.69     6.72      0.05 37.9%
 448 write       10.0%     9.9%     5966     7275      0      3.99    12.15      0.09 30.9%
 449 create       1.0%     1.0%      599      599      0      3.27     4.30      0.17  2.5%
 450 remove       1.0%     1.0%      622      622      0      2.34     3.43      0.15  1.9%
 451 readdir      1.0%     1.0%      604      604      0      0.40     0.07      0.02  0.3%
 452 fsstat       1.0%     1.0%      616      616      0      0.19     0.06      0.02  0.2%
 453 access      11.0%    10.9%     6545     6545      0      0.19     0.04      0.00  1.6%
 454 commit       0.0%     0.0%        0     2600      0      0.00     0.00      0.00 10.9%
 455 readdirplus  2.0%     2.0%     1206     1206      0      0.65     0.07      0.01  1.0%
 456 --------------------------------------------------------------------------------------
 457
 458       --------------------------------------------------
 459       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 460       --------------------------+-----------------------
 461 SFS NFS V3 THROUGHPUT:  200.06 Ops/Sec   AVG. RESPONSE TIME:    1.28 Msec/Op
 462 TCP PROTOCOL
 463 FAST CALLS: 0
 464 NFS V3 MIXFILE: [ SFS Default ]
 465 CLIENT REQUESTED LOAD: 200 Ops/Sec
 466 TOTAL LOGICAL NFS V3 OPERATIONS:  60018          TEST TIME: 300 Sec
 467 TOTAL PHYSICAL NFS V3 OPERATIONS: 66510
 468 PHYSICAL NFS V3 THROUGHPUT:  221.70 Ops/Sec
 469 FILE SET SIZE CREATED: 24128640.0 KB
 470 FILE SET SIZE ACCESSED: 7247088.0 - 7391741.0 KB  (100.00% to 102.00% of Base)
 471
 472 ------------------------------------------------------------------------
 473
 474 Sat Apr 30 19:52:01 2011 Completed.
 475
 476 Client Test Parameters:
 477         Number of processes = 16
 478         Requested Load (NFS V3 operations/second) = 200
 479         Maximum number of outstanding biod writes = 8
 480         Maximum number of outstanding biod reads = 8
 481         Warm-up time (seconds) = 300
 482         Run time (seconds) = 300
 483         File Set = 45440 Files created for I/O operations
 484                    13632 Files accessed for I/O operations
 485                     912 Files for non-I/O operations
 486                      32 Symlinks
 487                    1504 Directories
 488                         Additional non-I/O files created as necessary
 489
 490
 491 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 492 Sat Apr 30 19:52:01 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 493 Sat Apr 30 19:52:01 2011 Completed.
 494 Sat Apr 30 19:52:01 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 495
 496 ************************************************************************
 497 Sat Apr 30 19:52:02 2011 Received.
 498 Sat Apr 30 19:52:02 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 499 Sat Apr 30 19:52:02 2011 Completed.
 500 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
 501 NFS V3 Protocol Version
 502 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 503 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 504
 505 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 506 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 507
 508 ************************************************************************
 509
 510 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 19:52:04 2011
 511         Client hostname = c09-44
 512         Prime Client hostname = c09-44.sysnet.ucsd.edu
 513
 514 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 515 NFS V3 Protocol
 516 Sat Apr 30 19:52:04 2011 Mounting 16 remote test directories.
 517 Child[000]: NFS negotiated block size 32768
 518 Child[001]: NFS negotiated block size 32768
 519 Child[002]: NFS negotiated block size 32768
 520 Child[003]: NFS negotiated block size 32768
 521 Child[004]: NFS negotiated block size 32768
 522 Child[005]: NFS negotiated block size 32768
 523 Child[006]: NFS negotiated block size 32768
 524 Child[007]: NFS negotiated block size 32768
 525 Child[008]: NFS negotiated block size 32768
 526 Child[009]: NFS negotiated block size 32768
 527 Child[010]: NFS negotiated block size 32768
 528 Child[011]: NFS negotiated block size 32768
 529 Child[012]: NFS negotiated block size 32768
 530 Child[013]: NFS negotiated block size 32768
 531 Child[014]: NFS negotiated block size 32768
 532 Child[015]: NFS negotiated block size 32768
 533 Sat Apr 30 19:52:18 2011 Completed.
 534 Sat Apr 30 19:52:18 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 535 Sat Apr 30 19:52:18 2011 Completed.
 536 Sat Apr 30 19:52:18 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 537 Sat Apr 30 19:52:20 2011 Received.
 538
 539 Sat Apr 30 19:52:20 2011 Initializing test directories.
 540 Sat Apr 30 19:52:20 2011 Child 8 will create 145 directories.
 541 Sat Apr 30 19:52:20 2011 Child 4 will create 145 directories.
 542 Sat Apr 30 19:52:20 2011 Child 2 will create 145 directories.
 543 Sat Apr 30 19:52:20 2011 Child 7 will create 145 directories.
 544 Sat Apr 30 19:52:20 2011 Child 1 will create 145 directories.
 545 Sat Apr 30 19:52:20 2011 Child 0 will create 145 directories.
 546 Sat Apr 30 19:52:20 2011 Child 6 will create 145 directories.
 547 Sat Apr 30 19:52:20 2011 Child 5 will create 145 directories.
 548 Sat Apr 30 19:52:20 2011 Child 3 will create 145 directories.
 549 Sat Apr 30 19:52:20 2011 Child 9 will create 145 directories.
 550 Sat Apr 30 19:52:20 2011 Child 15 will create 145 directories.
 551 Sat Apr 30 19:52:20 2011 Child 10 will create 145 directories.
 552 Sat Apr 30 19:52:20 2011 Child 14 will create 145 directories.
 553 Sat Apr 30 19:52:20 2011 Child 11 will create 145 directories.
 554 Sat Apr 30 19:52:20 2011 Child 13 will create 145 directories.
 555 Sat Apr 30 19:52:20 2011 Child 12 will create 145 directories.
 556 Sat Apr 30 19:52:20 2011 Child 4 finished creating 145 directories.
 557 Sat Apr 30 19:52:20 2011 Child 4 will create 4408 files.
 558 Sat Apr 30 19:52:20 2011 Child 5 finished creating 145 directories.
 559 Sat Apr 30 19:52:20 2011 Child 5 will create 4408 files.
 560 Sat Apr 30 19:52:21 2011 Child 1 finished creating 145 directories.
 561 Sat Apr 30 19:52:21 2011 Child 1 will create 4408 files.
 562 Sat Apr 30 19:52:21 2011 Child 3 finished creating 145 directories.
 563 Sat Apr 30 19:52:21 2011 Child 3 will create 4408 files.
 564 Sat Apr 30 19:52:21 2011 Child 10 finished creating 145 directories.
 565 Sat Apr 30 19:52:21 2011 Child 10 will create 4408 files.
 566 Sat Apr 30 19:52:23 2011 Child 13 finished creating 145 directories.
 567 Sat Apr 30 19:52:23 2011 Child 13 will create 4408 files.
 568 Sat Apr 30 19:52:23 2011 Child 15 finished creating 145 directories.
 569 Sat Apr 30 19:52:23 2011 Child 15 will create 4408 files.
 570 Sat Apr 30 19:52:23 2011 Child 6 finished creating 145 directories.
 571 Sat Apr 30 19:52:23 2011 Child 6 will create 4408 files.
 572 Sat Apr 30 19:52:25 2011 Child 9 finished creating 145 directories.
 573 Sat Apr 30 19:52:25 2011 Child 9 will create 4408 files.
 574 Sat Apr 30 19:52:25 2011 Child 7 finished creating 145 directories.
 575 Sat Apr 30 19:52:25 2011 Child 7 will create 4408 files.
 576 Sat Apr 30 19:52:29 2011 Child 12 finished creating 145 directories.
 577 Sat Apr 30 19:52:29 2011 Child 12 will create 4408 files.
 578 Sat Apr 30 19:52:29 2011 Child 14 finished creating 145 directories.
 579 Sat Apr 30 19:52:29 2011 Child 14 will create 4408 files.
 580 Sat Apr 30 19:52:29 2011 Child 8 finished creating 145 directories.
 581 Sat Apr 30 19:52:29 2011 Child 8 will create 4408 files.
 582 Sat Apr 30 19:52:30 2011 Child 0 finished creating 145 directories.
 583 Sat Apr 30 19:52:30 2011 Child 0 will create 4408 files.
 584 Sat Apr 30 19:52:30 2011 Child 2 finished creating 145 directories.
 585 Sat Apr 30 19:52:30 2011 Child 2 will create 4408 files.
 586 Sat Apr 30 19:52:31 2011 Child 11 finished creating 145 directories.
 587 Sat Apr 30 19:52:31 2011 Child 11 will create 4408 files.
 588 Sat Apr 30 19:57:25 2011 Child 3 verified 3281 files (441 creates).
 589 Sat Apr 30 19:57:25 2011 Child 4 verified 3271 files (431 creates).
 590 Sat Apr 30 19:57:25 2011 Child 1 verified 3231 files (391 creates).
 591 Sat Apr 30 19:57:25 2011 Child 6 verified 3099 files (259 creates).
 592 Sat Apr 30 19:57:25 2011 Child 13 verified 3075 files (235 creates).
 593 Sat Apr 30 19:57:25 2011 Child 15 verified 3146 files (306 creates).
 594 Sat Apr 30 19:57:42 2011 Child 9 verified 3107 files (267 creates).
 595 Sat Apr 30 19:57:42 2011 Child 12 verified 3075 files (235 creates).
 596 Sat Apr 30 19:57:42 2011 Child 7 verified 3075 files (235 creates).
 597 Sat Apr 30 19:57:42 2011 Child 2 verified 3052 files (212 creates).
 598 Sat Apr 30 19:57:42 2011 Child 10 verified 3146 files (306 creates).
 599 Sat Apr 30 19:57:42 2011 Child 14 verified 3052 files (212 creates).
 600 Sat Apr 30 19:57:42 2011 Child 11 verified 3052 files (212 creates).
 601 Sat Apr 30 19:57:42 2011 Child 0 verified 3075 files (235 creates).
 602 Sat Apr 30 19:57:42 2011 Child 5 verified 3370 files (530 creates).
 603 Sat Apr 30 19:57:42 2011 Child 8 verified 3075 files (235 creates).
 604 Sat Apr 30 20:02:34 2011 Child 1 verified 3544 files (704 creates).
 605 Sat Apr 30 20:02:44 2011 Child 11 verified 3456 files (616 creates).
 606 Sat Apr 30 20:02:44 2011 Child 10 verified 3661 files (821 creates).
 607 Sat Apr 30 20:02:54 2011 Child 4 verified 3661 files (821 creates).
 608 Sat Apr 30 20:02:54 2011 Child 14 verified 3456 files (616 creates).
 609 Sat Apr 30 20:03:05 2011 Child 3 verified 3778 files (938 creates).
 610 Sat Apr 30 20:03:05 2011 Child 2 verified 3456 files (616 creates).
 611 Sat Apr 30 20:03:05 2011 Child 12 verified 3508 files (668 creates).
 612 Sat Apr 30 20:03:05 2011 Child 7 verified 3456 files (616 creates).
 613 Sat Apr 30 20:03:19 2011 Child 9 verified 3505 files (665 creates).
 614 Sat Apr 30 20:03:33 2011 Child 13 verified 3505 files (665 creates).
 615 Sat Apr 30 20:03:40 2011 Child 8 verified 3505 files (665 creates).
 616 Sat Apr 30 20:03:40 2011 Child 15 verified 3505 files (665 creates).
 617 Sat Apr 30 20:03:40 2011 Child 0 verified 3505 files (665 creates).
 618 Sat Apr 30 20:03:40 2011 Child 5 verified 3817 files (977 creates).
 619 Sat Apr 30 20:03:40 2011 Child 6 verified 3505 files (665 creates).
 620 Sat Apr 30 20:07:39 2011 Child 1 verified 4022 files (1182 creates).
 621 Sat Apr 30 20:07:51 2011 Child 10 verified 3886 files (1046 creates).
 622 Sat Apr 30 20:08:08 2011 Child 4 verified 3903 files (1063 creates).
 623 Sat Apr 30 20:08:08 2011 Child 3 verified 4061 files (1221 creates).
 624 Sat Apr 30 20:08:08 2011 Child 12 verified 3879 files (1039 creates).
 625 Sat Apr 30 20:08:14 2011 Child 14 verified 3778 files (938 creates).
 626 Sat Apr 30 20:08:27 2011 Child 11 verified 3778 files (938 creates).
 627 Sat Apr 30 20:08:27 2011 Child 2 verified 3743 files (903 creates).
 628 Sat Apr 30 20:08:27 2011 Child 7 verified 3743 files (903 creates).
 629 Sat Apr 30 20:08:27 2011 Child 9 verified 3886 files (1046 creates).
 630 Sat Apr 30 20:09:00 2011 Child 6 verified 3817 files (977 creates).
 631 Sat Apr 30 20:09:00 2011 Child 5 verified 4171 files (1331 creates).
 632 Sat Apr 30 20:09:20 2011 Child 15 verified 3817 files (977 creates).
 633 Sat Apr 30 20:09:20 2011 Child 0 verified 3817 files (977 creates).
 634 Sat Apr 30 20:09:49 2011 Child 3 verified 4408 files, created 1525
 635 Sat Apr 30 20:09:49 2011 Child 3 will create 2 symlinks.
 636 Sat Apr 30 20:09:49 2011 Child 3 finished creating 2 symlinks.
 637 Sat Apr 30 20:09:53 2011 Child 5 verified 4408 files, created 1525
 638 Sat Apr 30 20:09:53 2011 Child 5 will create 2 symlinks.
 639 Sat Apr 30 20:09:53 2011 Child 5 finished creating 2 symlinks.
 640 Sat Apr 30 20:10:12 2011 Child 8 verified 3817 files (977 creates).
 641 Sat Apr 30 20:10:12 2011 Child 13 verified 3873 files (1033 creates).
 642 Sat Apr 30 20:10:37 2011 Child 1 verified 4408 files, created 1525
 643 Sat Apr 30 20:10:37 2011 Child 1 will create 2 symlinks.
 644 Sat Apr 30 20:10:37 2011 Child 1 finished creating 2 symlinks.
 645 Sat Apr 30 20:10:39 2011 Child 10 verified 4408 files, created 1525
 646 Sat Apr 30 20:10:39 2011 Child 10 will create 2 symlinks.
 647 Sat Apr 30 20:10:39 2011 Child 10 finished creating 2 symlinks.
 648 Sat Apr 30 20:10:41 2011 Child 4 verified 4408 files, created 1525
 649 Sat Apr 30 20:10:41 2011 Child 4 will create 2 symlinks.
 650 Sat Apr 30 20:10:41 2011 Child 4 finished creating 2 symlinks.
 651 Sat Apr 30 20:10:44 2011 Child 9 verified 4408 files, created 1525
 652 Sat Apr 30 20:10:44 2011 Child 9 will create 2 symlinks.
 653 Sat Apr 30 20:10:44 2011 Child 9 finished creating 2 symlinks.
 654 Sat Apr 30 20:10:57 2011 Child 12 verified 4408 files, created 1525
 655 Sat Apr 30 20:10:57 2011 Child 12 will create 2 symlinks.
 656 Sat Apr 30 20:10:57 2011 Child 12 finished creating 2 symlinks.
 657 Sat Apr 30 20:11:03 2011 Child 0 verified 4408 files, created 1525
 658 Sat Apr 30 20:11:03 2011 Child 0 will create 2 symlinks.
 659 Sat Apr 30 20:11:03 2011 Child 0 finished creating 2 symlinks.
 660 Sat Apr 30 20:11:04 2011 Child 6 verified 4408 files, created 1525
 661 Sat Apr 30 20:11:04 2011 Child 6 will create 2 symlinks.
 662 Sat Apr 30 20:11:04 2011 Child 6 finished creating 2 symlinks.
 663 Sat Apr 30 20:11:05 2011 Child 13 verified 4408 files, created 1525
 664 Sat Apr 30 20:11:05 2011 Child 13 will create 2 symlinks.
 665 Sat Apr 30 20:11:05 2011 Child 13 finished creating 2 symlinks.
 666 Sat Apr 30 20:11:06 2011 Child 7 verified 4408 files, created 1525
 667 Sat Apr 30 20:11:06 2011 Child 7 will create 2 symlinks.
 668 Sat Apr 30 20:11:06 2011 Child 7 finished creating 2 symlinks.
 669 Sat Apr 30 20:11:06 2011 Child 15 verified 4408 files, created 1525
 670 Sat Apr 30 20:11:06 2011 Child 15 will create 2 symlinks.
 671 Sat Apr 30 20:11:07 2011 Child 15 finished creating 2 symlinks.
 672 Sat Apr 30 20:11:08 2011 Child 8 verified 4408 files, created 1525
 673 Sat Apr 30 20:11:08 2011 Child 8 will create 2 symlinks.
 674 Sat Apr 30 20:11:08 2011 Child 8 finished creating 2 symlinks.
 675 Sat Apr 30 20:11:08 2011 Child 11 verified 4408 files, created 1525
 676 Sat Apr 30 20:11:08 2011 Child 11 will create 2 symlinks.
 677 Sat Apr 30 20:11:08 2011 Child 11 finished creating 2 symlinks.
 678 Sat Apr 30 20:11:08 2011 Child 2 verified 4408 files, created 1525
 679 Sat Apr 30 20:11:08 2011 Child 2 will create 2 symlinks.
 680 Sat Apr 30 20:11:08 2011 Child 2 finished creating 2 symlinks.
 681 Sat Apr 30 20:11:08 2011 Child 14 verified 4408 files, created 1525
 682 Sat Apr 30 20:11:08 2011 Child 14 will create 2 symlinks.
 683 Sat Apr 30 20:11:08 2011 Child 14 finished creating 2 symlinks.
 684 Sat Apr 30 20:11:09 2011 Completed.
 685 Sat Apr 30 20:11:09 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 686 Sat Apr 30 20:11:09 2011 Completed.
 687 Sat Apr 30 20:11:09 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 688 Sat Apr 30 20:11:09 2011 Received.
 689 Sat Apr 30 20:11:09 2011 Performing 300 seconds pretest warmup.
 690 Sat Apr 30 20:16:09 2011 Completed.
 691 Sat Apr 30 20:16:09 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 692 Sat Apr 30 20:16:09 2011 Completed.
 693 Sat Apr 30 20:16:09 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 694 Sat Apr 30 20:16:10 2011 Received.
 695 Sat Apr 30 20:16:10 2011 Starting 300 seconds test run.
 696 SFS Single Client (c09-44) Results, Sat Apr 30 20:21:20 2011
 697 --------------------------------------------------------------------------------------
 698 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 699 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 700 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 701            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 702 --------------------------------------------------------------------------------------
 703 getattr     26.0%    26.0%    23397    23397      0      0.20     0.39      0.01  2.6%
 704 setattr      4.0%     4.0%     3599     3599      0      2.78     6.25      0.08  5.6%
 705 lookup      24.0%    24.1%    21692    21692      0      0.20     0.17      0.01  2.4%
 706 readlink     1.0%     1.0%      907      907      0      0.20     0.05      0.01  0.1%
 707 read        18.0%    17.9%    16106    19733      0      4.52    57.69      0.12 40.6%
 708 write       10.0%    10.1%     9111    11155      0      6.27   127.23      0.23 31.9%
 709 create       1.0%     1.0%      935      935      0      4.78     5.96      0.16  2.5%
 710 remove       1.0%     1.0%      865      865      0      4.19     6.73      0.17  2.0%
 711 readdir      1.0%     0.9%      855      855      0      0.41     0.07      0.02  0.2%
 712 fsstat       1.0%     1.0%      923      923      0      0.20     0.05      0.01  0.1%
 713 access      11.0%    11.0%     9878     9878      0      0.20     0.53      0.01  1.1%
 714 commit       0.0%     0.0%        0     3992      0      0.00     0.00      0.00 10.2%
 715 readdirplus  2.0%     2.0%     1778     1778      0      0.70     0.12      0.02  0.7%
 716 --------------------------------------------------------------------------------------
 717
 718       --------------------------------------------------
 719       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 720       --------------------------+-----------------------
 721 SFS NFS V3 THROUGHPUT:  301.15 Ops/Sec   AVG. RESPONSE TIME:    1.99 Msec/Op
 722 TCP PROTOCOL
 723 FAST CALLS: 0
 724 NFS V3 MIXFILE: [ SFS Default ]
 725 CLIENT REQUESTED LOAD: 300 Ops/Sec
 726 TOTAL LOGICAL NFS V3 OPERATIONS:  90046          TEST TIME: 299 Sec
 727 TOTAL PHYSICAL NFS V3 OPERATIONS: 99709
 728 PHYSICAL NFS V3 THROUGHPUT:  333.47 Ops/Sec
 729 FILE SET SIZE CREATED: 36711216.0 KB
 730 FILE SET SIZE ACCESSED: 11019312.0 - 11237901.0 KB  (100.00% to 101.98% of Base)
 731
 732 ------------------------------------------------------------------------
 733
 734 Sat Apr 30 20:21:20 2011 Completed.
 735
 736 Client Test Parameters:
 737         Number of processes = 16
 738         Requested Load (NFS V3 operations/second) = 300
 739         Maximum number of outstanding biod writes = 8
 740         Maximum number of outstanding biod reads = 8
 741         Warm-up time (seconds) = 300
 742         Run time (seconds) = 300
 743         File Set = 69136 Files created for I/O operations
 744                    20736 Files accessed for I/O operations
 745                    1392 Files for non-I/O operations
 746                      32 Symlinks
 747                    2304 Directories
 748                         Additional non-I/O files created as necessary
 749
 750
 751 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 752 Sat Apr 30 20:21:20 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 753 Sat Apr 30 20:21:20 2011 Completed.
 754 Sat Apr 30 20:21:20 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 755
 756 ************************************************************************
 757 Sat Apr 30 20:21:21 2011 Received.
 758 Sat Apr 30 20:21:21 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 759 Sat Apr 30 20:21:21 2011 Completed.
 760 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
 761 NFS V3 Protocol Version
 762
 763 ************************************************************************
 764 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 765 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 766
 767 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 768 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 769
 770 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 20:21:23 2011
 771         Client hostname = c09-44
 772         Prime Client hostname = c09-44.sysnet.ucsd.edu
 773
 774 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 775 NFS V3 Protocol
 776 Sat Apr 30 20:21:23 2011 Mounting 16 remote test directories.
 777 Child[000]: NFS negotiated block size 32768
 778 Child[001]: NFS negotiated block size 32768
 779 Child[002]: NFS negotiated block size 32768
 780 Child[003]: NFS negotiated block size 32768
 781 Child[004]: NFS negotiated block size 32768
 782 Child[005]: NFS negotiated block size 32768
 783 Child[006]: NFS negotiated block size 32768
 784 Child[007]: NFS negotiated block size 32768
 785 Child[008]: NFS negotiated block size 32768
 786 Child[009]: NFS negotiated block size 32768
 787 Child[010]: NFS negotiated block size 32768
 788 Child[011]: NFS negotiated block size 32768
 789 Child[012]: NFS negotiated block size 32768
 790 Child[013]: NFS negotiated block size 32768
 791 Child[014]: NFS negotiated block size 32768
 792 Child[015]: NFS negotiated block size 32768
 793 Sat Apr 30 20:21:37 2011 Completed.
 794 Sat Apr 30 20:21:37 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 795 Sat Apr 30 20:21:37 2011 Completed.
 796 Sat Apr 30 20:21:37 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 797 Sat Apr 30 20:21:39 2011 Received.
 798
 799 Sat Apr 30 20:21:39 2011 Initializing test directories.
 800 Sat Apr 30 20:21:39 2011 Child 6 will create 190 directories.
 801 Sat Apr 30 20:21:39 2011 Child 7 will create 190 directories.
 802 Sat Apr 30 20:21:39 2011 Child 3 will create 190 directories.
 803 Sat Apr 30 20:21:39 2011 Child 1 will create 190 directories.
 804 Sat Apr 30 20:21:39 2011 Child 4 will create 190 directories.
 805 Sat Apr 30 20:21:39 2011 Child 9 will create 190 directories.
 806 Sat Apr 30 20:21:39 2011 Child 2 will create 190 directories.
 807 Sat Apr 30 20:21:39 2011 Child 5 will create 190 directories.
 808 Sat Apr 30 20:21:39 2011 Child 10 will create 190 directories.
 809 Sat Apr 30 20:21:39 2011 Child 15 will create 190 directories.
 810 Sat Apr 30 20:21:39 2011 Child 8 will create 190 directories.
 811 Sat Apr 30 20:21:39 2011 Child 11 will create 190 directories.
 812 Sat Apr 30 20:21:39 2011 Child 0 will create 190 directories.
 813 Sat Apr 30 20:21:39 2011 Child 12 will create 190 directories.
 814 Sat Apr 30 20:21:39 2011 Child 14 will create 190 directories.
 815 Sat Apr 30 20:21:39 2011 Child 13 will create 190 directories.
 816 Sat Apr 30 20:21:40 2011 Child 2 finished creating 190 directories.
 817 Sat Apr 30 20:21:40 2011 Child 2 will create 5793 files.
 818 Sat Apr 30 20:21:41 2011 Child 15 finished creating 190 directories.
 819 Sat Apr 30 20:21:41 2011 Child 15 will create 5793 files.
 820 Sat Apr 30 20:21:41 2011 Child 6 finished creating 190 directories.
 821 Sat Apr 30 20:21:41 2011 Child 6 will create 5793 files.
 822 Sat Apr 30 20:21:43 2011 Child 10 finished creating 190 directories.
 823 Sat Apr 30 20:21:43 2011 Child 10 will create 5793 files.
 824 Sat Apr 30 20:21:43 2011 Child 7 finished creating 190 directories.
 825 Sat Apr 30 20:21:43 2011 Child 7 will create 5793 files.
 826 Sat Apr 30 20:21:44 2011 Child 0 finished creating 190 directories.
 827 Sat Apr 30 20:21:44 2011 Child 0 will create 5793 files.
 828 Sat Apr 30 20:21:44 2011 Child 8 finished creating 190 directories.
 829 Sat Apr 30 20:21:44 2011 Child 8 will create 5793 files.
 830 Sat Apr 30 20:21:44 2011 Child 13 finished creating 190 directories.
 831 Sat Apr 30 20:21:44 2011 Child 13 will create 5793 files.
 832 Sat Apr 30 20:21:51 2011 Child 4 finished creating 190 directories.
 833 Sat Apr 30 20:21:51 2011 Child 4 will create 5793 files.
 834 Sat Apr 30 20:21:51 2011 Child 12 finished creating 190 directories.
 835 Sat Apr 30 20:21:51 2011 Child 12 will create 5793 files.
 836 Sat Apr 30 20:21:51 2011 Child 14 finished creating 190 directories.
 837 Sat Apr 30 20:21:51 2011 Child 14 will create 5793 files.
 838 Sat Apr 30 20:21:52 2011 Child 5 finished creating 190 directories.
 839 Sat Apr 30 20:21:52 2011 Child 5 will create 5793 files.
 840 Sat Apr 30 20:21:53 2011 Child 9 finished creating 190 directories.
 841 Sat Apr 30 20:21:53 2011 Child 9 will create 5793 files.
 842 Sat Apr 30 20:21:53 2011 Child 11 finished creating 190 directories.
 843 Sat Apr 30 20:21:53 2011 Child 11 will create 5793 files.
 844 Sat Apr 30 20:21:54 2011 Child 3 finished creating 190 directories.
 845 Sat Apr 30 20:21:54 2011 Child 3 will create 5793 files.
 846 Sat Apr 30 20:21:54 2011 Child 1 finished creating 190 directories.
 847 Sat Apr 30 20:21:54 2011 Child 1 will create 5793 files.
 848 Sat Apr 30 20:26:41 2011 Child 2 verified 5166 files (845 creates).
 849 Sat Apr 30 20:26:43 2011 Child 6 verified 5182 files (861 creates).
 850 Sat Apr 30 20:27:23 2011 Child 7 verified 4932 files (611 creates).
 851 Sat Apr 30 20:27:23 2011 Child 13 verified 4819 files (498 creates).
 852 Sat Apr 30 20:27:23 2011 Child 3 verified 4714 files (393 creates).
 853 Sat Apr 30 20:27:23 2011 Child 12 verified 4514 files (193 creates).
 854 Sat Apr 30 20:27:29 2011 Child 9 verified 4525 files (204 creates).
 855 Sat Apr 30 20:27:30 2011 Child 15 verified 4896 files (575 creates).
 856 Sat Apr 30 20:27:30 2011 Child 1 verified 4479 files (158 creates).
 857 Sat Apr 30 20:27:30 2011 Child 8 verified 4886 files (565 creates).
 858 Sat Apr 30 20:27:30 2011 Child 5 verified 4571 files (250 creates).
 859 Sat Apr 30 20:27:45 2011 Child 4 verified 4777 files (456 creates).
 860 Sat Apr 30 20:28:02 2011 Child 0 verified 4991 files (670 creates).
 861 Sat Apr 30 20:28:02 2011 Child 10 verified 4886 files (565 creates).
 862 Sat Apr 30 20:28:02 2011 Child 14 verified 4571 files (250 creates).
 863 Sat Apr 30 20:28:02 2011 Child 11 verified 4571 files (250 creates).
 864 Sat Apr 30 20:29:51 2011 Child 6 verified 5793 files, created 1416
 865 Sat Apr 30 20:29:51 2011 Child 6 will create 2 symlinks.
 866 Sat Apr 30 20:29:51 2011 Child 6 finished creating 2 symlinks.
 867 Sat Apr 30 20:29:53 2011 Child 2 verified 5793 files, created 1416
 868 Sat Apr 30 20:29:53 2011 Child 2 will create 2 symlinks.
 869 Sat Apr 30 20:29:53 2011 Child 2 finished creating 2 symlinks.
 870 Sat Apr 30 20:32:28 2011 Child 12 verified 4951 files (630 creates).
 871 Sat Apr 30 20:32:37 2011 Child 1 verified 4951 files (630 creates).
 872 Sat Apr 30 20:32:37 2011 Child 9 verified 4896 files (575 creates).
 873 Sat Apr 30 20:32:37 2011 Child 8 verified 5269 files (948 creates).
 874 Sat Apr 30 20:32:37 2011 Child 13 verified 5275 files (954 creates).
 875 Sat Apr 30 20:32:37 2011 Child 3 verified 5191 files (870 creates).
 876 Sat Apr 30 20:32:37 2011 Child 7 verified 5362 files (1041 creates).
 877 Sat Apr 30 20:32:37 2011 Child 5 verified 4962 files (641 creates).
 878 Sat Apr 30 20:32:46 2011 Child 4 verified 5269 files (948 creates).
 879 Sat Apr 30 20:32:57 2011 Child 0 verified 5793 files, created 1416
 880 Sat Apr 30 20:32:57 2011 Child 0 will create 2 symlinks.
 881 Sat Apr 30 20:32:57 2011 Child 0 finished creating 2 symlinks.
 882 Sat Apr 30 20:33:02 2011 Child 10 verified 5505 files (1184 creates).
 883 Sat Apr 30 20:33:11 2011 Child 7 verified 5793 files, created 1416
 884 Sat Apr 30 20:33:11 2011 Child 7 will create 2 symlinks.
 885 Sat Apr 30 20:33:11 2011 Child 7 finished creating 2 symlinks.
 886 Sat Apr 30 20:33:13 2011 Child 13 verified 5793 files, created 1416
 887 Sat Apr 30 20:33:13 2011 Child 13 will create 2 symlinks.
 888 Sat Apr 30 20:33:13 2011 Child 13 finished creating 2 symlinks.
 889 Sat Apr 30 20:33:20 2011 Child 10 verified 5793 files, created 1416
 890 Sat Apr 30 20:33:20 2011 Child 10 will create 2 symlinks.
 891 Sat Apr 30 20:33:20 2011 Child 10 finished creating 2 symlinks.
 892 Sat Apr 30 20:33:28 2011 Child 3 verified 5793 files, created 1416
 893 Sat Apr 30 20:33:28 2011 Child 3 will create 2 symlinks.
 894 Sat Apr 30 20:33:28 2011 Child 3 finished creating 2 symlinks.
 895 Sat Apr 30 20:33:29 2011 Child 4 verified 5793 files, created 1416
 896 Sat Apr 30 20:33:29 2011 Child 4 will create 2 symlinks.
 897 Sat Apr 30 20:33:29 2011 Child 4 finished creating 2 symlinks.
 898 Sat Apr 30 20:33:33 2011 Child 5 verified 5793 files, created 1416
 899 Sat Apr 30 20:33:33 2011 Child 5 will create 2 symlinks.
 900 Sat Apr 30 20:33:33 2011 Child 5 finished creating 2 symlinks.
 901 Sat Apr 30 20:33:36 2011 Child 14 verified 4991 files (670 creates).
 902 Sat Apr 30 20:33:36 2011 Child 11 verified 4991 files (670 creates).
 903 Sat Apr 30 20:33:37 2011 Child 15 verified 5362 files (1041 creates).
 904 Sat Apr 30 20:33:37 2011 Child 8 verified 5793 files, created 1416
 905 Sat Apr 30 20:33:37 2011 Child 8 will create 2 symlinks.
 906 Sat Apr 30 20:33:37 2011 Child 8 finished creating 2 symlinks.
 907 Sat Apr 30 20:33:38 2011 Child 12 verified 5793 files, created 1416
 908 Sat Apr 30 20:33:38 2011 Child 12 will create 2 symlinks.
 909 Sat Apr 30 20:33:38 2011 Child 9 verified 5793 files, created 1416
 910 Sat Apr 30 20:33:38 2011 Child 9 will create 2 symlinks.
 911 Sat Apr 30 20:33:38 2011 Child 12 finished creating 2 symlinks.
 912 Sat Apr 30 20:33:38 2011 Child 1 verified 5793 files, created 1416
 913 Sat Apr 30 20:33:38 2011 Child 1 will create 2 symlinks.
 914 Sat Apr 30 20:33:38 2011 Child 9 finished creating 2 symlinks.
 915 Sat Apr 30 20:33:38 2011 Child 1 finished creating 2 symlinks.
 916 Sat Apr 30 20:33:45 2011 Child 15 verified 5793 files, created 1416
 917 Sat Apr 30 20:33:45 2011 Child 15 will create 2 symlinks.
 918 Sat Apr 30 20:33:45 2011 Child 15 finished creating 2 symlinks.
 919 Sat Apr 30 20:34:00 2011 Child 11 verified 5793 files, created 1416
 920 Sat Apr 30 20:34:00 2011 Child 11 will create 2 symlinks.
 921 Sat Apr 30 20:34:00 2011 Child 11 finished creating 2 symlinks.
 922 Sat Apr 30 20:34:00 2011 Child 14 verified 5793 files, created 1416
 923 Sat Apr 30 20:34:00 2011 Child 14 will create 2 symlinks.
 924 Sat Apr 30 20:34:00 2011 Child 14 finished creating 2 symlinks.
 925 Sat Apr 30 20:34:00 2011 Completed.
 926 Sat Apr 30 20:34:00 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 927 Sat Apr 30 20:34:00 2011 Completed.
 928 Sat Apr 30 20:34:00 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 929 Sat Apr 30 20:34:00 2011 Received.
 930 Sat Apr 30 20:34:00 2011 Performing 300 seconds pretest warmup.
 931 Sat Apr 30 20:39:00 2011 Completed.
 932 Sat Apr 30 20:39:00 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 933 Sat Apr 30 20:39:00 2011 Completed.
 934 Sat Apr 30 20:39:00 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 935 Sat Apr 30 20:39:01 2011 Received.
 936 Sat Apr 30 20:39:01 2011 Starting 300 seconds test run.
 937 SFS Single Client (c09-44) Results, Sat Apr 30 20:44:12 2011
 938 --------------------------------------------------------------------------------------
 939 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 940 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 941 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 942            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 943 --------------------------------------------------------------------------------------
 944 getattr     26.0%    25.9%    30879    30879      0      0.20     0.95      0.01  1.9%
 945 setattr      4.0%     4.0%     4781     4781      0      6.79    67.75      0.23  9.7%
 946 lookup      24.0%    23.8%    28411    28411      0      0.23     1.90      0.02  2.0%
 947 readlink     1.0%     1.0%     1223     1223      0      0.21     0.27      0.03  0.1%
 948 read        18.0%    18.1%    21599    26549      0      4.78    31.41      0.07 31.0%
 949 write       10.0%    10.1%    12039    14782      0      8.25    44.50      0.12 29.8%
 950 create       1.0%     1.0%     1162     1162      0     10.44    84.96      0.53  3.6%
 951 remove       1.0%     1.0%     1204     1204      0      7.77    62.93      0.45  2.8%
 952 readdir      1.0%     1.0%     1160     1160      0      0.42     0.10      0.02  0.1%
 953 fsstat       1.0%     1.0%     1250     1250      0      0.20     0.06      0.01  0.1%
 954 access      11.0%    11.0%    13076    13076      0      0.31    11.62      0.06  1.2%
 955 commit       0.0%     0.0%        0     5343      0      0.00     0.00      0.00 17.0%
 956 readdirplus  2.0%     2.0%     2385     2385      0      0.77     2.71      0.07  0.6%
 957 --------------------------------------------------------------------------------------
 958
 959       --------------------------------------------------
 960       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 961       --------------------------+-----------------------
 962 SFS NFS V3 THROUGHPUT:  395.91 Ops/Sec   AVG. RESPONSE TIME:    2.79 Msec/Op
 963 TCP PROTOCOL
 964 FAST CALLS: 0
 965 NFS V3 MIXFILE: [ SFS Default ]
 966 CLIENT REQUESTED LOAD: 400 Ops/Sec
 967 TOTAL LOGICAL NFS V3 OPERATIONS:  119169         TEST TIME: 301 Sec
 968 TOTAL PHYSICAL NFS V3 OPERATIONS: 132205
 969 PHYSICAL NFS V3 THROUGHPUT:  439.21 Ops/Sec
 970 FILE SET SIZE CREATED: 48248784.0 KB
 971 FILE SET SIZE ACCESSED: 14477184.0 - 14765993.0 KB  (100.00% to 101.99% of Base)
 972
 973 ------------------------------------------------------------------------
 974
 975 Sat Apr 30 20:44:12 2011 Completed.
 976
 977 Client Test Parameters:
 978         Number of processes = 16
 979         Requested Load (NFS V3 operations/second) = 400
 980         Maximum number of outstanding biod writes = 8
 981         Maximum number of outstanding biod reads = 8
 982         Warm-up time (seconds) = 300
 983         Run time (seconds) = 300
 984         File Set = 90864 Files created for I/O operations
 985                    27248 Files accessed for I/O operations
 986                    1824 Files for non-I/O operations
 987                      32 Symlinks
 988                    3024 Directories
 989                         Additional non-I/O files created as necessary
 990
 991
 992 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 993 Sat Apr 30 20:44:12 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 994 Sat Apr 30 20:44:12 2011 Completed.
 995 Sat Apr 30 20:44:12 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 996
 997 ************************************************************************
 998 Sat Apr 30 20:44:13 2011 Received.
 999 Sat Apr 30 20:44:13 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1000 Sat Apr 30 20:44:13 2011 Completed.
1001 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
1002 NFS V3 Protocol Version
1003
1004 ************************************************************************
1005 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1006 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1007
1008 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1009 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1010
1011 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 20:44:15 2011
1012         Client hostname = c09-44
1013         Prime Client hostname = c09-44.sysnet.ucsd.edu
1014
1015 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1016 NFS V3 Protocol
1017 Sat Apr 30 20:44:15 2011 Mounting 16 remote test directories.
1018 Child[000]: NFS negotiated block size 32768
1019 Child[001]: NFS negotiated block size 32768
1020 Child[002]: NFS negotiated block size 32768
1021 Child[003]: NFS negotiated block size 32768
1022 Child[004]: NFS negotiated block size 32768
1023 Child[005]: NFS negotiated block size 32768
1024 Child[006]: NFS negotiated block size 32768
1025 Child[007]: NFS negotiated block size 32768
1026 Child[008]: NFS negotiated block size 32768
1027 Child[009]: NFS negotiated block size 32768
1028 Child[010]: NFS negotiated block size 32768
1029 Child[011]: NFS negotiated block size 32768
1030 Child[012]: NFS negotiated block size 32768
1031 Child[013]: NFS negotiated block size 32768
1032 Child[014]: NFS negotiated block size 32768
1033 Child[015]: NFS negotiated block size 32768
1034 Sat Apr 30 20:44:29 2011 Completed.
1035 Sat Apr 30 20:44:29 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1036 Sat Apr 30 20:44:29 2011 Completed.
1037 Sat Apr 30 20:44:29 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1038 Sat Apr 30 20:44:31 2011 Received.
1039
1040 Sat Apr 30 20:44:31 2011 Initializing test directories.
1041 Sat Apr 30 20:44:31 2011 Child 15 will create 239 directories.
1042 Sat Apr 30 20:44:31 2011 Child 8 will create 239 directories.
1043 Sat Apr 30 20:44:31 2011 Child 7 will create 239 directories.
1044 Sat Apr 30 20:44:31 2011 Child 0 will create 239 directories.
1045 Sat Apr 30 20:44:31 2011 Child 12 will create 239 directories.
1046 Sat Apr 30 20:44:31 2011 Child 13 will create 239 directories.
1047 Sat Apr 30 20:44:31 2011 Child 14 will create 239 directories.
1048 Sat Apr 30 20:44:31 2011 Child 1 will create 239 directories.
1049 Sat Apr 30 20:44:31 2011 Child 11 will create 239 directories.
1050 Sat Apr 30 20:44:31 2011 Child 5 will create 239 directories.
1051 Sat Apr 30 20:44:31 2011 Child 4 will create 239 directories.
1052 Sat Apr 30 20:44:31 2011 Child 9 will create 239 directories.
1053 Sat Apr 30 20:44:31 2011 Child 6 will create 239 directories.
1054 Sat Apr 30 20:44:31 2011 Child 10 will create 239 directories.
1055 Sat Apr 30 20:44:31 2011 Child 3 will create 239 directories.
1056 Sat Apr 30 20:44:31 2011 Child 2 will create 239 directories.
1057 Sat Apr 30 20:44:32 2011 Child 2 finished creating 239 directories.
1058 Sat Apr 30 20:44:32 2011 Child 2 will create 7304 files.
1059 Sat Apr 30 20:44:33 2011 Child 6 finished creating 239 directories.
1060 Sat Apr 30 20:44:33 2011 Child 6 will create 7304 files.
1061 Sat Apr 30 20:44:33 2011 Child 3 finished creating 239 directories.
1062 Sat Apr 30 20:44:33 2011 Child 3 will create 7304 files.
1063 Sat Apr 30 20:44:36 2011 Child 10 finished creating 239 directories.
1064 Sat Apr 30 20:44:36 2011 Child 10 will create 7304 files.
1065 Sat Apr 30 20:44:37 2011 Child 12 finished creating 239 directories.
1066 Sat Apr 30 20:44:37 2011 Child 12 will create 7304 files.
1067 Sat Apr 30 20:44:40 2011 Child 15 finished creating 239 directories.
1068 Sat Apr 30 20:44:40 2011 Child 15 will create 7304 files.
1069 Sat Apr 30 20:44:40 2011 Child 9 finished creating 239 directories.
1070 Sat Apr 30 20:44:40 2011 Child 9 will create 7304 files.
1071 Sat Apr 30 20:44:42 2011 Child 0 finished creating 239 directories.
1072 Sat Apr 30 20:44:42 2011 Child 0 will create 7304 files.
1073 Sat Apr 30 20:44:42 2011 Child 4 finished creating 239 directories.
1074 Sat Apr 30 20:44:42 2011 Child 4 will create 7304 files.
1075 Sat Apr 30 20:44:42 2011 Child 11 finished creating 239 directories.
1076 Sat Apr 30 20:44:42 2011 Child 11 will create 7304 files.
1077 Sat Apr 30 20:44:43 2011 Child 1 finished creating 239 directories.
1078 Sat Apr 30 20:44:43 2011 Child 1 will create 7304 files.
1079 Sat Apr 30 20:44:46 2011 Child 7 finished creating 239 directories.
1080 Sat Apr 30 20:44:46 2011 Child 7 will create 7304 files.
1081 Sat Apr 30 20:44:46 2011 Child 13 finished creating 239 directories.
1082 Sat Apr 30 20:44:46 2011 Child 13 will create 7304 files.
1083 Sat Apr 30 20:44:47 2011 Child 5 finished creating 239 directories.
1084 Sat Apr 30 20:44:47 2011 Child 5 will create 7304 files.
1085 Sat Apr 30 20:44:49 2011 Child 14 finished creating 239 directories.
1086 Sat Apr 30 20:44:49 2011 Child 14 will create 7304 files.
1087 Sat Apr 30 20:44:52 2011 Child 8 finished creating 239 directories.
1088 Sat Apr 30 20:44:52 2011 Child 8 will create 7304 files.
1089 Sat Apr 30 20:50:44 2011 Child 5 verified 6267 files (588 creates).
1090 Sat Apr 30 20:50:44 2011 Child 7 verified 6198 files (519 creates).
1091 Sat Apr 30 20:50:44 2011 Child 2 verified 6712 files (1033 creates).
1092 Sat Apr 30 20:50:44 2011 Child 0 verified 6337 files (658 creates).
1093 Sat Apr 30 20:51:00 2011 Child 14 verified 6152 files (473 creates).
1094 Sat Apr 30 20:51:00 2011 Child 8 verified 6092 files (413 creates).
1095 Sat Apr 30 20:51:00 2011 Child 4 verified 6337 files (658 creates).
1096 Sat Apr 30 20:51:00 2011 Child 13 verified 6195 files (516 creates).
1097 Sat Apr 30 20:51:00 2011 Child 6 verified 6500 files (821 creates).
1098 Sat Apr 30 20:51:00 2011 Child 15 verified 6353 files (674 creates).
1099 Sat Apr 30 20:51:49 2011 Child 11 verified 6337 files (658 creates).
1100 Sat Apr 30 20:51:53 2011 Child 10 verified 6456 files (777 creates).
1101 Sat Apr 30 20:51:53 2011 Child 9 verified 6456 files (777 creates).
1102 Sat Apr 30 20:51:53 2011 Child 3 verified 6642 files (963 creates).
1103 Sat Apr 30 20:51:53 2011 Child 12 verified 6337 files (658 creates).
1104 Sat Apr 30 20:51:53 2011 Child 1 verified 6337 files (658 creates).
1105 Sat Apr 30 20:55:45 2011 Child 7 verified 6598 files (919 creates).
1106 Sat Apr 30 20:55:45 2011 Child 2 verified 7109 files (1430 creates).
1107 Sat Apr 30 20:55:49 2011 Child 0 verified 6543 files (864 creates).
1108 Sat Apr 30 20:55:51 2011 Child 5 verified 6543 files (864 creates).
1109 Sat Apr 30 20:55:55 2011 Child 2 verified 7304 files, created 1554
1110 Sat Apr 30 20:55:55 2011 Child 2 will create 2 symlinks.
1111 Sat Apr 30 20:55:55 2011 Child 2 finished creating 2 symlinks.
1112 Sat Apr 30 20:56:39 2011 Child 4 verified 6800 files (1121 creates).
1113 Sat Apr 30 20:56:40 2011 Child 13 verified 6638 files (959 creates).
1114 Sat Apr 30 20:56:42 2011 Child 15 verified 6811 files (1132 creates).
1115 Sat Apr 30 20:56:42 2011 Child 14 verified 6543 files (864 creates).
1116 Sat Apr 30 20:56:42 2011 Child 6 verified 7046 files (1367 creates).
1117 Sat Apr 30 20:56:54 2011 Child 10 verified 6799 files (1120 creates).
1118 Sat Apr 30 20:56:54 2011 Child 11 verified 6642 files (963 creates).
1119 Sat Apr 30 20:56:54 2011 Child 9 verified 6800 files (1121 creates).
1120 Sat Apr 30 20:56:54 2011 Child 12 verified 6643 files (964 creates).
1121 Sat Apr 30 20:56:54 2011 Child 1 verified 6657 files (978 creates).
1122 Sat Apr 30 20:56:57 2011 Child 6 verified 7304 files, created 1554
1123 Sat Apr 30 20:56:57 2011 Child 6 will create 2 symlinks.
1124 Sat Apr 30 20:56:58 2011 Child 6 finished creating 2 symlinks.
1125 Sat Apr 30 20:57:20 2011 Child 4 verified 7304 files, created 1554
1126 Sat Apr 30 20:57:20 2011 Child 4 will create 2 symlinks.
1127 Sat Apr 30 20:57:20 2011 Child 4 finished creating 2 symlinks.
1128 Sat Apr 30 20:57:21 2011 Child 15 verified 7304 files, created 1554
1129 Sat Apr 30 20:57:21 2011 Child 15 will create 2 symlinks.
1130 Sat Apr 30 20:57:21 2011 Child 15 finished creating 2 symlinks.
1131 Sat Apr 30 20:57:43 2011 Child 13 verified 7304 files, created 1554
1132 Sat Apr 30 20:57:43 2011 Child 13 will create 2 symlinks.
1133 Sat Apr 30 20:57:43 2011 Child 13 finished creating 2 symlinks.
1134 Sat Apr 30 20:57:43 2011 Child 3 verified 7036 files (1357 creates).
1135 Sat Apr 30 20:57:43 2011 Child 8 verified 6543 files (864 creates).
1136 Sat Apr 30 20:57:46 2011 Child 0 verified 7304 files, created 1554
1137 Sat Apr 30 20:57:46 2011 Child 0 will create 2 symlinks.
1138 Sat Apr 30 20:57:46 2011 Child 0 finished creating 2 symlinks.
1139 Sat Apr 30 20:57:46 2011 Child 5 verified 7304 files, created 1554
1140 Sat Apr 30 20:57:46 2011 Child 5 will create 2 symlinks.
1141 Sat Apr 30 20:57:46 2011 Child 5 finished creating 2 symlinks.
1142 Sat Apr 30 20:57:46 2011 Child 10 verified 7304 files, created 1554
1143 Sat Apr 30 20:57:46 2011 Child 10 will create 2 symlinks.
1144 Sat Apr 30 20:57:46 2011 Child 10 finished creating 2 symlinks.
1145 Sat Apr 30 20:57:48 2011 Child 1 verified 7304 files, created 1554
1146 Sat Apr 30 20:57:48 2011 Child 1 will create 2 symlinks.
1147 Sat Apr 30 20:57:49 2011 Child 1 finished creating 2 symlinks.
1148 Sat Apr 30 20:57:49 2011 Child 11 verified 7304 files, created 1554
1149 Sat Apr 30 20:57:49 2011 Child 11 will create 2 symlinks.
1150 Sat Apr 30 20:57:50 2011 Child 11 finished creating 2 symlinks.
1151 Sat Apr 30 20:57:50 2011 Child 12 verified 7304 files, created 1554
1152 Sat Apr 30 20:57:50 2011 Child 12 will create 2 symlinks.
1153 Sat Apr 30 20:57:50 2011 Child 12 finished creating 2 symlinks.
1154 Sat Apr 30 20:57:52 2011 Child 3 verified 7304 files, created 1554
1155 Sat Apr 30 20:57:52 2011 Child 3 will create 2 symlinks.
1156 Sat Apr 30 20:57:52 2011 Child 3 finished creating 2 symlinks.
1157 Sat Apr 30 20:57:56 2011 Child 9 verified 7304 files, created 1554
1158 Sat Apr 30 20:57:56 2011 Child 9 will create 2 symlinks.
1159 Sat Apr 30 20:57:56 2011 Child 9 finished creating 2 symlinks.
1160 Sat Apr 30 20:58:06 2011 Child 7 verified 7304 files, created 1554
1161 Sat Apr 30 20:58:06 2011 Child 7 will create 2 symlinks.
1162 Sat Apr 30 20:58:06 2011 Child 7 finished creating 2 symlinks.
1163 Sat Apr 30 20:58:09 2011 Child 8 verified 7304 files, created 1554
1164 Sat Apr 30 20:58:09 2011 Child 8 will create 2 symlinks.
1165 Sat Apr 30 20:58:09 2011 Child 8 finished creating 2 symlinks.
1166 Sat Apr 30 20:58:11 2011 Child 14 verified 7304 files, created 1554
1167 Sat Apr 30 20:58:11 2011 Child 14 will create 2 symlinks.
1168 Sat Apr 30 20:58:11 2011 Child 14 finished creating 2 symlinks.
1169 Sat Apr 30 20:58:11 2011 Completed.
1170 Sat Apr 30 20:58:11 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1171 Sat Apr 30 20:58:11 2011 Completed.
1172 Sat Apr 30 20:58:11 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1173 Sat Apr 30 20:58:11 2011 Received.
1174 Sat Apr 30 20:58:11 2011 Performing 300 seconds pretest warmup.
1175 Sat Apr 30 21:03:11 2011 Completed.
1176 Sat Apr 30 21:03:11 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1177 Sat Apr 30 21:03:11 2011 Completed.
1178 Sat Apr 30 21:03:11 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1179 Sat Apr 30 21:03:12 2011 Received.
1180 Sat Apr 30 21:03:12 2011 Starting 300 seconds test run.
1181 SFS Single Client (c09-44) Results, Sat Apr 30 21:08:22 2011
1182 --------------------------------------------------------------------------------------
1183 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1184 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1185 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1186            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1187 --------------------------------------------------------------------------------------
1188 getattr     26.0%    25.8%    36899    36899      0      0.41     2.43      0.02  1.8%
1189 setattr      4.0%     4.0%     5703     5703      0     11.79    33.36      0.15  8.0%
1190 lookup      24.0%    23.9%    34236    34236      0      2.24   162.84      0.14  9.2%
1191 readlink     1.0%     1.0%     1471     1471      0      0.46     3.55      0.10  0.1%
1192 read        18.0%    18.2%    26032    31980      0     11.11    31.14      0.07 34.6%
1193 write       10.0%    10.0%    14360    17438      0     17.15   176.45      0.22 29.5%
1194 create       1.0%     1.0%     1369     1369      0     21.35    65.97      0.43  3.5%
1195 remove       1.0%     1.0%     1433     1433      0     13.82    24.71      0.26  2.4%
1196 readdir      1.0%     1.0%     1369     1369      0      0.62     1.88      0.07  0.1%
1197 fsstat       1.0%     1.0%     1438     1438      0      0.43     2.14      0.08  0.1%
1198 access      11.0%    11.0%    15727    15727      0      0.41     2.27      0.02  0.8%
1199 commit       0.0%     0.0%        0     5921      0      0.00     0.00      0.00  9.6%
1200 readdirplus  2.0%     2.0%     2923     2923      0      1.01     2.57      0.06  0.4%
1201 --------------------------------------------------------------------------------------
1202
1203       --------------------------------------------------
1204       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1205       --------------------------+-----------------------
1206 SFS NFS V3 THROUGHPUT:  501.61 Ops/Sec   AVG. RESPONSE TIME:    5.84 Msec/Op
1207 TCP PROTOCOL
1208 FAST CALLS: 0
1209 NFS V3 MIXFILE: [ SFS Default ]
1210 CLIENT REQUESTED LOAD: 500 Ops/Sec
1211 TOTAL LOGICAL NFS V3 OPERATIONS:  142960         TEST TIME: 285 Sec
1212 TOTAL PHYSICAL NFS V3 OPERATIONS: 157907
1213 PHYSICAL NFS V3 THROUGHPUT:  554.05 Ops/Sec
1214 FILE SET SIZE CREATED: 60831360.0 KB
1215 FILE SET SIZE ACCESSED: 18257904.0 - 18538698.0 KB  (100.00% to 101.54% of Base)
1216
1217 ------------------------------------------------------------------------
1218
1219 Sat Apr 30 21:08:22 2011 Completed.
1220
1221 Client Test Parameters:
1222         Number of processes = 16
1223         Requested Load (NFS V3 operations/second) = 500
1224         Maximum number of outstanding biod writes = 8
1225         Maximum number of outstanding biod reads = 8
1226         Warm-up time (seconds) = 300
1227         Run time (seconds) = 300
1228         File Set = 114560 Files created for I/O operations
1229                    34368 Files accessed for I/O operations
1230                    2304 Files for non-I/O operations
1231                      32 Symlinks
1232                    3808 Directories
1233                         Additional non-I/O files created as necessary
1234
1235
1236 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1237 Sat Apr 30 21:08:22 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1238 Sat Apr 30 21:08:22 2011 Completed.
1239 Sat Apr 30 21:08:22 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1240
1241 ************************************************************************
1242 Sat Apr 30 21:08:23 2011 Received.
1243 Sat Apr 30 21:08:23 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1244 Sat Apr 30 21:08:23 2011 Completed.
1245 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
1246 NFS V3 Protocol Version
1247
1248 ************************************************************************
1249 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1250 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1251
1252 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1253 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1254
1255 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 21:08:25 2011
1256         Client hostname = c09-44
1257         Prime Client hostname = c09-44.sysnet.ucsd.edu
1258
1259 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1260 NFS V3 Protocol
1261 Sat Apr 30 21:08:25 2011 Mounting 16 remote test directories.
1262 Child[000]: NFS negotiated block size 32768
1263 Child[001]: NFS negotiated block size 32768
1264 Child[002]: NFS negotiated block size 32768
1265 Child[003]: NFS negotiated block size 32768
1266 Child[004]: NFS negotiated block size 32768
1267 Child[005]: NFS negotiated block size 32768
1268 Child[006]: NFS negotiated block size 32768
1269 Child[007]: NFS negotiated block size 32768
1270 Child[008]: NFS negotiated block size 32768
1271 Child[009]: NFS negotiated block size 32768
1272 Child[010]: NFS negotiated block size 32768
1273 Child[011]: NFS negotiated block size 32768
1274 Child[012]: NFS negotiated block size 32768
1275 Child[013]: NFS negotiated block size 32768
1276 Child[014]: NFS negotiated block size 32768
1277 Child[015]: NFS negotiated block size 32768
1278 Sat Apr 30 21:08:39 2011 Completed.
1279 Sat Apr 30 21:08:39 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1280 Sat Apr 30 21:08:39 2011 Completed.
1281 Sat Apr 30 21:08:39 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1282 Sat Apr 30 21:08:41 2011 Received.
1283
1284 Sat Apr 30 21:08:41 2011 Initializing test directories.
1285 Sat Apr 30 21:08:41 2011 Child 7 will create 289 directories.
1286 Sat Apr 30 21:08:41 2011 Child 5 will create 289 directories.
1287 Sat Apr 30 21:08:41 2011 Child 14 will create 289 directories.
1288 Sat Apr 30 21:08:41 2011 Child 1 will create 289 directories.
1289 Sat Apr 30 21:08:41 2011 Child 4 will create 289 directories.
1290 Sat Apr 30 21:08:41 2011 Child 0 will create 289 directories.
1291 Sat Apr 30 21:08:41 2011 Child 13 will create 289 directories.
1292 Sat Apr 30 21:08:41 2011 Child 2 will create 289 directories.
1293 Sat Apr 30 21:08:41 2011 Child 15 will create 289 directories.
1294 Sat Apr 30 21:08:41 2011 Child 3 will create 289 directories.
1295 Sat Apr 30 21:08:41 2011 Child 12 will create 289 directories.
1296 Sat Apr 30 21:08:41 2011 Child 6 will create 289 directories.
1297 Sat Apr 30 21:08:41 2011 Child 11 will create 289 directories.
1298 Sat Apr 30 21:08:41 2011 Child 8 will create 289 directories.
1299 Sat Apr 30 21:08:41 2011 Child 10 will create 289 directories.
1300 Sat Apr 30 21:08:41 2011 Child 9 will create 289 directories.
1301 Sat Apr 30 21:08:45 2011 Child 2 finished creating 289 directories.
1302 Sat Apr 30 21:08:45 2011 Child 2 will create 8814 files.
1303 Sat Apr 30 21:08:47 2011 Child 13 finished creating 289 directories.
1304 Sat Apr 30 21:08:47 2011 Child 13 will create 8814 files.
1305 Sat Apr 30 21:08:48 2011 Child 3 finished creating 289 directories.
1306 Sat Apr 30 21:08:48 2011 Child 3 will create 8814 files.
1307 Sat Apr 30 21:08:49 2011 Child 9 finished creating 289 directories.
1308 Sat Apr 30 21:08:49 2011 Child 9 will create 8814 files.
1309 Sat Apr 30 21:08:52 2011 Child 5 finished creating 289 directories.
1310 Sat Apr 30 21:08:52 2011 Child 5 will create 8814 files.
1311 Sat Apr 30 21:08:54 2011 Child 10 finished creating 289 directories.
1312 Sat Apr 30 21:08:54 2011 Child 10 will create 8814 files.
1313 Sat Apr 30 21:08:54 2011 Child 14 finished creating 289 directories.
1314 Sat Apr 30 21:08:54 2011 Child 14 will create 8814 files.
1315 Sat Apr 30 21:08:58 2011 Child 15 finished creating 289 directories.
1316 Sat Apr 30 21:08:58 2011 Child 15 will create 8814 files.
1317 Sat Apr 30 21:08:58 2011 Child 8 finished creating 289 directories.
1318 Sat Apr 30 21:08:58 2011 Child 8 will create 8814 files.
1319 Sat Apr 30 21:09:00 2011 Child 12 finished creating 289 directories.
1320 Sat Apr 30 21:09:00 2011 Child 12 will create 8814 files.
1321 Sat Apr 30 21:09:01 2011 Child 1 finished creating 289 directories.
1322 Sat Apr 30 21:09:01 2011 Child 1 will create 8814 files.
1323 Sat Apr 30 21:09:02 2011 Child 7 finished creating 289 directories.
1324 Sat Apr 30 21:09:02 2011 Child 7 will create 8814 files.
1325 Sat Apr 30 21:09:02 2011 Child 4 finished creating 289 directories.
1326 Sat Apr 30 21:09:02 2011 Child 4 will create 8814 files.
1327 Sat Apr 30 21:09:02 2011 Child 6 finished creating 289 directories.
1328 Sat Apr 30 21:09:02 2011 Child 6 will create 8814 files.
1329 Sat Apr 30 21:09:06 2011 Child 0 finished creating 289 directories.
1330 Sat Apr 30 21:09:06 2011 Child 0 will create 8814 files.
1331 Sat Apr 30 21:09:06 2011 Child 11 finished creating 289 directories.
1332 Sat Apr 30 21:09:06 2011 Child 11 will create 8814 files.
1333 Sat Apr 30 21:13:47 2011 Child 2 verified 8357 files (1197 creates).
1334 Sat Apr 30 21:13:51 2011 Child 13 verified 8229 files (1069 creates).
1335 Sat Apr 30 21:13:51 2011 Child 3 verified 8104 files (944 creates).
1336 Sat Apr 30 21:13:51 2011 Child 9 verified 8100 files (940 creates).
1337 Sat Apr 30 21:13:55 2011 Child 5 verified 7877 files (717 creates).
1338 Sat Apr 30 21:13:56 2011 Child 10 verified 7861 files (701 creates).
1339 Sat Apr 30 21:13:56 2011 Child 14 verified 7806 files (646 creates).
1340 Sat Apr 30 21:13:59 2011 Child 15 verified 7708 files (548 creates).
1341 Sat Apr 30 21:14:00 2011 Child 8 verified 7724 files (564 creates).
1342 Sat Apr 30 21:14:02 2011 Child 12 verified 7645 files (485 creates).
1343 Sat Apr 30 21:14:02 2011 Child 1 verified 7654 files (494 creates).
1344 Sat Apr 30 21:14:03 2011 Child 7 verified 7668 files (508 creates).
1345 Sat Apr 30 21:14:03 2011 Child 4 verified 7673 files (513 creates).
1346 Sat Apr 30 21:14:04 2011 Child 6 verified 7669 files (509 creates).
1347 Sat Apr 30 21:14:07 2011 Child 0 verified 7685 files (525 creates).
1348 Sat Apr 30 21:14:09 2011 Child 11 verified 7716 files (556 creates).
1349 Sat Apr 30 21:14:39 2011 Child 2 verified 8814 files, created 1568
1350 Sat Apr 30 21:14:39 2011 Child 2 will create 2 symlinks.
1351 Sat Apr 30 21:14:39 2011 Child 2 finished creating 2 symlinks.
1352 Sat Apr 30 21:15:12 2011 Child 13 verified 8814 files, created 1568
1353 Sat Apr 30 21:15:12 2011 Child 13 will create 2 symlinks.
1354 Sat Apr 30 21:15:12 2011 Child 13 finished creating 2 symlinks.
1355 Sat Apr 30 21:15:43 2011 Child 3 verified 8814 files, created 1568
1356 Sat Apr 30 21:15:43 2011 Child 3 will create 2 symlinks.
1357 Sat Apr 30 21:15:43 2011 Child 3 finished creating 2 symlinks.
1358 Sat Apr 30 21:20:34 2011 Child 5 verified 8415 files (1255 creates).
1359 Sat Apr 30 21:20:55 2011 Child 11 verified 8233 files (1073 creates).
1360 Sat Apr 30 21:20:55 2011 Child 12 verified 8233 files (1073 creates).
1361 Sat Apr 30 21:20:55 2011 Child 7 verified 8233 files (1073 creates).
1362 Sat Apr 30 21:20:55 2011 Child 9 verified 8613 files (1453 creates).
1363 Sat Apr 30 21:20:55 2011 Child 14 verified 8357 files (1197 creates).
1364 Sat Apr 30 21:20:55 2011 Child 10 verified 8385 files (1225 creates).
1365 Sat Apr 30 21:20:55 2011 Child 8 verified 8286 files (1126 creates).
1366 Sat Apr 30 21:20:55 2011 Child 4 verified 8233 files (1073 creates).
1367 Sat Apr 30 21:20:55 2011 Child 1 verified 8233 files (1073 creates).
1368 Sat Apr 30 21:20:55 2011 Child 15 verified 8286 files (1126 creates).
1369 Sat Apr 30 21:20:55 2011 Child 6 verified 8233 files (1073 creates).
1370 Sat Apr 30 21:20:55 2011 Child 0 verified 8233 files (1073 creates).
1371 Sat Apr 30 21:21:02 2011 Child 9 verified 8814 files, created 1568
1372 Sat Apr 30 21:21:02 2011 Child 9 will create 2 symlinks.
1373 Sat Apr 30 21:21:10 2011 Child 9 finished creating 2 symlinks.
1374 Sat Apr 30 21:23:20 2011 Child 14 verified 8814 files, created 1568
1375 Sat Apr 30 21:23:20 2011 Child 14 will create 2 symlinks.
1376 Sat Apr 30 21:23:20 2011 Child 10 verified 8814 files, created 1568
1377 Sat Apr 30 21:23:20 2011 Child 10 will create 2 symlinks.
1378 Sat Apr 30 21:23:20 2011 Child 5 verified 8814 files, created 1568
1379 Sat Apr 30 21:23:20 2011 Child 5 will create 2 symlinks.
1380 Sat Apr 30 21:23:20 2011 Child 10 finished creating 2 symlinks.
1381 Sat Apr 30 21:23:20 2011 Child 14 finished creating 2 symlinks.
1382 Sat Apr 30 21:23:20 2011 Child 5 finished creating 2 symlinks.
1383 Sat Apr 30 21:23:34 2011 Child 15 verified 8814 files, created 1568
1384 Sat Apr 30 21:23:34 2011 Child 15 will create 2 symlinks.
1385 Sat Apr 30 21:23:34 2011 Child 15 finished creating 2 symlinks.
1386 Sat Apr 30 21:23:34 2011 Child 11 verified 8814 files, created 1568
1387 Sat Apr 30 21:23:34 2011 Child 11 will create 2 symlinks.
1388 Sat Apr 30 21:23:34 2011 Child 8 verified 8814 files, created 1568
1389 Sat Apr 30 21:23:34 2011 Child 8 will create 2 symlinks.
1390 Sat Apr 30 21:23:34 2011 Child 11 finished creating 2 symlinks.
1391 Sat Apr 30 21:23:34 2011 Child 8 finished creating 2 symlinks.
1392 Sat Apr 30 21:23:43 2011 Child 12 verified 8814 files, created 1568
1393 Sat Apr 30 21:23:43 2011 Child 12 will create 2 symlinks.
1394 Sat Apr 30 21:23:43 2011 Child 12 finished creating 2 symlinks.
1395 Sat Apr 30 21:23:43 2011 Child 4 verified 8814 files, created 1568
1396 Sat Apr 30 21:23:43 2011 Child 4 will create 2 symlinks.
1397 Sat Apr 30 21:23:48 2011 Child 7 verified 8814 files, created 1568
1398 Sat Apr 30 21:23:48 2011 Child 7 will create 2 symlinks.
1399 Sat Apr 30 21:23:48 2011 Child 0 verified 8814 files, created 1568
1400 Sat Apr 30 21:23:48 2011 Child 0 will create 2 symlinks.
1401 Sat Apr 30 21:23:48 2011 Child 7 finished creating 2 symlinks.
1402 Sat Apr 30 21:23:48 2011 Child 6 verified 8814 files, created 1568
1403 Sat Apr 30 21:23:48 2011 Child 6 will create 2 symlinks.
1404 Sat Apr 30 21:23:48 2011 Child 1 verified 8814 files, created 1568
1405 Sat Apr 30 21:23:48 2011 Child 1 will create 2 symlinks.
1406 Sat Apr 30 21:23:48 2011 Child 0 finished creating 2 symlinks.
1407 Sat Apr 30 21:23:48 2011 Child 6 finished creating 2 symlinks.
1408 Sat Apr 30 21:23:48 2011 Child 1 finished creating 2 symlinks.
1409 Sat Apr 30 21:23:50 2011 Child 4 finished creating 2 symlinks.
1410 Sat Apr 30 21:23:50 2011 Completed.
1411 Sat Apr 30 21:23:50 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1412 Sat Apr 30 21:23:50 2011 Completed.
1413 Sat Apr 30 21:23:50 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1414 Sat Apr 30 21:23:50 2011 Received.
1415 Sat Apr 30 21:23:50 2011 Performing 300 seconds pretest warmup.
1416 Sat Apr 30 21:28:50 2011 Completed.
1417 Sat Apr 30 21:28:50 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1418 Sat Apr 30 21:28:50 2011 Completed.
1419 Sat Apr 30 21:28:50 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1420 Sat Apr 30 21:28:51 2011 Received.
1421 Sat Apr 30 21:28:51 2011 Starting 300 seconds test run.
1422 SFS Single Client (c09-44) Results, Sat Apr 30 21:34:01 2011
1423 --------------------------------------------------------------------------------------
1424 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1425 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1426 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1427            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1428 --------------------------------------------------------------------------------------
1429 getattr     26.0%    26.1%    47120    47120      0      0.40     2.55      0.01  1.8%
1430 setattr      4.0%     4.0%     7224     7224      0     13.06    25.31      0.12  8.9%
1431 lookup      24.0%    23.8%    43038    43038      0      0.38     2.57      0.02  1.6%
1432 readlink     1.0%     1.0%     1829     1829      0      0.38     2.67      0.07  0.1%
1433 read        18.0%    18.1%    32621    40280      0     12.84    40.81      0.07 39.7%
1434 write       10.0%    10.0%    18079    22003      0     17.19    31.39      0.08 29.4%
1435 create       1.0%     1.0%     1840     1840      0     21.59    34.04      0.27  3.8%
1436 remove       1.0%     1.0%     1864     1864      0     15.41    25.12      0.23  2.7%
1437 readdir      1.0%     1.0%     1795     1795      0      0.68     2.70      0.08  0.1%
1438 fsstat       1.0%     1.0%     1771     1771      0      0.46     2.92      0.08  0.1%
1439 access      11.0%    10.9%    19673    19673      0      0.39     2.58      0.02  0.7%
1440 commit       0.0%     0.0%        0     7470      0      0.00     0.00      0.00 10.8%
1441 readdirplus  2.0%     2.1%     3780     3780      0      1.07     5.07      0.07  0.4%
1442 --------------------------------------------------------------------------------------
1443
1444       --------------------------------------------------
1445       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1446       --------------------------+-----------------------
1447 SFS NFS V3 THROUGHPUT:  604.12 Ops/Sec   AVG. RESPONSE TIME:    5.84 Msec/Op
1448 TCP PROTOCOL
1449 FAST CALLS: 0
1450 NFS V3 MIXFILE: [ SFS Default ]
1451 CLIENT REQUESTED LOAD: 600 Ops/Sec
1452 TOTAL LOGICAL NFS V3 OPERATIONS:  180634         TEST TIME: 299 Sec
1453 TOTAL PHYSICAL NFS V3 OPERATIONS: 199687
1454 PHYSICAL NFS V3 THROUGHPUT:  667.84 Ops/Sec
1455 FILE SET SIZE CREATED: 73413936.0 KB
1456 FILE SET SIZE ACCESSED: 22030128.0 - 22378674.0 KB  (100.00% to 101.58% of Base)
1457
1458 ------------------------------------------------------------------------
1459
1460 Sat Apr 30 21:34:01 2011 Completed.
1461
1462 Client Test Parameters:
1463         Number of processes = 16
1464         Requested Load (NFS V3 operations/second) = 600
1465         Maximum number of outstanding biod writes = 8
1466         Maximum number of outstanding biod reads = 8
1467         Warm-up time (seconds) = 300
1468         Run time (seconds) = 300
1469         File Set = 138256 Files created for I/O operations
1470                    41472 Files accessed for I/O operations
1471                    2768 Files for non-I/O operations
1472                      32 Symlinks
1473                    4608 Directories
1474                         Additional non-I/O files created as necessary
1475
1476
1477 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1478 Sat Apr 30 21:34:01 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1479 Sat Apr 30 21:34:01 2011 Completed.
1480 Sat Apr 30 21:34:01 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1481
1482 ************************************************************************
1483 Sat Apr 30 21:34:02 2011 Received.
1484 Sat Apr 30 21:34:02 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1485 Sat Apr 30 21:34:02 2011 Completed.
1486 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
1487 NFS V3 Protocol Version
1488
1489 ************************************************************************
1490 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1491 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1492
1493 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1494 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1495
1496 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 21:34:04 2011
1497         Client hostname = c09-44
1498         Prime Client hostname = c09-44.sysnet.ucsd.edu
1499
1500 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1501 NFS V3 Protocol
1502 Sat Apr 30 21:34:04 2011 Mounting 16 remote test directories.
1503 Child[000]: NFS negotiated block size 32768
1504 Child[001]: NFS negotiated block size 32768
1505 Child[002]: NFS negotiated block size 32768
1506 Child[003]: NFS negotiated block size 32768
1507 Child[004]: NFS negotiated block size 32768
1508 Child[005]: NFS negotiated block size 32768
1509 Child[006]: NFS negotiated block size 32768
1510 Child[007]: NFS negotiated block size 32768
1511 Child[008]: NFS negotiated block size 32768
1512 Child[009]: NFS negotiated block size 32768
1513 Child[010]: NFS negotiated block size 32768
1514 Child[011]: NFS negotiated block size 32768
1515 Child[012]: NFS negotiated block size 32768
1516 Child[013]: NFS negotiated block size 32768
1517 Child[014]: NFS negotiated block size 32768
1518 Child[015]: NFS negotiated block size 32768
1519 Sat Apr 30 21:34:18 2011 Completed.
1520 Sat Apr 30 21:34:18 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1521 Sat Apr 30 21:34:18 2011 Completed.
1522 Sat Apr 30 21:34:18 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1523 Sat Apr 30 21:34:20 2011 Received.
1524
1525 Sat Apr 30 21:34:20 2011 Initializing test directories.
1526 Sat Apr 30 21:34:20 2011 Child 14 will create 338 directories.
1527 Sat Apr 30 21:34:20 2011 Child 12 will create 338 directories.
1528 Sat Apr 30 21:34:20 2011 Child 6 will create 338 directories.
1529 Sat Apr 30 21:34:20 2011 Child 1 will create 338 directories.
1530 Sat Apr 30 21:34:20 2011 Child 8 will create 338 directories.
1531 Sat Apr 30 21:34:20 2011 Child 7 will create 338 directories.
1532 Sat Apr 30 21:34:20 2011 Child 2 will create 338 directories.
1533 Sat Apr 30 21:34:20 2011 Child 9 will create 338 directories.
1534 Sat Apr 30 21:34:20 2011 Child 5 will create 338 directories.
1535 Sat Apr 30 21:34:20 2011 Child 3 will create 338 directories.
1536 Sat Apr 30 21:34:20 2011 Child 13 will create 338 directories.
1537 Sat Apr 30 21:34:20 2011 Child 11 will create 338 directories.
1538 Sat Apr 30 21:34:20 2011 Child 15 will create 338 directories.
1539 Sat Apr 30 21:34:20 2011 Child 4 will create 338 directories.
1540 Sat Apr 30 21:34:20 2011 Child 0 will create 338 directories.
1541 Sat Apr 30 21:34:20 2011 Child 10 will create 338 directories.
1542 Sat Apr 30 21:34:25 2011 Child 8 finished creating 338 directories.
1543 Sat Apr 30 21:34:25 2011 Child 8 will create 10325 files.
1544 Sat Apr 30 21:34:25 2011 Child 0 finished creating 338 directories.
1545 Sat Apr 30 21:34:25 2011 Child 0 will create 10325 files.
1546 Sat Apr 30 21:34:25 2011 Child 2 finished creating 338 directories.
1547 Sat Apr 30 21:34:25 2011 Child 2 will create 10325 files.
1548 Sat Apr 30 21:34:27 2011 Child 5 finished creating 338 directories.
1549 Sat Apr 30 21:34:27 2011 Child 5 will create 10325 files.
1550 Sat Apr 30 21:34:29 2011 Child 6 finished creating 338 directories.
1551 Sat Apr 30 21:34:29 2011 Child 6 will create 10325 files.
1552 Sat Apr 30 21:34:30 2011 Child 4 finished creating 338 directories.
1553 Sat Apr 30 21:34:30 2011 Child 4 will create 10325 files.
1554 Sat Apr 30 21:34:32 2011 Child 11 finished creating 338 directories.
1555 Sat Apr 30 21:34:32 2011 Child 11 will create 10325 files.
1556 Sat Apr 30 21:34:32 2011 Child 10 finished creating 338 directories.
1557 Sat Apr 30 21:34:32 2011 Child 10 will create 10325 files.
1558 Sat Apr 30 21:34:32 2011 Child 7 finished creating 338 directories.
1559 Sat Apr 30 21:34:32 2011 Child 7 will create 10325 files.
1560 Sat Apr 30 21:34:35 2011 Child 13 finished creating 338 directories.
1561 Sat Apr 30 21:34:35 2011 Child 13 will create 10325 files.
1562 Sat Apr 30 21:34:36 2011 Child 14 finished creating 338 directories.
1563 Sat Apr 30 21:34:36 2011 Child 14 will create 10325 files.
1564 Sat Apr 30 21:34:37 2011 Child 9 finished creating 338 directories.
1565 Sat Apr 30 21:34:37 2011 Child 9 will create 10325 files.
1566 Sat Apr 30 21:34:43 2011 Child 12 finished creating 338 directories.
1567 Sat Apr 30 21:34:43 2011 Child 12 will create 10325 files.
1568 Sat Apr 30 21:34:43 2011 Child 15 finished creating 338 directories.
1569 Sat Apr 30 21:34:43 2011 Child 15 will create 10325 files.
1570 Sat Apr 30 21:34:44 2011 Child 1 finished creating 338 directories.
1571 Sat Apr 30 21:34:44 2011 Child 1 will create 10325 files.
1572 Sat Apr 30 21:34:47 2011 Child 3 finished creating 338 directories.
1573 Sat Apr 30 21:34:47 2011 Child 3 will create 10325 files.
1574 Sat Apr 30 21:39:38 2011 Child 4 verified 9302 files (661 creates).
1575 Sat Apr 30 21:39:38 2011 Child 11 verified 9152 files (511 creates).
1576 Sat Apr 30 21:39:40 2011 Child 7 verified 9037 files (396 creates).
1577 Sat Apr 30 21:39:40 2011 Child 8 verified 9508 files (867 creates).
1578 Sat Apr 30 21:39:54 2011 Child 10 verified 9037 files (396 creates).
1579 Sat Apr 30 21:39:54 2011 Child 13 verified 9037 files (396 creates).
1580 Sat Apr 30 21:39:54 2011 Child 15 verified 8720 files (79 creates).
1581 Sat Apr 30 21:39:55 2011 Child 6 verified 9252 files (611 creates).
1582 Sat Apr 30 21:40:05 2011 Child 1 verified 8926 files (285 creates).
1583 Sat Apr 30 21:40:05 2011 Child 3 verified 8717 files (76 creates).
1584 Sat Apr 30 21:40:05 2011 Child 9 verified 8872 files (231 creates).
1585 Sat Apr 30 21:40:05 2011 Child 5 verified 9369 files (728 creates).
1586 Sat Apr 30 21:40:05 2011 Child 2 verified 9508 files (867 creates).
1587 Sat Apr 30 21:41:01 2011 Child 14 verified 9037 files (396 creates).
1588 Sat Apr 30 21:41:01 2011 Child 12 verified 8872 files (231 creates).
1589 Sat Apr 30 21:41:01 2011 Child 0 verified 9653 files (1012 creates).
1590 Sat Apr 30 21:44:36 2011 Child 11 verified 9761 files (1120 creates).
1591 Sat Apr 30 21:44:36 2011 Child 4 verified 9818 files (1177 creates).
1592 Sat Apr 30 21:44:41 2011 Child 7 verified 9581 files (940 creates).
1593 Sat Apr 30 21:44:41 2011 Child 8 verified 10047 files (1406 creates).
1594 Sat Apr 30 21:44:56 2011 Child 15 verified 9565 files (924 creates).
1595 Sat Apr 30 21:44:56 2011 Child 10 verified 9508 files (867 creates).
1596 Sat Apr 30 21:44:56 2011 Child 6 verified 9751 files (1110 creates).
1597 Sat Apr 30 21:44:56 2011 Child 13 verified 9819 files (1178 creates).
1598 Sat Apr 30 21:44:57 2011 Child 8 verified 10325 files, created 1583
1599 Sat Apr 30 21:44:57 2011 Child 8 will create 2 symlinks.
1600 Sat Apr 30 21:44:59 2011 Child 0 verified 10325 files, created 1583
1601 Sat Apr 30 21:44:59 2011 Child 0 will create 2 symlinks.
1602 Sat Apr 30 21:44:59 2011 Child 0 finished creating 2 symlinks.
1603 Sat Apr 30 21:45:00 2011 Child 2 verified 10325 files, created 1583
1604 Sat Apr 30 21:45:00 2011 Child 2 will create 2 symlinks.
1605 Sat Apr 30 21:45:00 2011 Child 2 finished creating 2 symlinks.
1606 Sat Apr 30 21:45:00 2011 Child 8 finished creating 2 symlinks.
1607 Sat Apr 30 21:45:05 2011 Child 3 verified 9369 files (728 creates).
1608 Sat Apr 30 21:45:05 2011 Child 1 verified 9762 files (1121 creates).
1609 Sat Apr 30 21:45:06 2011 Child 9 verified 9673 files (1032 creates).
1610 Sat Apr 30 21:45:09 2011 Child 5 verified 10325 files, created 1583
1611 Sat Apr 30 21:45:09 2011 Child 5 will create 2 symlinks.
1612 Sat Apr 30 21:45:15 2011 Child 5 finished creating 2 symlinks.
1613 Sat Apr 30 21:47:22 2011 Child 14 verified 9761 files (1120 creates).
1614 Sat Apr 30 21:47:22 2011 Child 12 verified 9653 files (1012 creates).
1615 Sat Apr 30 21:47:26 2011 Child 4 verified 10325 files, created 1583
1616 Sat Apr 30 21:47:26 2011 Child 4 will create 2 symlinks.
1617 Sat Apr 30 21:47:26 2011 Child 4 finished creating 2 symlinks.
1618 Sat Apr 30 21:47:42 2011 Child 13 verified 10325 files, created 1583
1619 Sat Apr 30 21:47:42 2011 Child 13 will create 2 symlinks.
1620 Sat Apr 30 21:47:42 2011 Child 13 finished creating 2 symlinks.
1621 Sat Apr 30 21:47:42 2011 Child 11 verified 10325 files, created 1583
1622 Sat Apr 30 21:47:42 2011 Child 11 will create 2 symlinks.
1623 Sat Apr 30 21:47:42 2011 Child 11 finished creating 2 symlinks.
1624 Sat Apr 30 21:47:53 2011 Child 6 verified 10325 files, created 1583
1625 Sat Apr 30 21:47:53 2011 Child 6 will create 2 symlinks.
1626 Sat Apr 30 21:47:53 2011 Child 6 finished creating 2 symlinks.
1627 Sat Apr 30 21:48:02 2011 Child 1 verified 10325 files, created 1583
1628 Sat Apr 30 21:48:02 2011 Child 1 will create 2 symlinks.
1629 Sat Apr 30 21:48:02 2011 Child 1 finished creating 2 symlinks.
1630 Sat Apr 30 21:48:06 2011 Child 7 verified 10325 files, created 1583
1631 Sat Apr 30 21:48:06 2011 Child 7 will create 2 symlinks.
1632 Sat Apr 30 21:48:06 2011 Child 7 finished creating 2 symlinks.
1633 Sat Apr 30 21:48:08 2011 Child 14 verified 10325 files, created 1583
1634 Sat Apr 30 21:48:08 2011 Child 14 will create 2 symlinks.
1635 Sat Apr 30 21:48:08 2011 Child 14 finished creating 2 symlinks.
1636 Sat Apr 30 21:48:10 2011 Child 9 verified 10325 files, created 1583
1637 Sat Apr 30 21:48:10 2011 Child 9 will create 2 symlinks.
1638 Sat Apr 30 21:48:10 2011 Child 15 verified 10325 files, created 1583
1639 Sat Apr 30 21:48:10 2011 Child 15 will create 2 symlinks.
1640 Sat Apr 30 21:48:10 2011 Child 9 finished creating 2 symlinks.
1641 Sat Apr 30 21:48:10 2011 Child 15 finished creating 2 symlinks.
1642 Sat Apr 30 21:48:11 2011 Child 10 verified 10325 files, created 1583
1643 Sat Apr 30 21:48:11 2011 Child 10 will create 2 symlinks.
1644 Sat Apr 30 21:48:11 2011 Child 10 finished creating 2 symlinks.
1645 Sat Apr 30 21:48:11 2011 Child 12 verified 10325 files, created 1583
1646 Sat Apr 30 21:48:11 2011 Child 12 will create 2 symlinks.
1647 Sat Apr 30 21:48:11 2011 Child 12 finished creating 2 symlinks.
1648 Sat Apr 30 21:48:12 2011 Child 3 verified 10325 files, created 1583
1649 Sat Apr 30 21:48:12 2011 Child 3 will create 2 symlinks.
1650 Sat Apr 30 21:48:12 2011 Child 3 finished creating 2 symlinks.
1651 Sat Apr 30 21:48:12 2011 Completed.
1652 Sat Apr 30 21:48:12 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1653 Sat Apr 30 21:48:12 2011 Completed.
1654 Sat Apr 30 21:48:12 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1655 Sat Apr 30 21:48:13 2011 Received.
1656 Sat Apr 30 21:48:13 2011 Performing 300 seconds pretest warmup.
1657 Sat Apr 30 21:53:13 2011 Completed.
1658 Sat Apr 30 21:53:13 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1659 Sat Apr 30 21:53:13 2011 Completed.
1660 Sat Apr 30 21:53:13 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1661 Sat Apr 30 21:53:14 2011 Received.
1662 Sat Apr 30 21:53:14 2011 Starting 300 seconds test run.
1663 SFS Single Client (c09-44) Results, Sat Apr 30 21:58:24 2011
1664 --------------------------------------------------------------------------------------
1665 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1666 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1667 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1668            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1669 --------------------------------------------------------------------------------------
1670 getattr     26.0%    25.9%    43499    43499      0      3.93   256.42      0.15  3.7%
1671 setattr      4.0%     4.0%     6737     6737      0     41.32    42.07      0.15  6.0%
1672 lookup      24.0%    24.0%    40334    40334      0      3.23   179.18      0.13  2.8%
1673 readlink     1.0%     1.0%     1705     1705      0      1.99     6.58      0.12  0.1%
1674 read        18.0%    18.1%    30426    37456      0     62.73   888.15      0.33 41.4%
1675 write       10.0%    10.1%    16910    20306      0     93.16  1404.78      0.56 34.1%
1676 create       1.0%     1.0%     1712     1712      0     78.58    63.70      0.38  2.9%
1677 remove       1.0%     1.0%     1656     1656      0     45.95    43.08      0.32  1.6%
1678 readdir      1.0%     1.0%     1613     1613      0      2.64    12.56      0.17  0.1%
1679 fsstat       1.0%     1.0%     1664     1664      0      2.30     8.28      0.14  0.1%
1680 access      11.0%    11.0%    18543    18543      0      2.36     9.94      0.05  0.9%
1681 commit       0.0%     0.0%        0     6596      0      0.00     0.00      0.00  5.9%
1682 readdirplus  2.0%     2.0%     3405     3405      0      3.54    27.72      0.18  0.3%
1683 --------------------------------------------------------------------------------------
1684
1685       --------------------------------------------------
1686       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1687       --------------------------+-----------------------
1688 SFS NFS V3 THROUGHPUT:  692.19 Ops/Sec   AVG. RESPONSE TIME:   27.43 Msec/Op
1689 TCP PROTOCOL
1690 FAST CALLS: 0
1691 NFS V3 MIXFILE: [ SFS Default ]
1692 CLIENT REQUESTED LOAD: 700 Ops/Sec
1693 TOTAL LOGICAL NFS V3 OPERATIONS:  168204         TEST TIME: 243 Sec
1694 TOTAL PHYSICAL NFS V3 OPERATIONS: 185226
1695 PHYSICAL NFS V3 THROUGHPUT:  762.24 Ops/Sec
1696 FILE SET SIZE CREATED: 85996512.0 KB
1697 FILE SET SIZE ACCESSED: 25802352.0 - 26091536.0 KB  (100.00% to 101.12% of Base)
1698
1699 ------------------------------------------------------------------------
1700
1701 Sat Apr 30 21:58:24 2011 Completed.
1702
1703 Client Test Parameters:
1704         Number of processes = 16
1705         Requested Load (NFS V3 operations/second) = 700
1706         Maximum number of outstanding biod writes = 8
1707         Maximum number of outstanding biod reads = 8
1708         Warm-up time (seconds) = 300
1709         Run time (seconds) = 300
1710         File Set = 161952 Files created for I/O operations
1711                    48576 Files accessed for I/O operations
1712                    3248 Files for non-I/O operations
1713                      32 Symlinks
1714                    5392 Directories
1715                         Additional non-I/O files created as necessary
1716
1717
1718 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1719 Sat Apr 30 21:58:24 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1720 Sat Apr 30 21:58:24 2011 Completed.
1721 Sat Apr 30 21:58:24 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1722
1723 ************************************************************************
1724 Sat Apr 30 21:58:25 2011 Received.
1725 Sat Apr 30 21:58:25 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1726 Sat Apr 30 21:58:25 2011 Completed.
1727 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
1728 NFS V3 Protocol Version
1729
1730 ************************************************************************
1731 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1732 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1733
1734 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1735 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1736
1737 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 21:58:27 2011
1738         Client hostname = c09-44
1739         Prime Client hostname = c09-44.sysnet.ucsd.edu
1740
1741 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1742 NFS V3 Protocol
1743 Sat Apr 30 21:58:27 2011 Mounting 16 remote test directories.
1744 Child[000]: NFS negotiated block size 32768
1745 Child[001]: NFS negotiated block size 32768
1746 Child[002]: NFS negotiated block size 32768
1747 Child[003]: NFS negotiated block size 32768
1748 Child[004]: NFS negotiated block size 32768
1749 Child[005]: NFS negotiated block size 32768
1750 Child[006]: NFS negotiated block size 32768
1751 Child[007]: NFS negotiated block size 32768
1752 Child[008]: NFS negotiated block size 32768
1753 Child[009]: NFS negotiated block size 32768
1754 Child[010]: NFS negotiated block size 32768
1755 Child[011]: NFS negotiated block size 32768
1756 Child[012]: NFS negotiated block size 32768
1757 Child[013]: NFS negotiated block size 32768
1758 Child[014]: NFS negotiated block size 32768
1759 Child[015]: NFS negotiated block size 32768
1760 Sat Apr 30 21:58:41 2011 Completed.
1761 Sat Apr 30 21:58:41 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1762 Sat Apr 30 21:58:41 2011 Completed.
1763 Sat Apr 30 21:58:41 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1764 Sat Apr 30 21:58:43 2011 Received.
1765
1766 Sat Apr 30 21:58:43 2011 Initializing test directories.
1767 Sat Apr 30 21:58:43 2011 Child 13 will create 383 directories.
1768 Sat Apr 30 21:58:43 2011 Child 9 will create 383 directories.
1769 Sat Apr 30 21:58:43 2011 Child 0 will create 383 directories.
1770 Sat Apr 30 21:58:43 2011 Child 12 will create 383 directories.
1771 Sat Apr 30 21:58:43 2011 Child 15 will create 383 directories.
1772 Sat Apr 30 21:58:43 2011 Child 10 will create 383 directories.
1773 Sat Apr 30 21:58:43 2011 Child 1 will create 383 directories.
1774 Sat Apr 30 21:58:43 2011 Child 14 will create 383 directories.
1775 Sat Apr 30 21:58:43 2011 Child 7 will create 383 directories.
1776 Sat Apr 30 21:58:43 2011 Child 2 will create 383 directories.
1777 Sat Apr 30 21:58:43 2011 Child 11 will create 383 directories.
1778 Sat Apr 30 21:58:43 2011 Child 6 will create 383 directories.
1779 Sat Apr 30 21:58:43 2011 Child 3 will create 383 directories.
1780 Sat Apr 30 21:58:43 2011 Child 5 will create 383 directories.
1781 Sat Apr 30 21:58:43 2011 Child 4 will create 383 directories.
1782 Sat Apr 30 21:58:43 2011 Child 8 will create 383 directories.
1783 Sat Apr 30 21:58:44 2011 Child 14 finished creating 383 directories.
1784 Sat Apr 30 21:58:44 2011 Child 14 will create 11710 files.
1785 Sat Apr 30 21:58:45 2011 Child 7 finished creating 383 directories.
1786 Sat Apr 30 21:58:45 2011 Child 7 will create 11710 files.
1787 Sat Apr 30 21:58:46 2011 Child 12 finished creating 383 directories.
1788 Sat Apr 30 21:58:46 2011 Child 12 will create 11710 files.
1789 Sat Apr 30 21:58:53 2011 Child 15 finished creating 383 directories.
1790 Sat Apr 30 21:58:53 2011 Child 15 will create 11710 files.
1791 Sat Apr 30 21:58:55 2011 Child 13 finished creating 383 directories.
1792 Sat Apr 30 21:58:55 2011 Child 13 will create 11710 files.
1793 Sat Apr 30 21:58:56 2011 Child 3 finished creating 383 directories.
1794 Sat Apr 30 21:58:56 2011 Child 3 will create 11710 files.
1795 Sat Apr 30 21:58:56 2011 Child 1 finished creating 383 directories.
1796 Sat Apr 30 21:58:56 2011 Child 1 will create 11710 files.
1797 Sat Apr 30 21:58:56 2011 Child 9 finished creating 383 directories.
1798 Sat Apr 30 21:58:56 2011 Child 9 will create 11710 files.
1799 Sat Apr 30 21:58:58 2011 Child 6 finished creating 383 directories.
1800 Sat Apr 30 21:58:58 2011 Child 6 will create 11710 files.
1801 Sat Apr 30 21:59:04 2011 Child 10 finished creating 383 directories.
1802 Sat Apr 30 21:59:04 2011 Child 10 will create 11710 files.
1803 Sat Apr 30 21:59:06 2011 Child 0 finished creating 383 directories.
1804 Sat Apr 30 21:59:06 2011 Child 0 will create 11710 files.
1805 Sat Apr 30 21:59:06 2011 Child 8 finished creating 383 directories.
1806 Sat Apr 30 21:59:06 2011 Child 8 will create 11710 files.
1807 Sat Apr 30 21:59:07 2011 Child 2 finished creating 383 directories.
1808 Sat Apr 30 21:59:07 2011 Child 2 will create 11710 files.
1809 Sat Apr 30 21:59:11 2011 Child 5 finished creating 383 directories.
1810 Sat Apr 30 21:59:11 2011 Child 5 will create 11710 files.
1811 Sat Apr 30 21:59:12 2011 Child 11 finished creating 383 directories.
1812 Sat Apr 30 21:59:12 2011 Child 11 will create 11710 files.
1813 Sat Apr 30 21:59:15 2011 Child 4 finished creating 383 directories.
1814 Sat Apr 30 21:59:15 2011 Child 4 will create 11710 files.
1815 Sat Apr 30 22:03:49 2011 Child 7 verified 10422 files (300 creates).
1816 Sat Apr 30 22:03:49 2011 Child 14 verified 10485 files (363 creates).
1817 Sat Apr 30 22:03:49 2011 Child 12 verified 10276 files (154 creates).
1818 Sat Apr 30 22:04:23 2011 Child 6 verified 10277 files (155 creates).
1819 Sat Apr 30 22:04:48 2011 Child 1 verified 10318 files (196 creates).
1820 Sat Apr 30 22:04:58 2011 Child 2 verified 10353 files (231 creates).
1821 Sat Apr 30 22:04:58 2011 Child 15 verified 10353 files (231 creates).
1822 Sat Apr 30 22:04:58 2011 Child 5 verified 10318 files (196 creates).
1823 Sat Apr 30 22:04:58 2011 Child 8 verified 10318 files (196 creates).
1824 Sat Apr 30 22:04:58 2011 Child 13 verified 10318 files (196 creates).
1825 Sat Apr 30 22:04:58 2011 Child 0 verified 10318 files (196 creates).
1826 Sat Apr 30 22:04:58 2011 Child 11 verified 10318 files (196 creates).
1827 Sat Apr 30 22:04:58 2011 Child 4 verified 10318 files (196 creates).
1828 Sat Apr 30 22:04:58 2011 Child 9 verified 10318 files (196 creates).
1829 Sat Apr 30 22:04:58 2011 Child 10 verified 10318 files (196 creates).
1830 Sat Apr 30 22:04:58 2011 Child 3 verified 10318 files (196 creates).
1831 Sat Apr 30 22:09:09 2011 Child 14 verified 11041 files (919 creates).
1832 Sat Apr 30 22:09:09 2011 Child 7 verified 10781 files (659 creates).
1833 Sat Apr 30 22:09:22 2011 Child 12 verified 10507 files (385 creates).
1834 Sat Apr 30 22:09:22 2011 Child 6 verified 10508 files (386 creates).
1835 Sat Apr 30 22:10:03 2011 Child 4 verified 10541 files (419 creates).
1836 Sat Apr 30 22:10:03 2011 Child 11 verified 10801 files (679 creates).
1837 Sat Apr 30 22:10:03 2011 Child 5 verified 10801 files (679 creates).
1838 Sat Apr 30 22:10:03 2011 Child 0 verified 10533 files (411 creates).
1839 Sat Apr 30 22:10:19 2011 Child 8 verified 10533 files (411 creates).
1840 Sat Apr 30 22:10:19 2011 Child 13 verified 10533 files (411 creates).
1841 Sat Apr 30 22:10:19 2011 Child 1 verified 10533 files (411 creates).
1842 Sat Apr 30 22:10:19 2011 Child 3 verified 10533 files (411 creates).
1843 Sat Apr 30 22:10:19 2011 Child 9 verified 10533 files (411 creates).
1844 Sat Apr 30 22:10:19 2011 Child 10 verified 10533 files (411 creates).
1845 Sat Apr 30 22:10:19 2011 Child 2 verified 10812 files (690 creates).
1846 Sat Apr 30 22:10:19 2011 Child 15 verified 10812 files (690 creates).
1847 Sat Apr 30 22:14:00 2011 Child 14 verified 11710 files, created 1474
1848 Sat Apr 30 22:14:00 2011 Child 14 will create 2 symlinks.
1849 Sat Apr 30 22:14:00 2011 Child 14 finished creating 2 symlinks.
1850 Sat Apr 30 22:14:49 2011 Child 12 verified 11148 files (1026 creates).
1851 Sat Apr 30 22:14:49 2011 Child 6 verified 11071 files (949 creates).
1852 Sat Apr 30 22:14:49 2011 Child 7 verified 11391 files (1269 creates).
1853 Sat Apr 30 22:15:29 2011 Child 8 verified 11182 files (1060 creates).
1854 Sat Apr 30 22:15:30 2011 Child 1 verified 11182 files (1060 creates).
1855 Sat Apr 30 22:15:30 2011 Child 5 verified 11428 files (1306 creates).
1856 Sat Apr 30 22:15:30 2011 Child 10 verified 11182 files (1060 creates).
1857 Sat Apr 30 22:15:30 2011 Child 2 verified 11428 files (1306 creates).
1858 Sat Apr 30 22:15:48 2011 Child 4 verified 11148 files (1026 creates).
1859 Sat Apr 30 22:15:48 2011 Child 15 verified 11428 files (1306 creates).
1860 Sat Apr 30 22:15:48 2011 Child 11 verified 11391 files (1269 creates).
1861 Sat Apr 30 22:15:48 2011 Child 3 verified 11182 files (1060 creates).
1862 Sat Apr 30 22:15:48 2011 Child 0 verified 11148 files (1026 creates).
1863 Sat Apr 30 22:15:48 2011 Child 9 verified 11148 files (1026 creates).
1864 Sat Apr 30 22:15:48 2011 Child 13 verified 11148 files (1026 creates).
1865 Sat Apr 30 22:15:56 2011 Child 15 verified 11710 files, created 1474
1866 Sat Apr 30 22:15:56 2011 Child 15 will create 2 symlinks.
1867 Sat Apr 30 22:15:56 2011 Child 15 finished creating 2 symlinks.
1868 Sat Apr 30 22:16:32 2011 Child 2 verified 11710 files, created 1474
1869 Sat Apr 30 22:16:32 2011 Child 2 will create 2 symlinks.
1870 Sat Apr 30 22:16:32 2011 Child 2 finished creating 2 symlinks.
1871 Sat Apr 30 22:16:32 2011 Child 5 verified 11710 files, created 1474
1872 Sat Apr 30 22:16:32 2011 Child 5 will create 2 symlinks.
1873 Sat Apr 30 22:16:32 2011 Child 11 verified 11710 files, created 1474
1874 Sat Apr 30 22:16:32 2011 Child 11 will create 2 symlinks.
1875 Sat Apr 30 22:16:32 2011 Child 5 finished creating 2 symlinks.
1876 Sat Apr 30 22:16:32 2011 Child 11 finished creating 2 symlinks.
1877 Sat Apr 30 22:16:34 2011 Child 7 verified 11710 files, created 1474
1878 Sat Apr 30 22:16:34 2011 Child 7 will create 2 symlinks.
1879 Sat Apr 30 22:16:34 2011 Child 7 finished creating 2 symlinks.
1880 Sat Apr 30 22:17:43 2011 Child 1 verified 11710 files, created 1474
1881 Sat Apr 30 22:17:43 2011 Child 1 will create 2 symlinks.
1882 Sat Apr 30 22:17:43 2011 Child 8 verified 11710 files, created 1474
1883 Sat Apr 30 22:17:43 2011 Child 8 will create 2 symlinks.
1884 Sat Apr 30 22:17:43 2011 Child 10 verified 11710 files, created 1474
1885 Sat Apr 30 22:17:43 2011 Child 10 will create 2 symlinks.
1886 Sat Apr 30 22:17:43 2011 Child 12 verified 11710 files, created 1474
1887 Sat Apr 30 22:17:43 2011 Child 12 will create 2 symlinks.
1888 Sat Apr 30 22:17:43 2011 Child 8 finished creating 2 symlinks.
1889 Sat Apr 30 22:17:43 2011 Child 1 finished creating 2 symlinks.
1890 Sat Apr 30 22:17:43 2011 Child 10 finished creating 2 symlinks.
1891 Sat Apr 30 22:17:43 2011 Child 3 verified 11710 files, created 1474
1892 Sat Apr 30 22:17:43 2011 Child 3 will create 2 symlinks.
1893 Sat Apr 30 22:17:43 2011 Child 12 finished creating 2 symlinks.
1894 Sat Apr 30 22:17:43 2011 Child 3 finished creating 2 symlinks.
1895 Sat Apr 30 22:18:23 2011 Child 0 verified 11710 files, created 1474
1896 Sat Apr 30 22:18:23 2011 Child 0 will create 2 symlinks.
1897 Sat Apr 30 22:18:23 2011 Child 9 verified 11710 files, created 1474
1898 Sat Apr 30 22:18:23 2011 Child 9 will create 2 symlinks.
1899 Sat Apr 30 22:18:23 2011 Child 6 verified 11710 files, created 1474
1900 Sat Apr 30 22:18:23 2011 Child 6 will create 2 symlinks.
1901 Sat Apr 30 22:18:23 2011 Child 0 finished creating 2 symlinks.
1902 Sat Apr 30 22:18:23 2011 Child 9 finished creating 2 symlinks.
1903 Sat Apr 30 22:18:23 2011 Child 4 verified 11710 files, created 1474
1904 Sat Apr 30 22:18:23 2011 Child 4 will create 2 symlinks.
1905 Sat Apr 30 22:18:23 2011 Child 6 finished creating 2 symlinks.
1906 Sat Apr 30 22:18:23 2011 Child 4 finished creating 2 symlinks.
1907 Sat Apr 30 22:18:23 2011 Child 13 verified 11710 files, created 1474
1908 Sat Apr 30 22:18:23 2011 Child 13 will create 2 symlinks.
1909 Sat Apr 30 22:18:23 2011 Child 13 finished creating 2 symlinks.
1910 Sat Apr 30 22:18:23 2011 Completed.
1911 Sat Apr 30 22:18:23 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1912 Sat Apr 30 22:18:23 2011 Completed.
1913 Sat Apr 30 22:18:23 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1914 Sat Apr 30 22:18:24 2011 Received.
1915 Sat Apr 30 22:18:24 2011 Performing 300 seconds pretest warmup.
1916 Sat Apr 30 22:23:24 2011 Completed.
1917 Sat Apr 30 22:23:24 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1918 Sat Apr 30 22:23:24 2011 Completed.
1919 Sat Apr 30 22:23:24 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1920 Sat Apr 30 22:23:25 2011 Received.
1921 Sat Apr 30 22:23:25 2011 Starting 300 seconds test run.
1922 SFS Single Client (c09-44) Results, Sat Apr 30 22:28:43 2011
1923 --------------------------------------------------------------------------------------
1924 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1925 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1926 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1927            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1928 --------------------------------------------------------------------------------------
1929 getattr     26.0%    25.9%    54184    54184      0      3.40   207.79      0.12  2.9%
1930 setattr      4.0%     4.0%     8371     8371      0     38.11    38.92      0.13  4.9%
1931 lookup      24.0%    24.1%    50338    50338      0      3.01   193.12      0.12  2.3%
1932 readlink     1.0%     1.0%     2064     2064      0      2.25     8.70      0.13  0.1%
1933 read        18.0%    18.0%    37671    46415      0     90.96  1420.14      0.38 53.0%
1934 write       10.0%    10.1%    21088    25447      0     74.18  1179.83      0.46 24.2%
1935 create       1.0%     1.0%     2105     2105      0     73.66    56.01      0.32  2.4%
1936 remove       1.0%     1.0%     2050     2050      0     44.21    43.16      0.28  1.4%
1937 readdir      1.0%     1.0%     2038     2038      0      2.45     9.44      0.13  0.1%
1938 fsstat       1.0%     1.0%     2178     2178      0     17.75   731.92      1.14  0.6%
1939 access      11.0%    10.9%    22851    22851      0      3.90   275.05      0.21  1.4%
1940 commit       0.0%     0.0%        0     8662      0      0.00     0.00      0.00  6.5%
1941 readdirplus  2.0%     2.0%     4203     4203      0      2.73     7.17      0.08  0.2%
1942 --------------------------------------------------------------------------------------
1943
1944       --------------------------------------------------
1945       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1946       --------------------------+-----------------------
1947 SFS NFS V3 THROUGHPUT:  681.24 Ops/Sec   AVG. RESPONSE TIME:   30.90 Msec/Op
1948 TCP PROTOCOL
1949 FAST CALLS: 0
1950 NFS V3 MIXFILE: [ SFS Default ]
1951 CLIENT REQUESTED LOAD: 800 Ops/Sec
1952 TOTAL LOGICAL NFS V3 OPERATIONS:  209141         TEST TIME: 307 Sec
1953 TOTAL PHYSICAL NFS V3 OPERATIONS: 230906
1954 PHYSICAL NFS V3 THROUGHPUT:  752.13 Ops/Sec
1955 FILE SET SIZE CREATED: 97534080.0 KB
1956 FILE SET SIZE ACCESSED: 29268720.0 - 29688682.0 KB  (100.00% to 101.43% of Base)
1957
1958 ------------------------------------------------------------------------
1959
1960 Sat Apr 30 22:28:43 2011 Completed.
1961
1962 Client Test Parameters:
1963         Number of processes = 16
1964         Requested Load (NFS V3 operations/second) = 800
1965         Maximum number of outstanding biod writes = 8
1966         Maximum number of outstanding biod reads = 8
1967         Warm-up time (seconds) = 300
1968         Run time (seconds) = 300
1969         File Set = 183680 Files created for I/O operations
1970                    55104 Files accessed for I/O operations
1971                    3680 Files for non-I/O operations
1972                      32 Symlinks
1973                    6112 Directories
1974                         Additional non-I/O files created as necessary
1975
1976
1977 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1978 Sat Apr 30 22:28:43 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1979 Sat Apr 30 22:28:43 2011 Completed.
1980 Sat Apr 30 22:28:43 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1981
1982 ************************************************************************
1983 Sat Apr 30 22:28:43 2011 Received.
1984 Sat Apr 30 22:28:43 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1985 Sat Apr 30 22:28:43 2011 Completed.
1986 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
1987 NFS V3 Protocol Version
1988
1989 ************************************************************************
1990 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1991 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1992
1993 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1994 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1995
1996 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 22:28:45 2011
1997         Client hostname = c09-44
1998         Prime Client hostname = c09-44.sysnet.ucsd.edu
1999
2000 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2001 NFS V3 Protocol
2002 Sat Apr 30 22:28:45 2011 Mounting 16 remote test directories.
2003 Child[000]: NFS negotiated block size 32768
2004 Child[001]: NFS negotiated block size 32768
2005 Child[002]: NFS negotiated block size 32768
2006 Child[003]: NFS negotiated block size 32768
2007 Child[004]: NFS negotiated block size 32768
2008 Child[005]: NFS negotiated block size 32768
2009 Child[006]: NFS negotiated block size 32768
2010 Child[007]: NFS negotiated block size 32768
2011 Child[008]: NFS negotiated block size 32768
2012 Child[009]: NFS negotiated block size 32768
2013 Child[010]: NFS negotiated block size 32768
2014 Child[011]: NFS negotiated block size 32768
2015 Child[012]: NFS negotiated block size 32768
2016 Child[013]: NFS negotiated block size 32768
2017 Child[014]: NFS negotiated block size 32768
2018 Child[015]: NFS negotiated block size 32768
2019 Sat Apr 30 22:28:59 2011 Completed.
2020 Sat Apr 30 22:28:59 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2021 Sat Apr 30 22:28:59 2011 Completed.
2022 Sat Apr 30 22:28:59 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2023 Sat Apr 30 22:29:01 2011 Received.
2024
2025 Sat Apr 30 22:29:01 2011 Initializing test directories.
2026 Sat Apr 30 22:29:01 2011 Child 6 will create 433 directories.
2027 Sat Apr 30 22:29:01 2011 Child 5 will create 433 directories.
2028 Sat Apr 30 22:29:01 2011 Child 4 will create 433 directories.
2029 Sat Apr 30 22:29:01 2011 Child 0 will create 433 directories.
2030 Sat Apr 30 22:29:01 2011 Child 7 will create 433 directories.
2031 Sat Apr 30 22:29:01 2011 Child 10 will create 433 directories.
2032 Sat Apr 30 22:29:01 2011 Child 3 will create 433 directories.
2033 Sat Apr 30 22:29:01 2011 Child 9 will create 433 directories.
2034 Sat Apr 30 22:29:01 2011 Child 13 will create 433 directories.
2035 Sat Apr 30 22:29:01 2011 Child 8 will create 433 directories.
2036 Sat Apr 30 22:29:01 2011 Child 1 will create 433 directories.
2037 Sat Apr 30 22:29:01 2011 Child 11 will create 433 directories.
2038 Sat Apr 30 22:29:01 2011 Child 15 will create 433 directories.
2039 Sat Apr 30 22:29:01 2011 Child 14 will create 433 directories.
2040 Sat Apr 30 22:29:01 2011 Child 2 will create 433 directories.
2041 Sat Apr 30 22:29:01 2011 Child 12 will create 433 directories.
2042 Sat Apr 30 22:29:04 2011 Child 10 finished creating 433 directories.
2043 Sat Apr 30 22:29:04 2011 Child 10 will create 13221 files.
2044 Sat Apr 30 22:29:04 2011 Child 5 finished creating 433 directories.
2045 Sat Apr 30 22:29:04 2011 Child 5 will create 13221 files.
2046 Sat Apr 30 22:29:08 2011 Child 7 finished creating 433 directories.
2047 Sat Apr 30 22:29:08 2011 Child 7 will create 13221 files.
2048 Sat Apr 30 22:29:09 2011 Child 3 finished creating 433 directories.
2049 Sat Apr 30 22:29:09 2011 Child 3 will create 13221 files.
2050 Sat Apr 30 22:29:11 2011 Child 8 finished creating 433 directories.
2051 Sat Apr 30 22:29:11 2011 Child 8 will create 13221 files.
2052 Sat Apr 30 22:29:17 2011 Child 13 finished creating 433 directories.
2053 Sat Apr 30 22:29:17 2011 Child 13 will create 13221 files.
2054 Sat Apr 30 22:29:19 2011 Child 12 finished creating 433 directories.
2055 Sat Apr 30 22:29:19 2011 Child 12 will create 13221 files.
2056 Sat Apr 30 22:29:19 2011 Child 1 finished creating 433 directories.
2057 Sat Apr 30 22:29:19 2011 Child 1 will create 13221 files.
2058 Sat Apr 30 22:29:23 2011 Child 11 finished creating 433 directories.
2059 Sat Apr 30 22:29:23 2011 Child 11 will create 13221 files.
2060 Sat Apr 30 22:29:23 2011 Child 15 finished creating 433 directories.
2061 Sat Apr 30 22:29:23 2011 Child 15 will create 13221 files.
2062 Sat Apr 30 22:29:31 2011 Child 0 finished creating 433 directories.
2063 Sat Apr 30 22:29:31 2011 Child 0 will create 13221 files.
2064 Sat Apr 30 22:29:33 2011 Child 4 finished creating 433 directories.
2065 Sat Apr 30 22:29:33 2011 Child 4 will create 13221 files.
2066 Sat Apr 30 22:29:36 2011 Child 2 finished creating 433 directories.
2067 Sat Apr 30 22:29:36 2011 Child 2 will create 13221 files.
2068 Sat Apr 30 22:29:37 2011 Child 6 finished creating 433 directories.
2069 Sat Apr 30 22:29:37 2011 Child 6 will create 13221 files.
2070 Sat Apr 30 22:29:38 2011 Child 9 finished creating 433 directories.
2071 Sat Apr 30 22:29:38 2011 Child 9 will create 13221 files.
2072 Sat Apr 30 22:29:39 2011 Child 14 finished creating 433 directories.
2073 Sat Apr 30 22:29:39 2011 Child 14 will create 13221 files.
2074 Sat Apr 30 22:32:47 2011 Child 5 verified 13221 files, created 1612
2075 Sat Apr 30 22:32:47 2011 Child 5 will create 2 symlinks.
2076 Sat Apr 30 22:32:47 2011 Child 5 finished creating 2 symlinks.
2077 Sat Apr 30 22:32:56 2011 Child 10 verified 13221 files, created 1612
2078 Sat Apr 30 22:32:56 2011 Child 10 will create 2 symlinks.
2079 Sat Apr 30 22:33:00 2011 Child 10 finished creating 2 symlinks.
2080 Sat Apr 30 22:34:14 2011 Child 8 verified 12857 files (1377 creates).
2081 Sat Apr 30 22:34:16 2011 Child 3 verified 13221 files, created 1612
2082 Sat Apr 30 22:34:16 2011 Child 3 will create 2 symlinks.
2083 Sat Apr 30 22:34:16 2011 Child 3 finished creating 2 symlinks.
2084 Sat Apr 30 22:34:19 2011 Child 13 verified 12684 files (1204 creates).
2085 Sat Apr 30 22:34:20 2011 Child 12 verified 12361 files (881 creates).
2086 Sat Apr 30 22:34:20 2011 Child 1 verified 12621 files (1141 creates).
2087 Sat Apr 30 22:34:25 2011 Child 7 verified 13221 files, created 1612
2088 Sat Apr 30 22:34:25 2011 Child 7 will create 2 symlinks.
2089 Sat Apr 30 22:34:26 2011 Child 7 finished creating 2 symlinks.
2090 Sat Apr 30 22:34:26 2011 Child 15 verified 12540 files (1060 creates).
2091 Sat Apr 30 22:34:26 2011 Child 11 verified 12518 files (1038 creates).
2092 Sat Apr 30 22:34:32 2011 Child 0 verified 12447 files (967 creates).
2093 Sat Apr 30 22:34:34 2011 Child 4 verified 12438 files (958 creates).
2094 Sat Apr 30 22:34:38 2011 Child 2 verified 12361 files (881 creates).
2095 Sat Apr 30 22:34:38 2011 Child 6 verified 12288 files (808 creates).
2096 Sat Apr 30 22:34:42 2011 Child 14 verified 12314 files (834 creates).
2097 Sat Apr 30 22:34:42 2011 Child 9 verified 12372 files (892 creates).
2098 Sat Apr 30 22:34:54 2011 Child 8 verified 13221 files, created 1612
2099 Sat Apr 30 22:34:54 2011 Child 8 will create 2 symlinks.
2100 Sat Apr 30 22:34:56 2011 Child 8 finished creating 2 symlinks.
2101 Sat Apr 30 22:35:40 2011 Child 13 verified 13221 files, created 1612
2102 Sat Apr 30 22:35:40 2011 Child 13 will create 2 symlinks.
2103 Sat Apr 30 22:35:40 2011 Child 13 finished creating 2 symlinks.
2104 Sat Apr 30 22:35:54 2011 Child 1 verified 13221 files, created 1612
2105 Sat Apr 30 22:35:54 2011 Child 1 will create 2 symlinks.
2106 Sat Apr 30 22:36:01 2011 Child 1 finished creating 2 symlinks.
2107 Sat Apr 30 22:36:20 2011 Child 11 verified 13221 files, created 1612
2108 Sat Apr 30 22:36:20 2011 Child 11 will create 2 symlinks.
2109 Sat Apr 30 22:36:20 2011 Child 11 finished creating 2 symlinks.
2110 Sat Apr 30 22:36:26 2011 Child 15 verified 13221 files, created 1612
2111 Sat Apr 30 22:36:26 2011 Child 15 will create 2 symlinks.
2112 Sat Apr 30 22:36:27 2011 Child 15 finished creating 2 symlinks.
2113 Sat Apr 30 22:36:38 2011 Child 0 verified 13221 files, created 1612
2114 Sat Apr 30 22:36:38 2011 Child 0 will create 2 symlinks.
2115 Sat Apr 30 22:36:44 2011 Child 0 finished creating 2 symlinks.
2116 Sat Apr 30 22:36:45 2011 Child 4 verified 13221 files, created 1612
2117 Sat Apr 30 22:36:45 2011 Child 4 will create 2 symlinks.
2118 Sat Apr 30 22:36:48 2011 Child 12 verified 13221 files, created 1612
2119 Sat Apr 30 22:36:48 2011 Child 12 will create 2 symlinks.
2120 Sat Apr 30 22:36:48 2011 Child 12 finished creating 2 symlinks.
2121 Sat Apr 30 22:36:50 2011 Child 4 finished creating 2 symlinks.
2122 Sat Apr 30 22:37:00 2011 Child 2 verified 13221 files, created 1612
2123 Sat Apr 30 22:37:00 2011 Child 2 will create 2 symlinks.
2124 Sat Apr 30 22:37:00 2011 Child 2 finished creating 2 symlinks.
2125 Sat Apr 30 22:37:01 2011 Child 9 verified 13221 files, created 1612
2126 Sat Apr 30 22:37:01 2011 Child 9 will create 2 symlinks.
2127 Sat Apr 30 22:37:01 2011 Child 9 finished creating 2 symlinks.
2128 Sat Apr 30 22:37:01 2011 Child 6 verified 13221 files, created 1612
2129 Sat Apr 30 22:37:01 2011 Child 6 will create 2 symlinks.
2130 Sat Apr 30 22:37:01 2011 Child 6 finished creating 2 symlinks.
2131 Sat Apr 30 22:37:04 2011 Child 14 verified 13221 files, created 1612
2132 Sat Apr 30 22:37:04 2011 Child 14 will create 2 symlinks.
2133 Sat Apr 30 22:37:04 2011 Child 14 finished creating 2 symlinks.
2134 Sat Apr 30 22:37:05 2011 Completed.
2135 Sat Apr 30 22:37:05 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2136 Sat Apr 30 22:37:05 2011 Completed.
2137 Sat Apr 30 22:37:05 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2138 Sat Apr 30 22:37:06 2011 Received.
2139 Sat Apr 30 22:37:06 2011 Performing 300 seconds pretest warmup.
2140 Sat Apr 30 22:42:06 2011 Completed.
2141 Sat Apr 30 22:42:06 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2142 Sat Apr 30 22:42:06 2011 Completed.
2143 Sat Apr 30 22:42:06 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2144 Sat Apr 30 22:42:07 2011 Received.
2145 Sat Apr 30 22:42:07 2011 Starting 300 seconds test run.
2146 sfsnfs3: INVALID RUN No good calls
2147 sfsnfs3: INVALID RUN, 100% Failed RPC calls
2148 sfsnfs3: INVALID RUN, no good calls
2149 SFS Single Client (c09-44) Results, Sat Apr 30 22:48:17 2011
2150 --------------------------------------------------------------------------------------
2151 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2152 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2153 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2154            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2155 --------------------------------------------------------------------------------------
2156 --------------------------------------------------------------------------------------
2157
2158       --------------------------------------------------
2159       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2160       --------------------------+-----------------------
2161 SFS NFS V3 THROUGHPUT:    0.00 Ops/Sec   AVG. RESPONSE TIME:    0.00 Msec/Op
2162 TCP PROTOCOL
2163 FAST CALLS: 0
2164 NFS V3 MIXFILE: [ SFS Default ]
2165 CLIENT REQUESTED LOAD: 900 Ops/Sec
2166 TOTAL LOGICAL NFS V3 OPERATIONS:  0              TEST TIME: 327 Sec
2167 TOTAL PHYSICAL NFS V3 OPERATIONS: 0
2168 PHYSICAL NFS V3 THROUGHPUT:    0.00 Ops/Sec
2169 FILE SET SIZE CREATED: 110116656.0 KB
2170 FILE SET SIZE ACCESSED: 33040944.0 - 33137132.0 KB  (100.00% to 100.29% of Base)
2171
2172 ------------------------------------------------------------------------
2173
2174 Sat Apr 30 22:48:17 2011 Completed.
2175
2176 Client Test Parameters:
2177         Number of processes = 16
2178         Requested Load (NFS V3 operations/second) = 900
2179         Maximum number of outstanding biod writes = 8
2180         Maximum number of outstanding biod reads = 8
2181         Warm-up time (seconds) = 300
2182         Run time (seconds) = 300
2183         File Set = 207376 Files created for I/O operations
2184                    62208 Files accessed for I/O operations
2185                    4160 Files for non-I/O operations
2186                      32 Symlinks
2187                    6912 Directories
2188                         Additional non-I/O files created as necessary
2189
2190
2191 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2192 Sat Apr 30 22:48:17 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2193 Sat Apr 30 22:48:17 2011 Completed.
2194 Sat Apr 30 22:48:17 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2195
2196 ************************************************************************
2197 Sat Apr 30 22:48:18 2011 Received.
2198 Sat Apr 30 22:48:18 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2199 Sat Apr 30 22:48:18 2011 Completed.
2200 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
2201 NFS V3 Protocol Version
2202
2203 ************************************************************************
2204 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2205 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2206
2207 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2208 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2209
2210 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 22:48:20 2011
2211         Client hostname = c09-44
2212         Prime Client hostname = c09-44.sysnet.ucsd.edu
2213
2214 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2215 NFS V3 Protocol
2216 Sat Apr 30 22:48:20 2011 Mounting 16 remote test directories.
2217 RPC: Timed out
2218 RPC: Timed out
2219 Unable to contact NFS server vrable2.sysnet.ucsd.edu.
2220 Unable to contact NFS server vrable2.sysnet.ucsd.edu.
2221 Verify NFS server daemon supporting version 3 is running and
2222 Verify NFS server daemon supporting version 3 is running and
2223 registered with the portmapper.
2224 registered with the portmapper.
2225 Mount failed. Exit(145)
2226 Mount failed. Exit(145)
2227 sfsnfs3: caught unexpected SIGCHLD.
2228 A child (PID: 13671) has abnormally exited with status of 145
2229 Tell Prime to stop the benchmark.
2230 Terminating all child processes.
2231 sfsnfs3: sending Pid 0 Signal 2