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

   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 14:02:48 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 14:02:48 2011 Mounting 16 remote test directories.
  18 Child[000]: NFS negotiated block size 32768
  19 Child[001]: NFS negotiated block size 32768
  20 Child[002]: NFS negotiated block size 32768
  21 Child[003]: NFS negotiated block size 32768
  22 Child[004]: NFS negotiated block size 32768
  23 Child[005]: NFS negotiated block size 32768
  24 Child[006]: NFS negotiated block size 32768
  25 Child[007]: NFS negotiated block size 32768
  26 Child[008]: NFS negotiated block size 32768
  27 Child[009]: NFS negotiated block size 32768
  28 Child[010]: NFS negotiated block size 32768
  29 Child[011]: NFS negotiated block size 32768
  30 Child[012]: NFS negotiated block size 32768
  31 Child[013]: NFS negotiated block size 32768
  32 Child[014]: NFS negotiated block size 32768
  33 Child[015]: NFS negotiated block size 32768
  34 Sat Apr 30 14:03:02 2011 Completed.
  35 Sat Apr 30 14:03:02 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
  36 Sat Apr 30 14:03:02 2011 Completed.
  37 Sat Apr 30 14:03:02 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
  38 Sat Apr 30 14:03:04 2011 Received.
  39
  40 Sat Apr 30 14:03:04 2011 Initializing test directories.
  41 Sat Apr 30 14:03:04 2011 Child 11 will create 46 directories.
  42 Sat Apr 30 14:03:04 2011 Child 9 will create 46 directories.
  43 Sat Apr 30 14:03:04 2011 Child 7 will create 46 directories.
  44 Sat Apr 30 14:03:04 2011 Child 4 will create 46 directories.
  45 Sat Apr 30 14:03:04 2011 Child 14 will create 46 directories.
  46 Sat Apr 30 14:03:04 2011 Child 2 will create 46 directories.
  47 Sat Apr 30 14:03:04 2011 Child 13 will create 46 directories.
  48 Sat Apr 30 14:03:04 2011 Child 15 will create 46 directories.
  49 Sat Apr 30 14:03:04 2011 Child 10 will create 46 directories.
  50 Sat Apr 30 14:03:04 2011 Child 3 will create 46 directories.
  51 Sat Apr 30 14:03:04 2011 Child 1 will create 46 directories.
  52 Sat Apr 30 14:03:04 2011 Child 0 will create 46 directories.
  53 Sat Apr 30 14:03:04 2011 Child 5 will create 46 directories.
  54 Sat Apr 30 14:03:04 2011 Child 8 will create 46 directories.
  55 Sat Apr 30 14:03:04 2011 Child 6 will create 46 directories.
  56 Sat Apr 30 14:03:04 2011 Child 12 will create 46 directories.
  57 Sat Apr 30 14:03:04 2011 Child 13 finished creating 46 directories.
  58 Sat Apr 30 14:03:04 2011 Child 13 will create 1386 files.
  59 Sat Apr 30 14:03:04 2011 Child 8 finished creating 46 directories.
  60 Sat Apr 30 14:03:04 2011 Child 8 will create 1386 files.
  61 Sat Apr 30 14:03:04 2011 Child 9 finished creating 46 directories.
  62 Sat Apr 30 14:03:04 2011 Child 9 will create 1386 files.
  63 Sat Apr 30 14:03:05 2011 Child 11 finished creating 46 directories.
  64 Sat Apr 30 14:03:05 2011 Child 11 will create 1386 files.
  65 Sat Apr 30 14:03:05 2011 Child 12 finished creating 46 directories.
  66 Sat Apr 30 14:03:05 2011 Child 12 will create 1386 files.
  67 Sat Apr 30 14:03:05 2011 Child 6 finished creating 46 directories.
  68 Sat Apr 30 14:03:05 2011 Child 6 will create 1386 files.
  69 Sat Apr 30 14:03:05 2011 Child 5 finished creating 46 directories.
  70 Sat Apr 30 14:03:05 2011 Child 5 will create 1386 files.
  71 Sat Apr 30 14:03:05 2011 Child 4 finished creating 46 directories.
  72 Sat Apr 30 14:03:05 2011 Child 4 will create 1386 files.
  73 Sat Apr 30 14:03:05 2011 Child 1 finished creating 46 directories.
  74 Sat Apr 30 14:03:05 2011 Child 1 will create 1386 files.
  75 Sat Apr 30 14:03:05 2011 Child 3 finished creating 46 directories.
  76 Sat Apr 30 14:03:05 2011 Child 3 will create 1386 files.
  77 Sat Apr 30 14:03:05 2011 Child 10 finished creating 46 directories.
  78 Sat Apr 30 14:03:05 2011 Child 10 will create 1386 files.
  79 Sat Apr 30 14:03:05 2011 Child 0 finished creating 46 directories.
  80 Sat Apr 30 14:03:05 2011 Child 0 will create 1386 files.
  81 Sat Apr 30 14:03:05 2011 Child 2 finished creating 46 directories.
  82 Sat Apr 30 14:03:05 2011 Child 2 will create 1386 files.
  83 Sat Apr 30 14:03:05 2011 Child 15 finished creating 46 directories.
  84 Sat Apr 30 14:03:05 2011 Child 15 will create 1386 files.
  85 Sat Apr 30 14:03:05 2011 Child 14 finished creating 46 directories.
  86 Sat Apr 30 14:03:05 2011 Child 14 will create 1386 files.
  87 Sat Apr 30 14:03:05 2011 Child 7 finished creating 46 directories.
  88 Sat Apr 30 14:03:05 2011 Child 7 will create 1386 files.
  89 Sat Apr 30 14:08:05 2011 Child 13 verified 883 files (883 creates).
  90 Sat Apr 30 14:08:06 2011 Child 8 verified 660 files (660 creates).
  91 Sat Apr 30 14:08:06 2011 Child 12 verified 663 files (663 creates).
  92 Sat Apr 30 14:08:06 2011 Child 11 verified 752 files (752 creates).
  93 Sat Apr 30 14:08:06 2011 Child 6 verified 755 files (755 creates).
  94 Sat Apr 30 14:08:06 2011 Child 1 verified 833 files (833 creates).
  95 Sat Apr 30 14:08:06 2011 Child 3 verified 809 files (809 creates).
  96 Sat Apr 30 14:08:06 2011 Child 0 verified 842 files (842 creates).
  97 Sat Apr 30 14:08:06 2011 Child 10 verified 655 files (655 creates).
  98 Sat Apr 30 14:08:06 2011 Child 5 verified 842 files (842 creates).
  99 Sat Apr 30 14:08:06 2011 Child 9 verified 783 files (783 creates).
 100 Sat Apr 30 14:08:06 2011 Child 2 verified 773 files (773 creates).
 101 Sat Apr 30 14:08:06 2011 Child 14 verified 670 files (670 creates).
 102 Sat Apr 30 14:08:06 2011 Child 15 verified 759 files (759 creates).
 103 Sat Apr 30 14:08:06 2011 Child 7 verified 895 files (895 creates).
 104 Sat Apr 30 14:08:07 2011 Child 4 verified 857 files (857 creates).
 105 Sat Apr 30 14:10:03 2011 Child 13 verified 1386 files, created 1373
 106 Sat Apr 30 14:10:03 2011 Child 13 will create 2 symlinks.
 107 Sat Apr 30 14:10:03 2011 Child 13 finished creating 2 symlinks.
 108 Sat Apr 30 14:10:04 2011 Child 7 verified 1386 files, created 1373
 109 Sat Apr 30 14:10:04 2011 Child 7 will create 2 symlinks.
 110 Sat Apr 30 14:10:04 2011 Child 7 finished creating 2 symlinks.
 111 Sat Apr 30 14:10:25 2011 Child 4 verified 1386 files, created 1373
 112 Sat Apr 30 14:10:25 2011 Child 4 will create 2 symlinks.
 113 Sat Apr 30 14:10:25 2011 Child 4 finished creating 2 symlinks.
 114 Sat Apr 30 14:10:49 2011 Child 0 verified 1386 files, created 1373
 115 Sat Apr 30 14:10:49 2011 Child 0 will create 2 symlinks.
 116 Sat Apr 30 14:10:49 2011 Child 0 finished creating 2 symlinks.
 117 Sat Apr 30 14:10:49 2011 Child 1 verified 1386 files, created 1373
 118 Sat Apr 30 14:10:49 2011 Child 1 will create 2 symlinks.
 119 Sat Apr 30 14:10:49 2011 Child 1 finished creating 2 symlinks.
 120 Sat Apr 30 14:10:56 2011 Child 11 verified 1386 files, created 1373
 121 Sat Apr 30 14:10:56 2011 Child 11 will create 2 symlinks.
 122 Sat Apr 30 14:10:56 2011 Child 11 finished creating 2 symlinks.
 123 Sat Apr 30 14:11:00 2011 Child 9 verified 1386 files, created 1373
 124 Sat Apr 30 14:11:00 2011 Child 9 will create 2 symlinks.
 125 Sat Apr 30 14:11:00 2011 Child 9 finished creating 2 symlinks.
 126 Sat Apr 30 14:11:00 2011 Child 5 verified 1386 files, created 1373
 127 Sat Apr 30 14:11:00 2011 Child 5 will create 2 symlinks.
 128 Sat Apr 30 14:11:00 2011 Child 5 finished creating 2 symlinks.
 129 Sat Apr 30 14:11:00 2011 Child 6 verified 1386 files, created 1373
 130 Sat Apr 30 14:11:00 2011 Child 6 will create 2 symlinks.
 131 Sat Apr 30 14:11:00 2011 Child 3 verified 1386 files, created 1373
 132 Sat Apr 30 14:11:00 2011 Child 3 will create 2 symlinks.
 133 Sat Apr 30 14:11:00 2011 Child 15 verified 1386 files, created 1373
 134 Sat Apr 30 14:11:00 2011 Child 15 will create 2 symlinks.
 135 Sat Apr 30 14:11:00 2011 Child 3 finished creating 2 symlinks.
 136 Sat Apr 30 14:11:00 2011 Child 6 finished creating 2 symlinks.
 137 Sat Apr 30 14:11:00 2011 Child 15 finished creating 2 symlinks.
 138 Sat Apr 30 14:11:00 2011 Child 2 verified 1386 files, created 1373
 139 Sat Apr 30 14:11:00 2011 Child 2 will create 2 symlinks.
 140 Sat Apr 30 14:11:00 2011 Child 2 finished creating 2 symlinks.
 141 Sat Apr 30 14:11:01 2011 Child 12 verified 1386 files, created 1373
 142 Sat Apr 30 14:11:01 2011 Child 12 will create 2 symlinks.
 143 Sat Apr 30 14:11:01 2011 Child 12 finished creating 2 symlinks.
 144 Sat Apr 30 14:11:03 2011 Child 8 verified 1386 files, created 1373
 145 Sat Apr 30 14:11:03 2011 Child 8 will create 2 symlinks.
 146 Sat Apr 30 14:11:03 2011 Child 14 verified 1386 files, created 1373
 147 Sat Apr 30 14:11:03 2011 Child 14 will create 2 symlinks.
 148 Sat Apr 30 14:11:03 2011 Child 10 verified 1386 files, created 1373
 149 Sat Apr 30 14:11:03 2011 Child 10 will create 2 symlinks.
 150 Sat Apr 30 14:11:03 2011 Child 8 finished creating 2 symlinks.
 151 Sat Apr 30 14:11:03 2011 Child 14 finished creating 2 symlinks.
 152 Sat Apr 30 14:11:03 2011 Child 10 finished creating 2 symlinks.
 153 Sat Apr 30 14:11:03 2011 Completed.
 154 Sat Apr 30 14:11:03 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 155 Sat Apr 30 14:11:03 2011 Completed.
 156 Sat Apr 30 14:11:03 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 157 Sat Apr 30 14:11:04 2011 Received.
 158 Sat Apr 30 14:11:04 2011 Performing 300 seconds pretest warmup.
 159 Sat Apr 30 14:16:04 2011 Completed.
 160 Sat Apr 30 14:16:04 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 161 Sat Apr 30 14:16:04 2011 Completed.
 162 Sat Apr 30 14:16:04 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 163 Sat Apr 30 14:16:05 2011 Received.
 164 Sat Apr 30 14:16:05 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 14:21:15 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%     7750     7750      0      0.19     0.05      0.00  5.6%
 175 setattr      4.0%     4.0%     1189     1189      0      0.93     1.01      0.06  4.3%
 176 lookup      24.0%    23.7%     7102     7102      0      0.19     0.04      0.00  5.3%
 177 readlink     1.0%     1.0%      302      302      0      0.19     0.02      0.02  0.2%
 178 read        18.0%    18.1%     5440     6645      0      1.38     3.30      0.05 28.9%
 179 write       10.0%    10.2%     3052     3705      0      3.11    11.63      0.12 36.5%
 180 create       1.0%     1.0%      302      302      0      1.89     1.26      0.13  2.2%
 181 remove       1.0%     1.0%      296      296      0      1.26     1.20      0.12  1.4%
 182 readdir      1.0%     1.0%      287      287      0      0.35     0.04      0.02  0.4%
 183 fsstat       1.0%     1.0%      293      293      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.4%
 185 commit       0.0%     0.0%        0     1342      0      0.00     0.00      0.00 11.3%
 186 readdirplus  2.0%     2.1%      628      628      0      0.55     0.05      0.02  1.3%
 187 --------------------------------------------------------------------------------------
 188
 189       --------------------------------------------------
 190       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 191       --------------------------+-----------------------
 192 SFS NFS V3 THROUGHPUT:   99.93 Ops/Sec   AVG. RESPONSE TIME:    0.86 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:  29980          TEST TIME: 300 Sec
 198 TOTAL PHYSICAL NFS V3 OPERATIONS: 33180
 199 PHYSICAL NFS V3 THROUGHPUT:  110.60 Ops/Sec
 200 FILE SET SIZE CREATED: 11537568.0 KB
 201 FILE SET SIZE ACCESSED: 3466368.0 - 3536571.0 KB  (100.00% to 102.03% of Base)
 202
 203 ------------------------------------------------------------------------
 204
 205 Sat Apr 30 14:21:15 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 14:21:15 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 224 Sat Apr 30 14:21:15 2011 Completed.
 225 Sat Apr 30 14:21:15 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 226
 227 ************************************************************************
 228 Sat Apr 30 14:21:16 2011 Received.
 229 Sat Apr 30 14:21:16 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 230 Sat Apr 30 14:21:16 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 14:21:18 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 14:21:18 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 14:21:32 2011 Completed.
 265 Sat Apr 30 14:21:32 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 266 Sat Apr 30 14:21:32 2011 Completed.
 267 Sat Apr 30 14:21:32 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 268 Sat Apr 30 14:21:34 2011 Received.
 269
 270 Sat Apr 30 14:21:34 2011 Initializing test directories.
 271 Sat Apr 30 14:21:34 2011 Child 14 will create 95 directories.
 272 Sat Apr 30 14:21:34 2011 Child 8 will create 95 directories.
 273 Sat Apr 30 14:21:34 2011 Child 11 will create 95 directories.
 274 Sat Apr 30 14:21:34 2011 Child 2 will create 95 directories.
 275 Sat Apr 30 14:21:34 2011 Child 6 will create 95 directories.
 276 Sat Apr 30 14:21:34 2011 Child 7 will create 95 directories.
 277 Sat Apr 30 14:21:34 2011 Child 4 will create 95 directories.
 278 Sat Apr 30 14:21:34 2011 Child 3 will create 95 directories.
 279 Sat Apr 30 14:21:34 2011 Child 10 will create 95 directories.
 280 Sat Apr 30 14:21:34 2011 Child 0 will create 95 directories.
 281 Sat Apr 30 14:21:34 2011 Child 1 will create 95 directories.
 282 Sat Apr 30 14:21:34 2011 Child 15 will create 95 directories.
 283 Sat Apr 30 14:21:34 2011 Child 13 will create 95 directories.
 284 Sat Apr 30 14:21:34 2011 Child 9 will create 95 directories.
 285 Sat Apr 30 14:21:34 2011 Child 12 will create 95 directories.
 286 Sat Apr 30 14:21:34 2011 Child 5 will create 95 directories.
 287 Sat Apr 30 14:21:35 2011 Child 1 finished creating 95 directories.
 288 Sat Apr 30 14:21:35 2011 Child 1 will create 2897 files.
 289 Sat Apr 30 14:21:35 2011 Child 15 finished creating 95 directories.
 290 Sat Apr 30 14:21:35 2011 Child 15 will create 2897 files.
 291 Sat Apr 30 14:21:35 2011 Child 0 finished creating 95 directories.
 292 Sat Apr 30 14:21:35 2011 Child 0 will create 2897 files.
 293 Sat Apr 30 14:21:35 2011 Child 3 finished creating 95 directories.
 294 Sat Apr 30 14:21:35 2011 Child 3 will create 2897 files.
 295 Sat Apr 30 14:21:35 2011 Child 12 finished creating 95 directories.
 296 Sat Apr 30 14:21:35 2011 Child 12 will create 2897 files.
 297 Sat Apr 30 14:21:35 2011 Child 5 finished creating 95 directories.
 298 Sat Apr 30 14:21:35 2011 Child 5 will create 2897 files.
 299 Sat Apr 30 14:21:36 2011 Child 11 finished creating 95 directories.
 300 Sat Apr 30 14:21:36 2011 Child 11 will create 2897 files.
 301 Sat Apr 30 14:21:36 2011 Child 13 finished creating 95 directories.
 302 Sat Apr 30 14:21:36 2011 Child 13 will create 2897 files.
 303 Sat Apr 30 14:21:39 2011 Child 7 finished creating 95 directories.
 304 Sat Apr 30 14:21:39 2011 Child 7 will create 2897 files.
 305 Sat Apr 30 14:21:39 2011 Child 9 finished creating 95 directories.
 306 Sat Apr 30 14:21:39 2011 Child 9 will create 2897 files.
 307 Sat Apr 30 14:21:39 2011 Child 14 finished creating 95 directories.
 308 Sat Apr 30 14:21:39 2011 Child 14 will create 2897 files.
 309 Sat Apr 30 14:21:39 2011 Child 10 finished creating 95 directories.
 310 Sat Apr 30 14:21:39 2011 Child 10 will create 2897 files.
 311 Sat Apr 30 14:21:39 2011 Child 2 finished creating 95 directories.
 312 Sat Apr 30 14:21:39 2011 Child 2 will create 2897 files.
 313 Sat Apr 30 14:21:39 2011 Child 4 finished creating 95 directories.
 314 Sat Apr 30 14:21:39 2011 Child 4 will create 2897 files.
 315 Sat Apr 30 14:21:39 2011 Child 8 finished creating 95 directories.
 316 Sat Apr 30 14:21:39 2011 Child 8 will create 2897 files.
 317 Sat Apr 30 14:21:40 2011 Child 6 finished creating 95 directories.
 318 Sat Apr 30 14:21:40 2011 Child 6 will create 2897 files.
 319 Sat Apr 30 14:26:36 2011 Child 1 verified 1850 files (492 creates).
 320 Sat Apr 30 14:26:36 2011 Child 0 verified 1851 files (493 creates).
 321 Sat Apr 30 14:26:36 2011 Child 15 verified 1850 files (492 creates).
 322 Sat Apr 30 14:26:36 2011 Child 3 verified 1851 files (493 creates).
 323 Sat Apr 30 14:26:37 2011 Child 5 verified 1860 files (502 creates).
 324 Sat Apr 30 14:26:42 2011 Child 11 verified 1784 files (426 creates).
 325 Sat Apr 30 14:26:42 2011 Child 14 verified 1760 files (402 creates).
 326 Sat Apr 30 14:26:42 2011 Child 7 verified 1733 files (375 creates).
 327 Sat Apr 30 14:26:42 2011 Child 9 verified 1784 files (426 creates).
 328 Sat Apr 30 14:26:42 2011 Child 10 verified 1780 files (422 creates).
 329 Sat Apr 30 14:26:42 2011 Child 13 verified 1780 files (422 creates).
 330 Sat Apr 30 14:26:42 2011 Child 4 verified 1780 files (422 creates).
 331 Sat Apr 30 14:26:42 2011 Child 6 verified 1766 files (408 creates).
 332 Sat Apr 30 14:26:43 2011 Child 2 verified 1751 files (393 creates).
 333 Sat Apr 30 14:27:11 2011 Child 8 verified 1774 files (416 creates).
 334 Sat Apr 30 14:27:15 2011 Child 12 verified 1822 files (464 creates).
 335 Sat Apr 30 14:31:45 2011 Child 5 verified 2482 files (1124 creates).
 336 Sat Apr 30 14:31:45 2011 Child 2 verified 2260 files (902 creates).
 337 Sat Apr 30 14:31:45 2011 Child 6 verified 2260 files (902 creates).
 338 Sat Apr 30 14:31:51 2011 Child 14 verified 2310 files (952 creates).
 339 Sat Apr 30 14:31:51 2011 Child 7 verified 2310 files (952 creates).
 340 Sat Apr 30 14:31:51 2011 Child 3 verified 2448 files (1090 creates).
 341 Sat Apr 30 14:31:55 2011 Child 10 verified 2378 files (1020 creates).
 342 Sat Apr 30 14:31:55 2011 Child 0 verified 2448 files (1090 creates).
 343 Sat Apr 30 14:31:55 2011 Child 4 verified 2378 files (1020 creates).
 344 Sat Apr 30 14:31:55 2011 Child 11 verified 2448 files (1090 creates).
 345 Sat Apr 30 14:31:55 2011 Child 12 verified 2448 files (1090 creates).
 346 Sat Apr 30 14:31:55 2011 Child 13 verified 2378 files (1020 creates).
 347 Sat Apr 30 14:31:55 2011 Child 9 verified 2404 files (1046 creates).
 348 Sat Apr 30 14:31:55 2011 Child 15 verified 2404 files (1046 creates).
 349 Sat Apr 30 14:31:55 2011 Child 1 verified 2404 files (1046 creates).
 350 Sat Apr 30 14:32:22 2011 Child 8 verified 2370 files (1012 creates).
 351 Sat Apr 30 14:36:31 2011 Child 5 verified 2897 files, created 1511
 352 Sat Apr 30 14:36:31 2011 Child 5 will create 2 symlinks.
 353 Sat Apr 30 14:36:36 2011 Child 5 finished creating 2 symlinks.
 354 Sat Apr 30 14:36:52 2011 Child 2 verified 2539 files (1181 creates).
 355 Sat Apr 30 14:36:52 2011 Child 6 verified 2539 files (1181 creates).
 356 Sat Apr 30 14:36:52 2011 Child 14 verified 2617 files (1259 creates).
 357 Sat Apr 30 14:36:52 2011 Child 3 verified 2817 files (1459 creates).
 358 Sat Apr 30 14:36:54 2011 Child 15 verified 2713 files (1355 creates).
 359 Sat Apr 30 14:36:54 2011 Child 12 verified 2782 files (1424 creates).
 360 Sat Apr 30 14:36:54 2011 Child 1 verified 2715 files (1357 creates).
 361 Sat Apr 30 14:36:54 2011 Child 13 verified 2656 files (1298 creates).
 362 Sat Apr 30 14:36:55 2011 Child 9 verified 2800 files (1442 creates).
 363 Sat Apr 30 14:36:56 2011 Child 10 verified 2678 files (1320 creates).
 364 Sat Apr 30 14:36:56 2011 Child 0 verified 2837 files (1479 creates).
 365 Sat Apr 30 14:36:59 2011 Child 4 verified 2680 files (1322 creates).
 366 Sat Apr 30 14:36:59 2011 Child 7 verified 2542 files (1184 creates).
 367 Sat Apr 30 14:37:04 2011 Child 3 verified 2897 files, created 1511
 368 Sat Apr 30 14:37:04 2011 Child 3 will create 2 symlinks.
 369 Sat Apr 30 14:37:04 2011 Child 3 finished creating 2 symlinks.
 370 Sat Apr 30 14:37:05 2011 Child 11 verified 2897 files, created 1511
 371 Sat Apr 30 14:37:05 2011 Child 11 will create 2 symlinks.
 372 Sat Apr 30 14:37:05 2011 Child 11 finished creating 2 symlinks.
 373 Sat Apr 30 14:37:23 2011 Child 8 verified 2725 files (1367 creates).
 374 Sat Apr 30 14:37:26 2011 Child 0 verified 2897 files, created 1511
 375 Sat Apr 30 14:37:26 2011 Child 0 will create 2 symlinks.
 376 Sat Apr 30 14:37:26 2011 Child 0 finished creating 2 symlinks.
 377 Sat Apr 30 14:37:30 2011 Child 1 verified 2897 files, created 1511
 378 Sat Apr 30 14:37:30 2011 Child 1 will create 2 symlinks.
 379 Sat Apr 30 14:37:31 2011 Child 1 finished creating 2 symlinks.
 380 Sat Apr 30 14:37:31 2011 Child 4 verified 2897 files, created 1511
 381 Sat Apr 30 14:37:31 2011 Child 4 will create 2 symlinks.
 382 Sat Apr 30 14:37:32 2011 Child 4 finished creating 2 symlinks.
 383 Sat Apr 30 14:37:32 2011 Child 13 verified 2897 files, created 1511
 384 Sat Apr 30 14:37:32 2011 Child 13 will create 2 symlinks.
 385 Sat Apr 30 14:37:34 2011 Child 13 finished creating 2 symlinks.
 386 Sat Apr 30 14:37:36 2011 Child 15 verified 2897 files, created 1511
 387 Sat Apr 30 14:37:36 2011 Child 15 will create 2 symlinks.
 388 Sat Apr 30 14:37:36 2011 Child 15 finished creating 2 symlinks.
 389 Sat Apr 30 14:37:37 2011 Child 8 verified 2897 files, created 1511
 390 Sat Apr 30 14:37:37 2011 Child 8 will create 2 symlinks.
 391 Sat Apr 30 14:37:37 2011 Child 8 finished creating 2 symlinks.
 392 Sat Apr 30 14:37:37 2011 Child 14 verified 2897 files, created 1511
 393 Sat Apr 30 14:37:37 2011 Child 14 will create 2 symlinks.
 394 Sat Apr 30 14:37:42 2011 Child 12 verified 2897 files, created 1511
 395 Sat Apr 30 14:37:42 2011 Child 12 will create 2 symlinks.
 396 Sat Apr 30 14:37:42 2011 Child 12 finished creating 2 symlinks.
 397 Sat Apr 30 14:37:42 2011 Child 7 verified 2897 files, created 1511
 398 Sat Apr 30 14:37:42 2011 Child 7 will create 2 symlinks.
 399 Sat Apr 30 14:37:42 2011 Child 7 finished creating 2 symlinks.
 400 Sat Apr 30 14:37:42 2011 Child 14 finished creating 2 symlinks.
 401 Sat Apr 30 14:37:45 2011 Child 6 verified 2897 files, created 1511
 402 Sat Apr 30 14:37:45 2011 Child 6 will create 2 symlinks.
 403 Sat Apr 30 14:37:45 2011 Child 2 verified 2897 files, created 1511
 404 Sat Apr 30 14:37:45 2011 Child 2 will create 2 symlinks.
 405 Sat Apr 30 14:37:45 2011 Child 2 finished creating 2 symlinks.
 406 Sat Apr 30 14:37:45 2011 Child 6 finished creating 2 symlinks.
 407 Sat Apr 30 14:37:46 2011 Child 9 verified 2897 files, created 1511
 408 Sat Apr 30 14:37:46 2011 Child 9 will create 2 symlinks.
 409 Sat Apr 30 14:37:46 2011 Child 9 finished creating 2 symlinks.
 410 Sat Apr 30 14:37:50 2011 Child 10 verified 2897 files, created 1511
 411 Sat Apr 30 14:37:50 2011 Child 10 will create 2 symlinks.
 412 Sat Apr 30 14:37:50 2011 Child 10 finished creating 2 symlinks.
 413 Sat Apr 30 14:37:50 2011 Completed.
 414 Sat Apr 30 14:37:50 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 415 Sat Apr 30 14:37:50 2011 Completed.
 416 Sat Apr 30 14:37:50 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 417 Sat Apr 30 14:37:50 2011 Received.
 418 Sat Apr 30 14:37:50 2011 Performing 300 seconds pretest warmup.
 419 Sat Apr 30 14:42:50 2011 Completed.
 420 Sat Apr 30 14:42:50 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 421 Sat Apr 30 14:42:50 2011 Completed.
 422 Sat Apr 30 14:42:50 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 423 Sat Apr 30 14:42:51 2011 Received.
 424 Sat Apr 30 14:42:51 2011 Starting 300 seconds test run.
 425 SFS Single Client (c09-44) Results, Sat Apr 30 14:48:01 2011
 426 --------------------------------------------------------------------------------------
 427 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 428 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 429 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 430            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 431 --------------------------------------------------------------------------------------
 432 getattr     26.0%    26.1%    15635    15635      0      0.19     0.05      0.00  4.6%
 433 setattr      4.0%     4.1%     2444     2444      0      1.26     1.72      0.05  4.7%
 434 lookup      24.0%    23.8%    14305    14305      0      0.20     0.05      0.00  4.3%
 435 readlink     1.0%     1.0%      621      621      0      0.20     0.09      0.02  0.2%
 436 read        18.0%    18.1%    10850    13436      0      2.24     5.34      0.04 37.3%
 437 write       10.0%     9.9%     5959     7275      0      3.27     9.44      0.08 29.9%
 438 create       1.0%     1.0%      599      599      0      2.92     3.83      0.16  2.7%
 439 remove       1.0%     1.0%      619      619      0      2.08     2.44      0.12  2.0%
 440 readdir      1.0%     1.0%      605      605      0      0.40     0.08      0.02  0.4%
 441 fsstat       1.0%     1.0%      615      615      0      0.19     0.04      0.02  0.2%
 442 access      11.0%    10.9%     6541     6541      0      0.19     0.05      0.01  1.9%
 443 commit       0.0%     0.0%        0     2604      0      0.00     0.00      0.00 10.6%
 444 readdirplus  2.0%     2.0%     1205     1205      0      0.63     0.07      0.01  1.2%
 445 --------------------------------------------------------------------------------------
 446
 447       --------------------------------------------------
 448       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 449       --------------------------+-----------------------
 450 SFS NFS V3 THROUGHPUT:  199.99 Ops/Sec   AVG. RESPONSE TIME:    1.08 Msec/Op
 451 TCP PROTOCOL
 452 FAST CALLS: 0
 453 NFS V3 MIXFILE: [ SFS Default ]
 454 CLIENT REQUESTED LOAD: 200 Ops/Sec
 455 TOTAL LOGICAL NFS V3 OPERATIONS:  59998          TEST TIME: 300 Sec
 456 TOTAL PHYSICAL NFS V3 OPERATIONS: 66504
 457 PHYSICAL NFS V3 THROUGHPUT:  221.68 Ops/Sec
 458 FILE SET SIZE CREATED: 24128640.0 KB
 459 FILE SET SIZE ACCESSED: 7247088.0 - 7391763.0 KB  (100.00% to 102.00% of Base)
 460
 461 ------------------------------------------------------------------------
 462
 463 Sat Apr 30 14:48:01 2011 Completed.
 464
 465 Client Test Parameters:
 466         Number of processes = 16
 467         Requested Load (NFS V3 operations/second) = 200
 468         Maximum number of outstanding biod writes = 8
 469         Maximum number of outstanding biod reads = 8
 470         Warm-up time (seconds) = 300
 471         Run time (seconds) = 300
 472         File Set = 45440 Files created for I/O operations
 473                    13632 Files accessed for I/O operations
 474                     912 Files for non-I/O operations
 475                      32 Symlinks
 476                    1504 Directories
 477                         Additional non-I/O files created as necessary
 478
 479
 480 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 481 Sat Apr 30 14:48:01 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 482 Sat Apr 30 14:48:01 2011 Completed.
 483 Sat Apr 30 14:48:01 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 484
 485 ************************************************************************
 486 Sat Apr 30 14:48:02 2011 Received.
 487 Sat Apr 30 14:48:02 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 488 Sat Apr 30 14:48:02 2011 Completed.
 489 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
 490 NFS V3 Protocol Version
 491
 492 ************************************************************************
 493 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 494 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 495
 496 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 497 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 498
 499 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 14:48:04 2011
 500         Client hostname = c09-44
 501         Prime Client hostname = c09-44.sysnet.ucsd.edu
 502
 503 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 504 NFS V3 Protocol
 505 Sat Apr 30 14:48:04 2011 Mounting 16 remote test directories.
 506 Child[000]: NFS negotiated block size 32768
 507 Child[001]: NFS negotiated block size 32768
 508 Child[002]: NFS negotiated block size 32768
 509 Child[003]: NFS negotiated block size 32768
 510 Child[004]: NFS negotiated block size 32768
 511 Child[005]: NFS negotiated block size 32768
 512 Child[006]: NFS negotiated block size 32768
 513 Child[007]: NFS negotiated block size 32768
 514 Child[008]: NFS negotiated block size 32768
 515 Child[009]: NFS negotiated block size 32768
 516 Child[010]: NFS negotiated block size 32768
 517 Child[011]: NFS negotiated block size 32768
 518 Child[012]: NFS negotiated block size 32768
 519 Child[013]: NFS negotiated block size 32768
 520 Child[014]: NFS negotiated block size 32768
 521 Child[015]: NFS negotiated block size 32768
 522 Sat Apr 30 14:48:18 2011 Completed.
 523 Sat Apr 30 14:48:18 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 524 Sat Apr 30 14:48:18 2011 Completed.
 525 Sat Apr 30 14:48:18 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 526 Sat Apr 30 14:48:20 2011 Received.
 527
 528 Sat Apr 30 14:48:20 2011 Initializing test directories.
 529 Sat Apr 30 14:48:20 2011 Child 15 will create 145 directories.
 530 Sat Apr 30 14:48:20 2011 Child 9 will create 145 directories.
 531 Sat Apr 30 14:48:20 2011 Child 7 will create 145 directories.
 532 Sat Apr 30 14:48:20 2011 Child 14 will create 145 directories.
 533 Sat Apr 30 14:48:20 2011 Child 0 will create 145 directories.
 534 Sat Apr 30 14:48:20 2011 Child 10 will create 145 directories.
 535 Sat Apr 30 14:48:20 2011 Child 6 will create 145 directories.
 536 Sat Apr 30 14:48:20 2011 Child 12 will create 145 directories.
 537 Sat Apr 30 14:48:20 2011 Child 3 will create 145 directories.
 538 Sat Apr 30 14:48:20 2011 Child 13 will create 145 directories.
 539 Sat Apr 30 14:48:20 2011 Child 4 will create 145 directories.
 540 Sat Apr 30 14:48:20 2011 Child 11 will create 145 directories.
 541 Sat Apr 30 14:48:20 2011 Child 8 will create 145 directories.
 542 Sat Apr 30 14:48:20 2011 Child 2 will create 145 directories.
 543 Sat Apr 30 14:48:20 2011 Child 1 will create 145 directories.
 544 Sat Apr 30 14:48:20 2011 Child 5 will create 145 directories.
 545 Sat Apr 30 14:48:21 2011 Child 4 finished creating 145 directories.
 546 Sat Apr 30 14:48:21 2011 Child 4 will create 4408 files.
 547 Sat Apr 30 14:48:21 2011 Child 5 finished creating 145 directories.
 548 Sat Apr 30 14:48:21 2011 Child 5 will create 4408 files.
 549 Sat Apr 30 14:48:21 2011 Child 1 finished creating 145 directories.
 550 Sat Apr 30 14:48:21 2011 Child 1 will create 4408 files.
 551 Sat Apr 30 14:48:22 2011 Child 3 finished creating 145 directories.
 552 Sat Apr 30 14:48:22 2011 Child 3 will create 4408 files.
 553 Sat Apr 30 14:48:22 2011 Child 10 finished creating 145 directories.
 554 Sat Apr 30 14:48:22 2011 Child 10 will create 4408 files.
 555 Sat Apr 30 14:48:23 2011 Child 13 finished creating 145 directories.
 556 Sat Apr 30 14:48:23 2011 Child 13 will create 4408 files.
 557 Sat Apr 30 14:48:23 2011 Child 15 finished creating 145 directories.
 558 Sat Apr 30 14:48:23 2011 Child 15 will create 4408 files.
 559 Sat Apr 30 14:48:24 2011 Child 6 finished creating 145 directories.
 560 Sat Apr 30 14:48:24 2011 Child 6 will create 4408 files.
 561 Sat Apr 30 14:48:26 2011 Child 9 finished creating 145 directories.
 562 Sat Apr 30 14:48:26 2011 Child 9 will create 4408 files.
 563 Sat Apr 30 14:48:26 2011 Child 7 finished creating 145 directories.
 564 Sat Apr 30 14:48:26 2011 Child 7 will create 4408 files.
 565 Sat Apr 30 14:48:29 2011 Child 12 finished creating 145 directories.
 566 Sat Apr 30 14:48:29 2011 Child 12 will create 4408 files.
 567 Sat Apr 30 14:48:30 2011 Child 0 finished creating 145 directories.
 568 Sat Apr 30 14:48:30 2011 Child 0 will create 4408 files.
 569 Sat Apr 30 14:48:30 2011 Child 8 finished creating 145 directories.
 570 Sat Apr 30 14:48:30 2011 Child 8 will create 4408 files.
 571 Sat Apr 30 14:48:30 2011 Child 14 finished creating 145 directories.
 572 Sat Apr 30 14:48:30 2011 Child 14 will create 4408 files.
 573 Sat Apr 30 14:48:31 2011 Child 2 finished creating 145 directories.
 574 Sat Apr 30 14:48:31 2011 Child 2 will create 4408 files.
 575 Sat Apr 30 14:48:31 2011 Child 11 finished creating 145 directories.
 576 Sat Apr 30 14:48:31 2011 Child 11 will create 4408 files.
 577 Sat Apr 30 14:53:45 2011 Child 6 verified 3446 files (606 creates).
 578 Sat Apr 30 14:54:10 2011 Child 8 verified 3237 files (397 creates).
 579 Sat Apr 30 14:54:12 2011 Child 12 verified 3192 files (352 creates).
 580 Sat Apr 30 14:54:14 2011 Child 5 verified 3505 files (665 creates).
 581 Sat Apr 30 14:54:14 2011 Child 1 verified 3544 files (704 creates).
 582 Sat Apr 30 14:54:14 2011 Child 7 verified 3192 files (352 creates).
 583 Sat Apr 30 14:54:14 2011 Child 11 verified 3192 files (352 creates).
 584 Sat Apr 30 14:54:14 2011 Child 14 verified 3192 files (352 creates).
 585 Sat Apr 30 14:54:14 2011 Child 0 verified 3192 files (352 creates).
 586 Sat Apr 30 14:54:14 2011 Child 13 verified 3370 files (530 creates).
 587 Sat Apr 30 14:54:14 2011 Child 3 verified 3370 files (530 creates).
 588 Sat Apr 30 14:54:23 2011 Child 4 verified 3456 files (616 creates).
 589 Sat Apr 30 14:54:23 2011 Child 9 verified 3456 files (616 creates).
 590 Sat Apr 30 14:54:26 2011 Child 15 verified 3456 files (616 creates).
 591 Sat Apr 30 14:54:26 2011 Child 10 verified 3505 files (665 creates).
 592 Sat Apr 30 14:54:26 2011 Child 2 verified 3370 files (530 creates).
 593 Sat Apr 30 14:59:02 2011 Child 1 verified 4408 files, created 1525
 594 Sat Apr 30 14:59:02 2011 Child 1 will create 2 symlinks.
 595 Sat Apr 30 14:59:02 2011 Child 1 finished creating 2 symlinks.
 596 Sat Apr 30 14:59:10 2011 Child 6 verified 4094 files (1254 creates).
 597 Sat Apr 30 14:59:12 2011 Child 9 verified 4408 files, created 1525
 598 Sat Apr 30 14:59:12 2011 Child 9 will create 2 symlinks.
 599 Sat Apr 30 14:59:12 2011 Child 9 finished creating 2 symlinks.
 600 Sat Apr 30 14:59:12 2011 Child 8 verified 3978 files (1138 creates).
 601 Sat Apr 30 14:59:15 2011 Child 13 verified 4010 files (1170 creates).
 602 Sat Apr 30 14:59:15 2011 Child 5 verified 4181 files (1341 creates).
 603 Sat Apr 30 14:59:15 2011 Child 12 verified 3873 files (1033 creates).
 604 Sat Apr 30 14:59:15 2011 Child 11 verified 3873 files (1033 creates).
 605 Sat Apr 30 14:59:15 2011 Child 7 verified 3850 files (1010 creates).
 606 Sat Apr 30 14:59:15 2011 Child 14 verified 3849 files (1009 creates).
 607 Sat Apr 30 14:59:15 2011 Child 0 verified 3893 files (1053 creates).
 608 Sat Apr 30 14:59:15 2011 Child 3 verified 3874 files (1034 creates).
 609 Sat Apr 30 14:59:29 2011 Child 10 verified 4181 files (1341 creates).
 610 Sat Apr 30 14:59:29 2011 Child 15 verified 4181 files (1341 creates).
 611 Sat Apr 30 14:59:29 2011 Child 2 verified 4085 files (1245 creates).
 612 Sat Apr 30 14:59:29 2011 Child 4 verified 4181 files (1341 creates).
 613 Sat Apr 30 14:59:44 2011 Child 5 verified 4408 files, created 1525
 614 Sat Apr 30 14:59:44 2011 Child 5 will create 2 symlinks.
 615 Sat Apr 30 14:59:44 2011 Child 5 finished creating 2 symlinks.
 616 Sat Apr 30 14:59:50 2011 Child 4 verified 4408 files, created 1525
 617 Sat Apr 30 14:59:50 2011 Child 4 will create 2 symlinks.
 618 Sat Apr 30 14:59:50 2011 Child 10 verified 4408 files, created 1525
 619 Sat Apr 30 14:59:50 2011 Child 10 will create 2 symlinks.
 620 Sat Apr 30 14:59:50 2011 Child 4 finished creating 2 symlinks.
 621 Sat Apr 30 14:59:50 2011 Child 10 finished creating 2 symlinks.
 622 Sat Apr 30 14:59:50 2011 Child 15 verified 4408 files, created 1525
 623 Sat Apr 30 14:59:50 2011 Child 15 will create 2 symlinks.
 624 Sat Apr 30 14:59:50 2011 Child 6 verified 4408 files, created 1525
 625 Sat Apr 30 14:59:50 2011 Child 6 will create 2 symlinks.
 626 Sat Apr 30 14:59:51 2011 Child 15 finished creating 2 symlinks.
 627 Sat Apr 30 14:59:51 2011 Child 6 finished creating 2 symlinks.
 628 Sat Apr 30 14:59:54 2011 Child 13 verified 4408 files, created 1525
 629 Sat Apr 30 14:59:54 2011 Child 13 will create 2 symlinks.
 630 Sat Apr 30 14:59:54 2011 Child 13 finished creating 2 symlinks.
 631 Sat Apr 30 14:59:54 2011 Child 8 verified 4408 files, created 1525
 632 Sat Apr 30 14:59:54 2011 Child 8 will create 2 symlinks.
 633 Sat Apr 30 14:59:54 2011 Child 8 finished creating 2 symlinks.
 634 Sat Apr 30 14:59:54 2011 Child 2 verified 4408 files, created 1525
 635 Sat Apr 30 14:59:54 2011 Child 2 will create 2 symlinks.
 636 Sat Apr 30 14:59:55 2011 Child 2 finished creating 2 symlinks.
 637 Sat Apr 30 15:00:03 2011 Child 0 verified 4408 files, created 1525
 638 Sat Apr 30 15:00:03 2011 Child 0 will create 2 symlinks.
 639 Sat Apr 30 15:00:03 2011 Child 0 finished creating 2 symlinks.
 640 Sat Apr 30 15:00:09 2011 Child 11 verified 4408 files, created 1525
 641 Sat Apr 30 15:00:09 2011 Child 11 will create 2 symlinks.
 642 Sat Apr 30 15:00:09 2011 Child 7 verified 4408 files, created 1525
 643 Sat Apr 30 15:00:09 2011 Child 7 will create 2 symlinks.
 644 Sat Apr 30 15:00:09 2011 Child 12 verified 4408 files, created 1525
 645 Sat Apr 30 15:00:09 2011 Child 12 will create 2 symlinks.
 646 Sat Apr 30 15:00:09 2011 Child 11 finished creating 2 symlinks.
 647 Sat Apr 30 15:00:09 2011 Child 3 verified 4408 files, created 1525
 648 Sat Apr 30 15:00:09 2011 Child 3 will create 2 symlinks.
 649 Sat Apr 30 15:00:09 2011 Child 14 verified 4408 files, created 1525
 650 Sat Apr 30 15:00:09 2011 Child 14 will create 2 symlinks.
 651 Sat Apr 30 15:00:09 2011 Child 7 finished creating 2 symlinks.
 652 Sat Apr 30 15:00:09 2011 Child 12 finished creating 2 symlinks.
 653 Sat Apr 30 15:00:09 2011 Child 14 finished creating 2 symlinks.
 654 Sat Apr 30 15:00:09 2011 Child 3 finished creating 2 symlinks.
 655 Sat Apr 30 15:00:09 2011 Completed.
 656 Sat Apr 30 15:00:09 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 657 Sat Apr 30 15:00:09 2011 Completed.
 658 Sat Apr 30 15:00:09 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 659 Sat Apr 30 15:00:09 2011 Received.
 660 Sat Apr 30 15:00:09 2011 Performing 300 seconds pretest warmup.
 661 Sat Apr 30 15:05:09 2011 Completed.
 662 Sat Apr 30 15:05:09 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 663 Sat Apr 30 15:05:09 2011 Completed.
 664 Sat Apr 30 15:05:09 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 665 Sat Apr 30 15:05:10 2011 Received.
 666 Sat Apr 30 15:05:10 2011 Starting 300 seconds test run.
 667 SFS Single Client (c09-44) Results, Sat Apr 30 15:10:20 2011
 668 --------------------------------------------------------------------------------------
 669 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 670 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 671 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 672            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 673 --------------------------------------------------------------------------------------
 674 getattr     26.0%    26.0%    23380    23380      0      0.20     0.75      0.01  3.8%
 675 setattr      4.0%     4.0%     3599     3599      0      1.83     5.73      0.08  5.3%
 676 lookup      24.0%    24.0%    21644    21644      0      0.22     0.81      0.01  3.7%
 677 readlink     1.0%     1.0%      918      918      0      0.20     0.09      0.02  0.1%
 678 read        18.0%    17.9%    16131    19765      0      3.09    17.87      0.07 40.0%
 679 write       10.0%    10.1%     9127    11155      0      3.84    10.08      0.07 28.2%
 680 create       1.0%     1.0%      938      938      0      4.40     7.95      0.18  3.3%
 681 remove       1.0%     1.0%      869      869      0      2.77     3.06      0.12  1.9%
 682 readdir      1.0%     1.0%      859      859      0      0.41     0.14      0.03  0.3%
 683 fsstat       1.0%     1.0%      927      927      0      0.20     0.11      0.02  0.2%
 684 access      11.0%    11.0%     9865     9865      0      0.21     0.78      0.02  1.6%
 685 commit       0.0%     0.0%        0     4006      0      0.00     0.00      0.00 10.4%
 686 readdirplus  2.0%     2.0%     1779     1779      0      0.69     0.13      0.02  1.0%
 687 --------------------------------------------------------------------------------------
 688
 689       --------------------------------------------------
 690       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 691       --------------------------+-----------------------
 692 SFS NFS V3 THROUGHPUT:  300.12 Ops/Sec   AVG. RESPONSE TIME:    1.38 Msec/Op
 693 TCP PROTOCOL
 694 FAST CALLS: 0
 695 NFS V3 MIXFILE: [ SFS Default ]
 696 CLIENT REQUESTED LOAD: 300 Ops/Sec
 697 TOTAL LOGICAL NFS V3 OPERATIONS:  90036          TEST TIME: 300 Sec
 698 TOTAL PHYSICAL NFS V3 OPERATIONS: 99704
 699 PHYSICAL NFS V3 THROUGHPUT:  332.34 Ops/Sec
 700 FILE SET SIZE CREATED: 36711216.0 KB
 701 FILE SET SIZE ACCESSED: 11019312.0 - 11236578.0 KB  (100.00% to 101.97% of Base)
 702
 703 ------------------------------------------------------------------------
 704
 705 Sat Apr 30 15:10:20 2011 Completed.
 706
 707 Client Test Parameters:
 708         Number of processes = 16
 709         Requested Load (NFS V3 operations/second) = 300
 710         Maximum number of outstanding biod writes = 8
 711         Maximum number of outstanding biod reads = 8
 712         Warm-up time (seconds) = 300
 713         Run time (seconds) = 300
 714         File Set = 69136 Files created for I/O operations
 715                    20736 Files accessed for I/O operations
 716                    1392 Files for non-I/O operations
 717                      32 Symlinks
 718                    2304 Directories
 719                         Additional non-I/O files created as necessary
 720
 721
 722 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 723 Sat Apr 30 15:10:20 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 724 Sat Apr 30 15:10:20 2011 Completed.
 725 Sat Apr 30 15:10:20 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 726
 727 ************************************************************************
 728 Sat Apr 30 15:10:21 2011 Received.
 729 Sat Apr 30 15:10:21 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 730 Sat Apr 30 15:10:21 2011 Completed.
 731 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
 732 NFS V3 Protocol Version
 733
 734 ************************************************************************
 735 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 736 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 737
 738 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 739 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 740
 741 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 15:10:23 2011
 742         Client hostname = c09-44
 743         Prime Client hostname = c09-44.sysnet.ucsd.edu
 744
 745 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 746 NFS V3 Protocol
 747 Sat Apr 30 15:10:23 2011 Mounting 16 remote test directories.
 748 Child[001]: NFS negotiated block size 32768
 749 Child[002]: NFS negotiated block size 32768
 750 Child[003]: NFS negotiated block size 32768
 751 Child[004]: NFS negotiated block size 32768
 752 Child[005]: NFS negotiated block size 32768
 753 Child[006]: NFS negotiated block size 32768
 754 Child[007]: NFS negotiated block size 32768
 755 Child[008]: NFS negotiated block size 32768
 756 Child[009]: NFS negotiated block size 32768
 757 Child[010]: NFS negotiated block size 32768
 758 Child[011]: NFS negotiated block size 32768
 759 Child[012]: NFS negotiated block size 32768
 760 Child[013]: NFS negotiated block size 32768
 761 Child[014]: NFS negotiated block size 32768
 762 Child[015]: NFS negotiated block size 32768
 763 Child[000]: NFS negotiated block size 32768
 764 Sat Apr 30 15:10:39 2011 Completed.
 765 Sat Apr 30 15:10:39 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 766 Sat Apr 30 15:10:39 2011 Completed.
 767 Sat Apr 30 15:10:39 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 768 Sat Apr 30 15:10:39 2011 Received.
 769
 770 Sat Apr 30 15:10:39 2011 Initializing test directories.
 771 Sat Apr 30 15:10:39 2011 Child 15 will create 190 directories.
 772 Sat Apr 30 15:10:39 2011 Child 9 will create 190 directories.
 773 Sat Apr 30 15:10:39 2011 Child 3 will create 190 directories.
 774 Sat Apr 30 15:10:39 2011 Child 14 will create 190 directories.
 775 Sat Apr 30 15:10:39 2011 Child 1 will create 190 directories.
 776 Sat Apr 30 15:10:39 2011 Child 8 will create 190 directories.
 777 Sat Apr 30 15:10:39 2011 Child 0 will create 190 directories.
 778 Sat Apr 30 15:10:39 2011 Child 13 will create 190 directories.
 779 Sat Apr 30 15:10:39 2011 Child 2 will create 190 directories.
 780 Sat Apr 30 15:10:39 2011 Child 4 will create 190 directories.
 781 Sat Apr 30 15:10:39 2011 Child 12 will create 190 directories.
 782 Sat Apr 30 15:10:39 2011 Child 10 will create 190 directories.
 783 Sat Apr 30 15:10:39 2011 Child 5 will create 190 directories.
 784 Sat Apr 30 15:10:39 2011 Child 11 will create 190 directories.
 785 Sat Apr 30 15:10:39 2011 Child 7 will create 190 directories.
 786 Sat Apr 30 15:10:39 2011 Child 6 will create 190 directories.
 787 Sat Apr 30 15:10:40 2011 Child 2 finished creating 190 directories.
 788 Sat Apr 30 15:10:40 2011 Child 2 will create 5793 files.
 789 Sat Apr 30 15:10:41 2011 Child 15 finished creating 190 directories.
 790 Sat Apr 30 15:10:41 2011 Child 15 will create 5793 files.
 791 Sat Apr 30 15:10:42 2011 Child 6 finished creating 190 directories.
 792 Sat Apr 30 15:10:42 2011 Child 6 will create 5793 files.
 793 Sat Apr 30 15:10:43 2011 Child 10 finished creating 190 directories.
 794 Sat Apr 30 15:10:43 2011 Child 10 will create 5793 files.
 795 Sat Apr 30 15:10:44 2011 Child 7 finished creating 190 directories.
 796 Sat Apr 30 15:10:44 2011 Child 7 will create 5793 files.
 797 Sat Apr 30 15:10:45 2011 Child 0 finished creating 190 directories.
 798 Sat Apr 30 15:10:45 2011 Child 0 will create 5793 files.
 799 Sat Apr 30 15:10:45 2011 Child 8 finished creating 190 directories.
 800 Sat Apr 30 15:10:45 2011 Child 8 will create 5793 files.
 801 Sat Apr 30 15:10:45 2011 Child 13 finished creating 190 directories.
 802 Sat Apr 30 15:10:45 2011 Child 13 will create 5793 files.
 803 Sat Apr 30 15:10:52 2011 Child 12 finished creating 190 directories.
 804 Sat Apr 30 15:10:52 2011 Child 12 will create 5793 files.
 805 Sat Apr 30 15:10:52 2011 Child 14 finished creating 190 directories.
 806 Sat Apr 30 15:10:52 2011 Child 14 will create 5793 files.
 807 Sat Apr 30 15:10:52 2011 Child 4 finished creating 190 directories.
 808 Sat Apr 30 15:10:52 2011 Child 4 will create 5793 files.
 809 Sat Apr 30 15:10:52 2011 Child 5 finished creating 190 directories.
 810 Sat Apr 30 15:10:52 2011 Child 5 will create 5793 files.
 811 Sat Apr 30 15:10:54 2011 Child 9 finished creating 190 directories.
 812 Sat Apr 30 15:10:54 2011 Child 9 will create 5793 files.
 813 Sat Apr 30 15:10:54 2011 Child 11 finished creating 190 directories.
 814 Sat Apr 30 15:10:54 2011 Child 11 will create 5793 files.
 815 Sat Apr 30 15:10:55 2011 Child 3 finished creating 190 directories.
 816 Sat Apr 30 15:10:55 2011 Child 3 will create 5793 files.
 817 Sat Apr 30 15:10:55 2011 Child 1 finished creating 190 directories.
 818 Sat Apr 30 15:10:55 2011 Child 1 will create 5793 files.
 819 Sat Apr 30 15:15:44 2011 Child 15 verified 5107 files (786 creates).
 820 Sat Apr 30 15:15:44 2011 Child 2 verified 5275 files (954 creates).
 821 Sat Apr 30 15:15:44 2011 Child 6 verified 5105 files (784 creates).
 822 Sat Apr 30 15:15:45 2011 Child 10 verified 5125 files (804 creates).
 823 Sat Apr 30 15:15:47 2011 Child 13 verified 5011 files (690 creates).
 824 Sat Apr 30 15:15:47 2011 Child 8 verified 4991 files (670 creates).
 825 Sat Apr 30 15:15:48 2011 Child 0 verified 4991 files (670 creates).
 826 Sat Apr 30 15:16:29 2011 Child 3 verified 4777 files (456 creates).
 827 Sat Apr 30 15:16:29 2011 Child 4 verified 4886 files (565 creates).
 828 Sat Apr 30 15:16:29 2011 Child 12 verified 4777 files (456 creates).
 829 Sat Apr 30 15:16:29 2011 Child 9 verified 4879 files (558 creates).
 830 Sat Apr 30 15:16:36 2011 Child 11 verified 4886 files (565 creates).
 831 Sat Apr 30 15:16:36 2011 Child 5 verified 4886 files (565 creates).
 832 Sat Apr 30 15:16:36 2011 Child 14 verified 4777 files (456 creates).
 833 Sat Apr 30 15:16:36 2011 Child 7 verified 4991 files (670 creates).
 834 Sat Apr 30 15:16:37 2011 Child 1 verified 4886 files (565 creates).
 835 Sat Apr 30 15:17:47 2011 Child 2 verified 5793 files, created 1416
 836 Sat Apr 30 15:17:47 2011 Child 2 will create 2 symlinks.
 837 Sat Apr 30 15:17:47 2011 Child 2 finished creating 2 symlinks.
 838 Sat Apr 30 15:18:14 2011 Child 10 verified 5793 files, created 1416
 839 Sat Apr 30 15:18:14 2011 Child 10 will create 2 symlinks.
 840 Sat Apr 30 15:18:14 2011 Child 15 verified 5793 files, created 1416
 841 Sat Apr 30 15:18:14 2011 Child 15 will create 2 symlinks.
 842 Sat Apr 30 15:18:14 2011 Child 10 finished creating 2 symlinks.
 843 Sat Apr 30 15:18:14 2011 Child 15 finished creating 2 symlinks.
 844 Sat Apr 30 15:18:55 2011 Child 6 verified 5793 files, created 1416
 845 Sat Apr 30 15:18:55 2011 Child 6 will create 2 symlinks.
 846 Sat Apr 30 15:18:55 2011 Child 6 finished creating 2 symlinks.
 847 Sat Apr 30 15:18:56 2011 Child 8 verified 5793 files, created 1416
 848 Sat Apr 30 15:18:56 2011 Child 8 will create 2 symlinks.
 849 Sat Apr 30 15:18:56 2011 Child 8 finished creating 2 symlinks.
 850 Sat Apr 30 15:19:01 2011 Child 13 verified 5793 files, created 1416
 851 Sat Apr 30 15:19:01 2011 Child 13 will create 2 symlinks.
 852 Sat Apr 30 15:19:01 2011 Child 13 finished creating 2 symlinks.
 853 Sat Apr 30 15:19:04 2011 Child 0 verified 5793 files, created 1416
 854 Sat Apr 30 15:19:04 2011 Child 0 will create 2 symlinks.
 855 Sat Apr 30 15:19:06 2011 Child 7 verified 5793 files, created 1416
 856 Sat Apr 30 15:19:06 2011 Child 7 will create 2 symlinks.
 857 Sat Apr 30 15:19:06 2011 Child 7 finished creating 2 symlinks.
 858 Sat Apr 30 15:19:06 2011 Child 0 finished creating 2 symlinks.
 859 Sat Apr 30 15:19:26 2011 Child 5 verified 5793 files, created 1416
 860 Sat Apr 30 15:19:26 2011 Child 5 will create 2 symlinks.
 861 Sat Apr 30 15:19:26 2011 Child 5 finished creating 2 symlinks.
 862 Sat Apr 30 15:19:26 2011 Child 4 verified 5793 files, created 1416
 863 Sat Apr 30 15:19:26 2011 Child 4 will create 2 symlinks.
 864 Sat Apr 30 15:19:26 2011 Child 9 verified 5793 files, created 1416
 865 Sat Apr 30 15:19:26 2011 Child 9 will create 2 symlinks.
 866 Sat Apr 30 15:19:26 2011 Child 4 finished creating 2 symlinks.
 867 Sat Apr 30 15:19:26 2011 Child 9 finished creating 2 symlinks.
 868 Sat Apr 30 15:19:27 2011 Child 11 verified 5793 files, created 1416
 869 Sat Apr 30 15:19:27 2011 Child 11 will create 2 symlinks.
 870 Sat Apr 30 15:19:27 2011 Child 11 finished creating 2 symlinks.
 871 Sat Apr 30 15:19:30 2011 Child 1 verified 5793 files, created 1416
 872 Sat Apr 30 15:19:30 2011 Child 1 will create 2 symlinks.
 873 Sat Apr 30 15:19:30 2011 Child 1 finished creating 2 symlinks.
 874 Sat Apr 30 15:19:32 2011 Child 3 verified 5793 files, created 1416
 875 Sat Apr 30 15:19:32 2011 Child 3 will create 2 symlinks.
 876 Sat Apr 30 15:19:32 2011 Child 3 finished creating 2 symlinks.
 877 Sat Apr 30 15:19:32 2011 Child 12 verified 5793 files, created 1416
 878 Sat Apr 30 15:19:32 2011 Child 12 will create 2 symlinks.
 879 Sat Apr 30 15:19:32 2011 Child 12 finished creating 2 symlinks.
 880 Sat Apr 30 15:19:33 2011 Child 14 verified 5793 files, created 1416
 881 Sat Apr 30 15:19:33 2011 Child 14 will create 2 symlinks.
 882 Sat Apr 30 15:19:33 2011 Child 14 finished creating 2 symlinks.
 883 Sat Apr 30 15:19:34 2011 Completed.
 884 Sat Apr 30 15:19:34 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 885 Sat Apr 30 15:19:34 2011 Completed.
 886 Sat Apr 30 15:19:34 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 887 Sat Apr 30 15:19:35 2011 Received.
 888 Sat Apr 30 15:19:35 2011 Performing 300 seconds pretest warmup.
 889 Sat Apr 30 15:24:35 2011 Completed.
 890 Sat Apr 30 15:24:35 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 891 Sat Apr 30 15:24:35 2011 Completed.
 892 Sat Apr 30 15:24:35 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 893 Sat Apr 30 15:24:36 2011 Received.
 894 Sat Apr 30 15:24:36 2011 Starting 300 seconds test run.
 895 SFS Single Client (c09-44) Results, Sat Apr 30 15:29:46 2011
 896 --------------------------------------------------------------------------------------
 897 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 898 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 899 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 900            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 901 --------------------------------------------------------------------------------------
 902 getattr     26.0%    25.9%    31146    31146      0      0.33    13.83      0.04  2.6%
 903 setattr      4.0%     4.0%     4782     4782      0      9.13   108.50      0.30 11.3%
 904 lookup      24.0%    23.8%    28657    28657      0      0.29    10.32      0.04  2.1%
 905 readlink     1.0%     1.0%     1222     1222      0      0.21     0.29      0.03  0.1%
 906 read        18.0%    18.2%    21854    26931      0      5.24    47.52      0.09 29.5%
 907 write       10.0%    10.1%    12129    14909      0     10.18    75.60      0.15 31.8%
 908 create       1.0%     1.0%     1173     1173      0     15.21   135.53      0.67  4.6%
 909 remove       1.0%     1.0%     1208     1208      0     14.11   137.24      0.66  4.4%
 910 readdir      1.0%     1.0%     1184     1184      0      0.42     0.29      0.03  0.1%
 911 fsstat       1.0%     1.0%     1258     1258      0      0.30     3.53      0.10  0.1%
 912 access      11.0%    11.0%    13177    13177      0      0.21     1.24      0.02  0.7%
 913 commit       0.0%     0.0%        0     5333      0      0.00     0.00      0.00 12.2%
 914 readdirplus  2.0%     2.0%     2393     2393      0      0.71     0.14      0.01  0.4%
 915 --------------------------------------------------------------------------------------
 916
 917       --------------------------------------------------
 918       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 919       --------------------------+-----------------------
 920 SFS NFS V3 THROUGHPUT:  400.61 Ops/Sec   AVG. RESPONSE TIME:    3.22 Msec/Op
 921 TCP PROTOCOL
 922 FAST CALLS: 0
 923 NFS V3 MIXFILE: [ SFS Default ]
 924 CLIENT REQUESTED LOAD: 400 Ops/Sec
 925 TOTAL LOGICAL NFS V3 OPERATIONS:  120183         TEST TIME: 300 Sec
 926 TOTAL PHYSICAL NFS V3 OPERATIONS: 133373
 927 PHYSICAL NFS V3 THROUGHPUT:  444.57 Ops/Sec
 928 FILE SET SIZE CREATED: 48248784.0 KB
 929 FILE SET SIZE ACCESSED: 14477184.0 - 14762931.0 KB  (100.00% to 101.97% of Base)
 930
 931 ------------------------------------------------------------------------
 932
 933 Sat Apr 30 15:29:46 2011 Completed.
 934
 935 Client Test Parameters:
 936         Number of processes = 16
 937         Requested Load (NFS V3 operations/second) = 400
 938         Maximum number of outstanding biod writes = 8
 939         Maximum number of outstanding biod reads = 8
 940         Warm-up time (seconds) = 300
 941         Run time (seconds) = 300
 942         File Set = 90864 Files created for I/O operations
 943                    27248 Files accessed for I/O operations
 944                    1824 Files for non-I/O operations
 945                      32 Symlinks
 946                    3024 Directories
 947                         Additional non-I/O files created as necessary
 948
 949
 950 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 951 Sat Apr 30 15:29:46 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 952 Sat Apr 30 15:29:46 2011 Completed.
 953 Sat Apr 30 15:29:46 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 954
 955 ************************************************************************
 956 Sat Apr 30 15:29:47 2011 Received.
 957 Sat Apr 30 15:29:47 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 958 Sat Apr 30 15:29:47 2011 Completed.
 959 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
 960 NFS V3 Protocol Version
 961
 962 ************************************************************************
 963 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 964 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 965
 966 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 967 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 968
 969 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 15:29:49 2011
 970         Client hostname = c09-44
 971         Prime Client hostname = c09-44.sysnet.ucsd.edu
 972
 973 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 974 NFS V3 Protocol
 975 Sat Apr 30 15:29:49 2011 Mounting 16 remote test directories.
 976 Child[000]: NFS negotiated block size 32768
 977 Child[001]: NFS negotiated block size 32768
 978 Child[002]: NFS negotiated block size 32768
 979 Child[003]: NFS negotiated block size 32768
 980 Child[004]: NFS negotiated block size 32768
 981 Child[005]: NFS negotiated block size 32768
 982 Child[006]: NFS negotiated block size 32768
 983 Child[007]: NFS negotiated block size 32768
 984 Child[008]: NFS negotiated block size 32768
 985 Child[009]: NFS negotiated block size 32768
 986 Child[010]: NFS negotiated block size 32768
 987 Child[011]: NFS negotiated block size 32768
 988 Child[012]: NFS negotiated block size 32768
 989 Child[013]: NFS negotiated block size 32768
 990 Child[014]: NFS negotiated block size 32768
 991 Child[015]: NFS negotiated block size 32768
 992 Sat Apr 30 15:30:03 2011 Completed.
 993 Sat Apr 30 15:30:03 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 994 Sat Apr 30 15:30:03 2011 Completed.
 995 Sat Apr 30 15:30:03 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 996 Sat Apr 30 15:30:05 2011 Received.
 997
 998 Sat Apr 30 15:30:05 2011 Initializing test directories.
 999 Sat Apr 30 15:30:05 2011 Child 15 will create 239 directories.
1000 Sat Apr 30 15:30:05 2011 Child 14 will create 239 directories.
1001 Sat Apr 30 15:30:05 2011 Child 0 will create 239 directories.
1002 Sat Apr 30 15:30:05 2011 Child 13 will create 239 directories.
1003 Sat Apr 30 15:30:05 2011 Child 10 will create 239 directories.
1004 Sat Apr 30 15:30:05 2011 Child 1 will create 239 directories.
1005 Sat Apr 30 15:30:05 2011 Child 11 will create 239 directories.
1006 Sat Apr 30 15:30:05 2011 Child 6 will create 239 directories.
1007 Sat Apr 30 15:30:05 2011 Child 2 will create 239 directories.
1008 Sat Apr 30 15:30:05 2011 Child 8 will create 239 directories.
1009 Sat Apr 30 15:30:05 2011 Child 12 will create 239 directories.
1010 Sat Apr 30 15:30:05 2011 Child 3 will create 239 directories.
1011 Sat Apr 30 15:30:05 2011 Child 9 will create 239 directories.
1012 Sat Apr 30 15:30:05 2011 Child 4 will create 239 directories.
1013 Sat Apr 30 15:30:05 2011 Child 7 will create 239 directories.
1014 Sat Apr 30 15:30:05 2011 Child 5 will create 239 directories.
1015 Sat Apr 30 15:30:06 2011 Child 2 finished creating 239 directories.
1016 Sat Apr 30 15:30:06 2011 Child 2 will create 7304 files.
1017 Sat Apr 30 15:30:06 2011 Child 3 finished creating 239 directories.
1018 Sat Apr 30 15:30:06 2011 Child 6 finished creating 239 directories.
1019 Sat Apr 30 15:30:06 2011 Child 3 will create 7304 files.
1020 Sat Apr 30 15:30:06 2011 Child 6 will create 7304 files.
1021 Sat Apr 30 15:30:10 2011 Child 10 finished creating 239 directories.
1022 Sat Apr 30 15:30:10 2011 Child 10 will create 7304 files.
1023 Sat Apr 30 15:30:10 2011 Child 12 finished creating 239 directories.
1024 Sat Apr 30 15:30:10 2011 Child 12 will create 7304 files.
1025 Sat Apr 30 15:30:14 2011 Child 15 finished creating 239 directories.
1026 Sat Apr 30 15:30:14 2011 Child 15 will create 7304 files.
1027 Sat Apr 30 15:30:14 2011 Child 9 finished creating 239 directories.
1028 Sat Apr 30 15:30:14 2011 Child 9 will create 7304 files.
1029 Sat Apr 30 15:30:17 2011 Child 11 finished creating 239 directories.
1030 Sat Apr 30 15:30:17 2011 Child 11 will create 7304 files.
1031 Sat Apr 30 15:30:17 2011 Child 4 finished creating 239 directories.
1032 Sat Apr 30 15:30:17 2011 Child 4 will create 7304 files.
1033 Sat Apr 30 15:30:17 2011 Child 0 finished creating 239 directories.
1034 Sat Apr 30 15:30:17 2011 Child 0 will create 7304 files.
1035 Sat Apr 30 15:30:17 2011 Child 1 finished creating 239 directories.
1036 Sat Apr 30 15:30:17 2011 Child 1 will create 7304 files.
1037 Sat Apr 30 15:30:19 2011 Child 7 finished creating 239 directories.
1038 Sat Apr 30 15:30:19 2011 Child 7 will create 7304 files.
1039 Sat Apr 30 15:30:22 2011 Child 5 finished creating 239 directories.
1040 Sat Apr 30 15:30:22 2011 Child 5 will create 7304 files.
1041 Sat Apr 30 15:30:22 2011 Child 13 finished creating 239 directories.
1042 Sat Apr 30 15:30:22 2011 Child 13 will create 7304 files.
1043 Sat Apr 30 15:30:23 2011 Child 14 finished creating 239 directories.
1044 Sat Apr 30 15:30:23 2011 Child 14 will create 7304 files.
1045 Sat Apr 30 15:30:24 2011 Child 8 finished creating 239 directories.
1046 Sat Apr 30 15:30:24 2011 Child 8 will create 7304 files.
1047 Sat Apr 30 15:35:07 2011 Child 2 verified 6832 files (1153 creates).
1048 Sat Apr 30 15:35:08 2011 Child 6 verified 6817 files (1138 creates).
1049 Sat Apr 30 15:35:08 2011 Child 3 verified 6822 files (1143 creates).
1050 Sat Apr 30 15:35:11 2011 Child 10 verified 6582 files (903 creates).
1051 Sat Apr 30 15:35:15 2011 Child 12 verified 6543 files (864 creates).
1052 Sat Apr 30 15:35:15 2011 Child 9 verified 6543 files (864 creates).
1053 Sat Apr 30 15:35:15 2011 Child 15 verified 6418 files (739 creates).
1054 Sat Apr 30 15:35:21 2011 Child 7 verified 6456 files (777 creates).
1055 Sat Apr 30 15:35:21 2011 Child 1 verified 6456 files (777 creates).
1056 Sat Apr 30 15:35:21 2011 Child 11 verified 6450 files (771 creates).
1057 Sat Apr 30 15:35:21 2011 Child 0 verified 6456 files (777 creates).
1058 Sat Apr 30 15:35:21 2011 Child 4 verified 6531 files (852 creates).
1059 Sat Apr 30 15:35:24 2011 Child 14 verified 6447 files (768 creates).
1060 Sat Apr 30 15:35:44 2011 Child 13 verified 6455 files (776 creates).
1061 Sat Apr 30 15:35:45 2011 Child 5 verified 6510 files (831 creates).
1062 Sat Apr 30 15:35:45 2011 Child 8 verified 6543 files (864 creates).
1063 Sat Apr 30 15:38:27 2011 Child 2 verified 7304 files, created 1554
1064 Sat Apr 30 15:38:27 2011 Child 2 will create 2 symlinks.
1065 Sat Apr 30 15:38:27 2011 Child 2 finished creating 2 symlinks.
1066 Sat Apr 30 15:38:27 2011 Child 6 verified 7304 files, created 1554
1067 Sat Apr 30 15:38:27 2011 Child 6 will create 2 symlinks.
1068 Sat Apr 30 15:38:27 2011 Child 6 finished creating 2 symlinks.
1069 Sat Apr 30 15:38:27 2011 Child 3 verified 7304 files, created 1554
1070 Sat Apr 30 15:38:27 2011 Child 3 will create 2 symlinks.
1071 Sat Apr 30 15:38:27 2011 Child 3 finished creating 2 symlinks.
1072 Sat Apr 30 15:39:07 2011 Child 10 verified 7304 files, created 1554
1073 Sat Apr 30 15:39:07 2011 Child 10 will create 2 symlinks.
1074 Sat Apr 30 15:39:16 2011 Child 10 finished creating 2 symlinks.
1075 Sat Apr 30 15:39:21 2011 Child 12 verified 7304 files, created 1554
1076 Sat Apr 30 15:39:21 2011 Child 12 will create 2 symlinks.
1077 Sat Apr 30 15:39:21 2011 Child 12 finished creating 2 symlinks.
1078 Sat Apr 30 15:39:21 2011 Child 9 verified 7304 files, created 1554
1079 Sat Apr 30 15:39:21 2011 Child 9 will create 2 symlinks.
1080 Sat Apr 30 15:39:21 2011 Child 9 finished creating 2 symlinks.
1081 Sat Apr 30 15:39:23 2011 Child 8 verified 7304 files, created 1554
1082 Sat Apr 30 15:39:23 2011 Child 8 will create 2 symlinks.
1083 Sat Apr 30 15:39:24 2011 Child 8 finished creating 2 symlinks.
1084 Sat Apr 30 15:39:26 2011 Child 4 verified 7304 files, created 1554
1085 Sat Apr 30 15:39:26 2011 Child 4 will create 2 symlinks.
1086 Sat Apr 30 15:39:26 2011 Child 4 finished creating 2 symlinks.
1087 Sat Apr 30 15:39:33 2011 Child 5 verified 7304 files, created 1554
1088 Sat Apr 30 15:39:33 2011 Child 5 will create 2 symlinks.
1089 Sat Apr 30 15:39:38 2011 Child 5 finished creating 2 symlinks.
1090 Sat Apr 30 15:39:53 2011 Child 15 verified 7304 files, created 1554
1091 Sat Apr 30 15:39:53 2011 Child 15 will create 2 symlinks.
1092 Sat Apr 30 15:39:53 2011 Child 15 finished creating 2 symlinks.
1093 Sat Apr 30 15:39:53 2011 Child 13 verified 7304 files, created 1554
1094 Sat Apr 30 15:39:53 2011 Child 13 will create 2 symlinks.
1095 Sat Apr 30 15:39:53 2011 Child 13 finished creating 2 symlinks.
1096 Sat Apr 30 15:40:03 2011 Child 11 verified 7304 files, created 1554
1097 Sat Apr 30 15:40:03 2011 Child 11 will create 2 symlinks.
1098 Sat Apr 30 15:40:03 2011 Child 0 verified 7304 files, created 1554
1099 Sat Apr 30 15:40:03 2011 Child 0 will create 2 symlinks.
1100 Sat Apr 30 15:40:03 2011 Child 11 finished creating 2 symlinks.
1101 Sat Apr 30 15:40:03 2011 Child 0 finished creating 2 symlinks.
1102 Sat Apr 30 15:40:03 2011 Child 14 verified 7304 files, created 1554
1103 Sat Apr 30 15:40:03 2011 Child 14 will create 2 symlinks.
1104 Sat Apr 30 15:40:03 2011 Child 14 finished creating 2 symlinks.
1105 Sat Apr 30 15:40:03 2011 Child 1 verified 7304 files, created 1554
1106 Sat Apr 30 15:40:03 2011 Child 1 will create 2 symlinks.
1107 Sat Apr 30 15:40:03 2011 Child 1 finished creating 2 symlinks.
1108 Sat Apr 30 15:40:04 2011 Child 7 verified 7304 files, created 1554
1109 Sat Apr 30 15:40:04 2011 Child 7 will create 2 symlinks.
1110 Sat Apr 30 15:40:04 2011 Child 7 finished creating 2 symlinks.
1111 Sat Apr 30 15:40:04 2011 Completed.
1112 Sat Apr 30 15:40:04 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1113 Sat Apr 30 15:40:04 2011 Completed.
1114 Sat Apr 30 15:40:04 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1115 Sat Apr 30 15:40:05 2011 Received.
1116 Sat Apr 30 15:40:05 2011 Performing 300 seconds pretest warmup.
1117 Sat Apr 30 15:45:05 2011 Completed.
1118 Sat Apr 30 15:45:05 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1119 Sat Apr 30 15:45:05 2011 Completed.
1120 Sat Apr 30 15:45:05 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1121 Sat Apr 30 15:45:06 2011 Received.
1122 Sat Apr 30 15:45:06 2011 Starting 300 seconds test run.
1123 SFS Single Client (c09-44) Results, Sat Apr 30 15:50:16 2011
1124 --------------------------------------------------------------------------------------
1125 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1126 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1127 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1128            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1129 --------------------------------------------------------------------------------------
1130 getattr     26.0%    26.0%    38814    38814      0      0.21     0.16      0.00  1.8%
1131 setattr      4.0%     4.0%     5901     5901      0      5.02    38.06      0.16  6.6%
1132 lookup      24.0%    23.8%    35524    35524      0      0.32    13.77      0.04  2.5%
1133 readlink     1.0%     1.0%     1464     1464      0      0.21     0.13      0.02  0.1%
1134 read        18.0%    18.1%    27064    33373      0      5.42    29.43      0.06 32.7%
1135 write       10.0%    10.1%    15013    18200      0     11.69    57.55      0.12 39.1%
1136 create       1.0%     1.0%     1524     1524      0      8.86    49.94      0.35  3.0%
1137 remove       1.0%     1.0%     1464     1464      0      6.45    13.93      0.19  2.1%
1138 readdir      1.0%     1.0%     1508     1508      0      0.42     0.18      0.02  0.1%
1139 fsstat       1.0%     1.0%     1477     1477      0      0.22     0.24      0.03  0.1%
1140 access      11.0%    11.0%    16379    16379      0      0.20     0.13      0.01  0.7%
1141 commit       0.0%     0.0%        0     6564      0      0.00     0.00      0.00 10.7%
1142 readdirplus  2.0%     2.0%     3041     3041      0      0.72     0.21      0.02  0.5%
1143 --------------------------------------------------------------------------------------
1144
1145       --------------------------------------------------
1146       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1147       --------------------------+-----------------------
1148 SFS NFS V3 THROUGHPUT:  498.90 Ops/Sec   AVG. RESPONSE TIME:    3.00 Msec/Op
1149 TCP PROTOCOL
1150 FAST CALLS: 0
1151 NFS V3 MIXFILE: [ SFS Default ]
1152 CLIENT REQUESTED LOAD: 500 Ops/Sec
1153 TOTAL LOGICAL NFS V3 OPERATIONS:  149173         TEST TIME: 299 Sec
1154 TOTAL PHYSICAL NFS V3 OPERATIONS: 165233
1155 PHYSICAL NFS V3 THROUGHPUT:  552.61 Ops/Sec
1156 FILE SET SIZE CREATED: 60831360.0 KB
1157 FILE SET SIZE ACCESSED: 18257904.0 - 18609460.0 KB  (100.00% to 101.93% of Base)
1158
1159 ------------------------------------------------------------------------
1160
1161 Sat Apr 30 15:50:16 2011 Completed.
1162
1163 Client Test Parameters:
1164         Number of processes = 16
1165         Requested Load (NFS V3 operations/second) = 500
1166         Maximum number of outstanding biod writes = 8
1167         Maximum number of outstanding biod reads = 8
1168         Warm-up time (seconds) = 300
1169         Run time (seconds) = 300
1170         File Set = 114560 Files created for I/O operations
1171                    34368 Files accessed for I/O operations
1172                    2304 Files for non-I/O operations
1173                      32 Symlinks
1174                    3808 Directories
1175                         Additional non-I/O files created as necessary
1176
1177
1178 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1179 Sat Apr 30 15:50:16 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1180 Sat Apr 30 15:50:16 2011 Completed.
1181 Sat Apr 30 15:50:16 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1182
1183 ************************************************************************
1184 Sat Apr 30 15:50:17 2011 Received.
1185 Sat Apr 30 15:50:17 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1186 Sat Apr 30 15:50:17 2011 Completed.
1187 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
1188 NFS V3 Protocol Version
1189
1190 ************************************************************************
1191 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1192 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1193
1194 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1195 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1196
1197 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 15:50:19 2011
1198         Client hostname = c09-44
1199         Prime Client hostname = c09-44.sysnet.ucsd.edu
1200
1201 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1202 NFS V3 Protocol
1203 Sat Apr 30 15:50:19 2011 Mounting 16 remote test directories.
1204 Child[000]: NFS negotiated block size 32768
1205 Child[001]: NFS negotiated block size 32768
1206 Child[002]: NFS negotiated block size 32768
1207 Child[003]: NFS negotiated block size 32768
1208 Child[004]: NFS negotiated block size 32768
1209 Child[005]: NFS negotiated block size 32768
1210 Child[006]: NFS negotiated block size 32768
1211 Child[007]: NFS negotiated block size 32768
1212 Child[008]: NFS negotiated block size 32768
1213 Child[009]: NFS negotiated block size 32768
1214 Child[010]: NFS negotiated block size 32768
1215 Child[011]: NFS negotiated block size 32768
1216 Child[012]: NFS negotiated block size 32768
1217 Child[013]: NFS negotiated block size 32768
1218 Child[014]: NFS negotiated block size 32768
1219 Child[015]: NFS negotiated block size 32768
1220 Sat Apr 30 15:50:33 2011 Completed.
1221 Sat Apr 30 15:50:33 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1222 Sat Apr 30 15:50:33 2011 Completed.
1223 Sat Apr 30 15:50:33 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1224 Sat Apr 30 15:50:35 2011 Received.
1225
1226 Sat Apr 30 15:50:35 2011 Initializing test directories.
1227 Sat Apr 30 15:50:35 2011 Child 15 will create 289 directories.
1228 Sat Apr 30 15:50:35 2011 Child 7 will create 289 directories.
1229 Sat Apr 30 15:50:35 2011 Child 14 will create 289 directories.
1230 Sat Apr 30 15:50:35 2011 Child 4 will create 289 directories.
1231 Sat Apr 30 15:50:35 2011 Child 8 will create 289 directories.
1232 Sat Apr 30 15:50:35 2011 Child 5 will create 289 directories.
1233 Sat Apr 30 15:50:35 2011 Child 12 will create 289 directories.
1234 Sat Apr 30 15:50:35 2011 Child 2 will create 289 directories.
1235 Sat Apr 30 15:50:35 2011 Child 13 will create 289 directories.
1236 Sat Apr 30 15:50:35 2011 Child 6 will create 289 directories.
1237 Sat Apr 30 15:50:35 2011 Child 1 will create 289 directories.
1238 Sat Apr 30 15:50:35 2011 Child 10 will create 289 directories.
1239 Sat Apr 30 15:50:35 2011 Child 11 will create 289 directories.
1240 Sat Apr 30 15:50:35 2011 Child 0 will create 289 directories.
1241 Sat Apr 30 15:50:35 2011 Child 9 will create 289 directories.
1242 Sat Apr 30 15:50:35 2011 Child 3 will create 289 directories.
1243 Sat Apr 30 15:50:38 2011 Child 2 finished creating 289 directories.
1244 Sat Apr 30 15:50:38 2011 Child 2 will create 8814 files.
1245 Sat Apr 30 15:50:40 2011 Child 13 finished creating 289 directories.
1246 Sat Apr 30 15:50:40 2011 Child 13 will create 8814 files.
1247 Sat Apr 30 15:50:41 2011 Child 3 finished creating 289 directories.
1248 Sat Apr 30 15:50:41 2011 Child 3 will create 8814 files.
1249 Sat Apr 30 15:50:42 2011 Child 9 finished creating 289 directories.
1250 Sat Apr 30 15:50:42 2011 Child 9 will create 8814 files.
1251 Sat Apr 30 15:50:46 2011 Child 5 finished creating 289 directories.
1252 Sat Apr 30 15:50:46 2011 Child 5 will create 8814 files.
1253 Sat Apr 30 15:50:49 2011 Child 10 finished creating 289 directories.
1254 Sat Apr 30 15:50:49 2011 Child 10 will create 8814 files.
1255 Sat Apr 30 15:50:49 2011 Child 14 finished creating 289 directories.
1256 Sat Apr 30 15:50:49 2011 Child 14 will create 8814 files.
1257 Sat Apr 30 15:50:51 2011 Child 15 finished creating 289 directories.
1258 Sat Apr 30 15:50:51 2011 Child 15 will create 8814 files.
1259 Sat Apr 30 15:50:52 2011 Child 8 finished creating 289 directories.
1260 Sat Apr 30 15:50:52 2011 Child 8 will create 8814 files.
1261 Sat Apr 30 15:50:54 2011 Child 12 finished creating 289 directories.
1262 Sat Apr 30 15:50:54 2011 Child 12 will create 8814 files.
1263 Sat Apr 30 15:50:54 2011 Child 1 finished creating 289 directories.
1264 Sat Apr 30 15:50:54 2011 Child 1 will create 8814 files.
1265 Sat Apr 30 15:50:55 2011 Child 7 finished creating 289 directories.
1266 Sat Apr 30 15:50:55 2011 Child 7 will create 8814 files.
1267 Sat Apr 30 15:50:55 2011 Child 4 finished creating 289 directories.
1268 Sat Apr 30 15:50:55 2011 Child 4 will create 8814 files.
1269 Sat Apr 30 15:50:57 2011 Child 6 finished creating 289 directories.
1270 Sat Apr 30 15:50:57 2011 Child 6 will create 8814 files.
1271 Sat Apr 30 15:50:59 2011 Child 11 finished creating 289 directories.
1272 Sat Apr 30 15:50:59 2011 Child 11 will create 8814 files.
1273 Sat Apr 30 15:51:00 2011 Child 0 finished creating 289 directories.
1274 Sat Apr 30 15:51:00 2011 Child 0 will create 8814 files.
1275 Sat Apr 30 15:55:38 2011 Child 2 verified 8814 files, created 1568
1276 Sat Apr 30 15:55:38 2011 Child 2 will create 2 symlinks.
1277 Sat Apr 30 15:55:41 2011 Child 13 verified 8614 files (1454 creates).
1278 Sat Apr 30 15:55:43 2011 Child 2 finished creating 2 symlinks.
1279 Sat Apr 30 15:55:44 2011 Child 3 verified 8421 files (1261 creates).
1280 Sat Apr 30 15:55:44 2011 Child 9 verified 8205 files (1045 creates).
1281 Sat Apr 30 15:55:47 2011 Child 5 verified 8293 files (1133 creates).
1282 Sat Apr 30 15:55:50 2011 Child 10 verified 8171 files (1011 creates).
1283 Sat Apr 30 15:55:50 2011 Child 14 verified 8187 files (1027 creates).
1284 Sat Apr 30 15:55:52 2011 Child 15 verified 8000 files (840 creates).
1285 Sat Apr 30 15:55:54 2011 Child 8 verified 8192 files (1032 creates).
1286 Sat Apr 30 15:55:56 2011 Child 12 verified 8097 files (937 creates).
1287 Sat Apr 30 15:55:56 2011 Child 1 verified 8256 files (1096 creates).
1288 Sat Apr 30 15:55:57 2011 Child 7 verified 8038 files (878 creates).
1289 Sat Apr 30 15:55:57 2011 Child 4 verified 8092 files (932 creates).
1290 Sat Apr 30 15:55:58 2011 Child 6 verified 7918 files (758 creates).
1291 Sat Apr 30 15:56:00 2011 Child 13 verified 8814 files, created 1568
1292 Sat Apr 30 15:56:00 2011 Child 13 will create 2 symlinks.
1293 Sat Apr 30 15:56:00 2011 Child 13 finished creating 2 symlinks.
1294 Sat Apr 30 15:56:02 2011 Child 11 verified 8163 files (1003 creates).
1295 Sat Apr 30 15:56:02 2011 Child 0 verified 7865 files (705 creates).
1296 Sat Apr 30 15:56:55 2011 Child 3 verified 8814 files, created 1568
1297 Sat Apr 30 15:56:55 2011 Child 3 will create 2 symlinks.
1298 Sat Apr 30 15:56:55 2011 Child 3 finished creating 2 symlinks.
1299 Sat Apr 30 15:57:36 2011 Child 5 verified 8814 files, created 1568
1300 Sat Apr 30 15:57:36 2011 Child 5 will create 2 symlinks.
1301 Sat Apr 30 15:57:36 2011 Child 5 finished creating 2 symlinks.
1302 Sat Apr 30 15:58:04 2011 Child 9 verified 8814 files, created 1568
1303 Sat Apr 30 15:58:04 2011 Child 9 will create 2 symlinks.
1304 Sat Apr 30 15:58:04 2011 Child 9 finished creating 2 symlinks.
1305 Sat Apr 30 15:58:08 2011 Child 1 verified 8814 files, created 1568
1306 Sat Apr 30 15:58:08 2011 Child 1 will create 2 symlinks.
1307 Sat Apr 30 15:58:08 2011 Child 1 finished creating 2 symlinks.
1308 Sat Apr 30 15:58:09 2011 Child 14 verified 8814 files, created 1568
1309 Sat Apr 30 15:58:09 2011 Child 14 will create 2 symlinks.
1310 Sat Apr 30 15:58:09 2011 Child 14 finished creating 2 symlinks.
1311 Sat Apr 30 15:58:19 2011 Child 8 verified 8814 files, created 1568
1312 Sat Apr 30 15:58:19 2011 Child 8 will create 2 symlinks.
1313 Sat Apr 30 15:58:19 2011 Child 8 finished creating 2 symlinks.
1314 Sat Apr 30 15:58:22 2011 Child 10 verified 8814 files, created 1568
1315 Sat Apr 30 15:58:22 2011 Child 10 will create 2 symlinks.
1316 Sat Apr 30 15:58:22 2011 Child 10 finished creating 2 symlinks.
1317 Sat Apr 30 15:58:34 2011 Child 11 verified 8814 files, created 1568
1318 Sat Apr 30 15:58:34 2011 Child 11 will create 2 symlinks.
1319 Sat Apr 30 15:58:34 2011 Child 11 finished creating 2 symlinks.
1320 Sat Apr 30 15:58:34 2011 Child 12 verified 8814 files, created 1568
1321 Sat Apr 30 15:58:34 2011 Child 12 will create 2 symlinks.
1322 Sat Apr 30 15:58:35 2011 Child 12 finished creating 2 symlinks.
1323 Sat Apr 30 15:58:36 2011 Child 7 verified 8814 files, created 1568
1324 Sat Apr 30 15:58:36 2011 Child 7 will create 2 symlinks.
1325 Sat Apr 30 15:58:36 2011 Child 7 finished creating 2 symlinks.
1326 Sat Apr 30 15:58:37 2011 Child 4 verified 8814 files, created 1568
1327 Sat Apr 30 15:58:37 2011 Child 4 will create 2 symlinks.
1328 Sat Apr 30 15:58:37 2011 Child 4 finished creating 2 symlinks.
1329 Sat Apr 30 15:58:41 2011 Child 0 verified 8814 files, created 1568
1330 Sat Apr 30 15:58:41 2011 Child 0 will create 2 symlinks.
1331 Sat Apr 30 15:58:41 2011 Child 0 finished creating 2 symlinks.
1332 Sat Apr 30 15:58:41 2011 Child 15 verified 8814 files, created 1568
1333 Sat Apr 30 15:58:41 2011 Child 15 will create 2 symlinks.
1334 Sat Apr 30 15:58:41 2011 Child 15 finished creating 2 symlinks.
1335 Sat Apr 30 15:58:42 2011 Child 6 verified 8814 files, created 1568
1336 Sat Apr 30 15:58:42 2011 Child 6 will create 2 symlinks.
1337 Sat Apr 30 15:58:42 2011 Child 6 finished creating 2 symlinks.
1338 Sat Apr 30 15:58:43 2011 Completed.
1339 Sat Apr 30 15:58:43 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1340 Sat Apr 30 15:58:43 2011 Completed.
1341 Sat Apr 30 15:58:43 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1342 Sat Apr 30 15:58:44 2011 Received.
1343 Sat Apr 30 15:58:44 2011 Performing 300 seconds pretest warmup.
1344 Sat Apr 30 16:03:44 2011 Completed.
1345 Sat Apr 30 16:03:44 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1346 Sat Apr 30 16:03:44 2011 Completed.
1347 Sat Apr 30 16:03:44 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1348 Sat Apr 30 16:03:45 2011 Received.
1349 Sat Apr 30 16:03:45 2011 Starting 300 seconds test run.
1350 SFS Single Client (c09-44) Results, Sat Apr 30 16:08:55 2011
1351 --------------------------------------------------------------------------------------
1352 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1353 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1354 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1355            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1356 --------------------------------------------------------------------------------------
1357 getattr     26.0%    26.2%    47226    47226      0      0.25     2.47      0.01  1.8%
1358 setattr      4.0%     4.0%     7200     7200      0      8.67    76.64      0.20  9.5%
1359 lookup      24.0%    23.9%    43068    43068      0      0.23     1.12      0.01  1.5%
1360 readlink     1.0%     1.0%     1827     1827      0      0.21     0.19      0.02  0.1%
1361 read        18.0%    18.0%    32397    39897      0      7.31    40.89      0.07 36.0%
1362 write       10.0%     9.9%    17913    21823      0     11.54    30.31      0.08 31.4%
1363 create       1.0%     1.0%     1877     1877      0     11.70    60.74      0.35  3.3%
1364 remove       1.0%     1.0%     1813     1813      0      8.52    17.21      0.19  2.3%
1365 readdir      1.0%     1.0%     1822     1822      0      0.55     5.03      0.10  0.2%
1366 fsstat       1.0%     1.0%     1789     1789      0      0.21     0.16      0.02  0.1%
1367 access      11.0%    10.9%    19723    19723      0      0.23     1.68      0.02  0.7%
1368 commit       0.0%     0.0%        0     7834      0      0.00     0.00      0.00 12.7%
1369 readdirplus  2.0%     2.0%     3695     3695      0      0.75     0.72      0.03  0.4%
1370 --------------------------------------------------------------------------------------
1371
1372       --------------------------------------------------
1373       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1374       --------------------------+-----------------------
1375 SFS NFS V3 THROUGHPUT:  601.16 Ops/Sec   AVG. RESPONSE TIME:    3.64 Msec/Op
1376 TCP PROTOCOL
1377 FAST CALLS: 0
1378 NFS V3 MIXFILE: [ SFS Default ]
1379 CLIENT REQUESTED LOAD: 600 Ops/Sec
1380 TOTAL LOGICAL NFS V3 OPERATIONS:  180350         TEST TIME: 300 Sec
1381 TOTAL PHYSICAL NFS V3 OPERATIONS: 199594
1382 PHYSICAL NFS V3 THROUGHPUT:  665.31 Ops/Sec
1383 FILE SET SIZE CREATED: 73413936.0 KB
1384 FILE SET SIZE ACCESSED: 22030128.0 - 22447100.0 KB  (100.00% to 101.89% of Base)
1385
1386 ------------------------------------------------------------------------
1387
1388 Sat Apr 30 16:08:55 2011 Completed.
1389
1390 Client Test Parameters:
1391         Number of processes = 16
1392         Requested Load (NFS V3 operations/second) = 600
1393         Maximum number of outstanding biod writes = 8
1394         Maximum number of outstanding biod reads = 8
1395         Warm-up time (seconds) = 300
1396         Run time (seconds) = 300
1397         File Set = 138256 Files created for I/O operations
1398                    41472 Files accessed for I/O operations
1399                    2768 Files for non-I/O operations
1400                      32 Symlinks
1401                    4608 Directories
1402                         Additional non-I/O files created as necessary
1403
1404
1405 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1406 Sat Apr 30 16:08:55 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1407 Sat Apr 30 16:08:55 2011 Completed.
1408 Sat Apr 30 16:08:55 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1409
1410 ************************************************************************
1411 Sat Apr 30 16:08:56 2011 Received.
1412 Sat Apr 30 16:08:56 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1413 Sat Apr 30 16:08:56 2011 Completed.
1414 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
1415 NFS V3 Protocol Version
1416 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1417 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1418
1419 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1420 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1421
1422 ************************************************************************
1423
1424 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 16:08:58 2011
1425         Client hostname = c09-44
1426         Prime Client hostname = c09-44.sysnet.ucsd.edu
1427
1428 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1429 NFS V3 Protocol
1430 Sat Apr 30 16:08:58 2011 Mounting 16 remote test directories.
1431 Child[000]: NFS negotiated block size 32768
1432 Child[001]: NFS negotiated block size 32768
1433 Child[002]: NFS negotiated block size 32768
1434 Child[003]: NFS negotiated block size 32768
1435 Child[004]: NFS negotiated block size 32768
1436 Child[005]: NFS negotiated block size 32768
1437 Child[006]: NFS negotiated block size 32768
1438 Child[007]: NFS negotiated block size 32768
1439 Child[008]: NFS negotiated block size 32768
1440 Child[009]: NFS negotiated block size 32768
1441 Child[010]: NFS negotiated block size 32768
1442 Child[011]: NFS negotiated block size 32768
1443 Child[012]: NFS negotiated block size 32768
1444 Child[013]: NFS negotiated block size 32768
1445 Child[014]: NFS negotiated block size 32768
1446 Child[015]: NFS negotiated block size 32768
1447 Sat Apr 30 16:09:12 2011 Completed.
1448 Sat Apr 30 16:09:12 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1449 Sat Apr 30 16:09:12 2011 Completed.
1450 Sat Apr 30 16:09:12 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1451 Sat Apr 30 16:09:14 2011 Received.
1452
1453 Sat Apr 30 16:09:14 2011 Initializing test directories.
1454 Sat Apr 30 16:09:14 2011 Child 15 will create 338 directories.
1455 Sat Apr 30 16:09:14 2011 Child 11 will create 338 directories.
1456 Sat Apr 30 16:09:14 2011 Child 1 will create 338 directories.
1457 Sat Apr 30 16:09:14 2011 Child 0 will create 338 directories.
1458 Sat Apr 30 16:09:14 2011 Child 10 will create 338 directories.
1459 Sat Apr 30 16:09:14 2011 Child 12 will create 338 directories.
1460 Sat Apr 30 16:09:14 2011 Child 6 will create 338 directories.
1461 Sat Apr 30 16:09:14 2011 Child 2 will create 338 directories.
1462 Sat Apr 30 16:09:14 2011 Child 9 will create 338 directories.
1463 Sat Apr 30 16:09:14 2011 Child 14 will create 338 directories.
1464 Sat Apr 30 16:09:14 2011 Child 13 will create 338 directories.
1465 Sat Apr 30 16:09:14 2011 Child 7 will create 338 directories.
1466 Sat Apr 30 16:09:14 2011 Child 8 will create 338 directories.
1467 Sat Apr 30 16:09:14 2011 Child 3 will create 338 directories.
1468 Sat Apr 30 16:09:14 2011 Child 4 will create 338 directories.
1469 Sat Apr 30 16:09:14 2011 Child 5 will create 338 directories.
1470 Sat Apr 30 16:09:18 2011 Child 8 finished creating 338 directories.
1471 Sat Apr 30 16:09:18 2011 Child 8 will create 10325 files.
1472 Sat Apr 30 16:09:18 2011 Child 0 finished creating 338 directories.
1473 Sat Apr 30 16:09:18 2011 Child 0 will create 10325 files.
1474 Sat Apr 30 16:09:19 2011 Child 2 finished creating 338 directories.
1475 Sat Apr 30 16:09:19 2011 Child 2 will create 10325 files.
1476 Sat Apr 30 16:09:20 2011 Child 5 finished creating 338 directories.
1477 Sat Apr 30 16:09:20 2011 Child 5 will create 10325 files.
1478 Sat Apr 30 16:09:22 2011 Child 6 finished creating 338 directories.
1479 Sat Apr 30 16:09:22 2011 Child 6 will create 10325 files.
1480 Sat Apr 30 16:09:23 2011 Child 4 finished creating 338 directories.
1481 Sat Apr 30 16:09:23 2011 Child 4 will create 10325 files.
1482 Sat Apr 30 16:09:26 2011 Child 10 finished creating 338 directories.
1483 Sat Apr 30 16:09:26 2011 Child 10 will create 10325 files.
1484 Sat Apr 30 16:09:26 2011 Child 11 finished creating 338 directories.
1485 Sat Apr 30 16:09:26 2011 Child 11 will create 10325 files.
1486 Sat Apr 30 16:09:26 2011 Child 7 finished creating 338 directories.
1487 Sat Apr 30 16:09:26 2011 Child 7 will create 10325 files.
1488 Sat Apr 30 16:09:27 2011 Child 13 finished creating 338 directories.
1489 Sat Apr 30 16:09:27 2011 Child 13 will create 10325 files.
1490 Sat Apr 30 16:09:29 2011 Child 14 finished creating 338 directories.
1491 Sat Apr 30 16:09:29 2011 Child 14 will create 10325 files.
1492 Sat Apr 30 16:09:30 2011 Child 9 finished creating 338 directories.
1493 Sat Apr 30 16:09:30 2011 Child 9 will create 10325 files.
1494 Sat Apr 30 16:09:37 2011 Child 15 finished creating 338 directories.
1495 Sat Apr 30 16:09:37 2011 Child 15 will create 10325 files.
1496 Sat Apr 30 16:09:38 2011 Child 12 finished creating 338 directories.
1497 Sat Apr 30 16:09:38 2011 Child 12 will create 10325 files.
1498 Sat Apr 30 16:09:38 2011 Child 1 finished creating 338 directories.
1499 Sat Apr 30 16:09:38 2011 Child 1 will create 10325 files.
1500 Sat Apr 30 16:09:39 2011 Child 3 finished creating 338 directories.
1501 Sat Apr 30 16:09:39 2011 Child 3 will create 10325 files.
1502 Sat Apr 30 16:14:08 2011 Child 8 verified 10325 files, created 1583
1503 Sat Apr 30 16:14:08 2011 Child 8 will create 2 symlinks.
1504 Sat Apr 30 16:14:08 2011 Child 8 finished creating 2 symlinks.
1505 Sat Apr 30 16:14:21 2011 Child 2 verified 10325 files, created 1583
1506 Sat Apr 30 16:14:21 2011 Child 2 will create 2 symlinks.
1507 Sat Apr 30 16:14:21 2011 Child 2 finished creating 2 symlinks.
1508 Sat Apr 30 16:14:25 2011 Child 6 verified 9794 files (1153 creates).
1509 Sat Apr 30 16:14:25 2011 Child 4 verified 9627 files (986 creates).
1510 Sat Apr 30 16:14:27 2011 Child 10 verified 9514 files (873 creates).
1511 Sat Apr 30 16:14:27 2011 Child 7 verified 9647 files (1006 creates).
1512 Sat Apr 30 16:14:28 2011 Child 5 verified 9818 files (1177 creates).
1513 Sat Apr 30 16:14:30 2011 Child 11 verified 9507 files (866 creates).
1514 Sat Apr 30 16:14:34 2011 Child 13 verified 9818 files (1177 creates).
1515 Sat Apr 30 16:14:34 2011 Child 14 verified 9516 files (875 creates).
1516 Sat Apr 30 16:14:34 2011 Child 0 verified 9918 files (1277 creates).
1517 Sat Apr 30 16:14:35 2011 Child 9 verified 9655 files (1014 creates).
1518 Sat Apr 30 16:14:38 2011 Child 15 verified 9521 files (880 creates).
1519 Sat Apr 30 16:14:39 2011 Child 12 verified 9483 files (842 creates).
1520 Sat Apr 30 16:14:39 2011 Child 1 verified 9544 files (903 creates).
1521 Sat Apr 30 16:14:40 2011 Child 3 verified 9582 files (941 creates).
1522 Sat Apr 30 16:14:54 2011 Child 0 verified 10325 files, created 1583
1523 Sat Apr 30 16:14:54 2011 Child 0 will create 2 symlinks.
1524 Sat Apr 30 16:14:54 2011 Child 0 finished creating 2 symlinks.
1525 Sat Apr 30 16:15:57 2011 Child 6 verified 10325 files, created 1583
1526 Sat Apr 30 16:15:57 2011 Child 6 will create 2 symlinks.
1527 Sat Apr 30 16:15:57 2011 Child 6 finished creating 2 symlinks.
1528 Sat Apr 30 16:15:57 2011 Child 5 verified 10325 files, created 1583
1529 Sat Apr 30 16:15:57 2011 Child 5 will create 2 symlinks.
1530 Sat Apr 30 16:15:58 2011 Child 5 finished creating 2 symlinks.
1531 Sat Apr 30 16:15:59 2011 Child 13 verified 10325 files, created 1583
1532 Sat Apr 30 16:15:59 2011 Child 13 will create 2 symlinks.
1533 Sat Apr 30 16:15:59 2011 Child 13 finished creating 2 symlinks.
1534 Sat Apr 30 16:16:29 2011 Child 7 verified 10325 files, created 1583
1535 Sat Apr 30 16:16:29 2011 Child 7 will create 2 symlinks.
1536 Sat Apr 30 16:16:29 2011 Child 7 finished creating 2 symlinks.
1537 Sat Apr 30 16:16:36 2011 Child 9 verified 10325 files, created 1583
1538 Sat Apr 30 16:16:36 2011 Child 9 will create 2 symlinks.
1539 Sat Apr 30 16:16:36 2011 Child 10 verified 10325 files, created 1583
1540 Sat Apr 30 16:16:36 2011 Child 10 will create 2 symlinks.
1541 Sat Apr 30 16:16:36 2011 Child 9 finished creating 2 symlinks.
1542 Sat Apr 30 16:16:43 2011 Child 4 verified 10325 files, created 1583
1543 Sat Apr 30 16:16:43 2011 Child 4 will create 2 symlinks.
1544 Sat Apr 30 16:16:43 2011 Child 4 finished creating 2 symlinks.
1545 Sat Apr 30 16:16:44 2011 Child 10 finished creating 2 symlinks.
1546 Sat Apr 30 16:16:55 2011 Child 3 verified 10325 files, created 1583
1547 Sat Apr 30 16:16:55 2011 Child 3 will create 2 symlinks.
1548 Sat Apr 30 16:16:56 2011 Child 3 finished creating 2 symlinks.
1549 Sat Apr 30 16:16:56 2011 Child 15 verified 10325 files, created 1583
1550 Sat Apr 30 16:16:56 2011 Child 15 will create 2 symlinks.
1551 Sat Apr 30 16:16:56 2011 Child 15 finished creating 2 symlinks.
1552 Sat Apr 30 16:16:57 2011 Child 11 verified 10325 files, created 1583
1553 Sat Apr 30 16:16:57 2011 Child 11 will create 2 symlinks.
1554 Sat Apr 30 16:16:57 2011 Child 1 verified 10325 files, created 1583
1555 Sat Apr 30 16:16:57 2011 Child 1 will create 2 symlinks.
1556 Sat Apr 30 16:16:57 2011 Child 12 verified 10325 files, created 1583
1557 Sat Apr 30 16:16:57 2011 Child 12 will create 2 symlinks.
1558 Sat Apr 30 16:16:57 2011 Child 11 finished creating 2 symlinks.
1559 Sat Apr 30 16:16:57 2011 Child 1 finished creating 2 symlinks.
1560 Sat Apr 30 16:16:57 2011 Child 12 finished creating 2 symlinks.
1561 Sat Apr 30 16:16:57 2011 Child 14 verified 10325 files, created 1583
1562 Sat Apr 30 16:16:57 2011 Child 14 will create 2 symlinks.
1563 Sat Apr 30 16:16:57 2011 Child 14 finished creating 2 symlinks.
1564 Sat Apr 30 16:16:57 2011 Completed.
1565 Sat Apr 30 16:16:57 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1566 Sat Apr 30 16:16:57 2011 Completed.
1567 Sat Apr 30 16:16:57 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1568 Sat Apr 30 16:16:58 2011 Received.
1569 Sat Apr 30 16:16:58 2011 Performing 300 seconds pretest warmup.
1570 Sat Apr 30 16:21:58 2011 Completed.
1571 Sat Apr 30 16:21:58 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1572 Sat Apr 30 16:21:58 2011 Completed.
1573 Sat Apr 30 16:21:58 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1574 Sat Apr 30 16:21:59 2011 Received.
1575 Sat Apr 30 16:21:59 2011 Starting 300 seconds test run.
1576 SFS Single Client (c09-44) Results, Sat Apr 30 16:27:09 2011
1577 --------------------------------------------------------------------------------------
1578 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1579 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1580 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1581            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1582 --------------------------------------------------------------------------------------
1583 getattr     26.0%    25.9%    54753    54753      0      0.68    63.84      0.07  2.2%
1584 setattr      4.0%     4.0%     8479     8479      0     17.61   183.28      0.29  8.9%
1585 lookup      24.0%    24.0%    50732    50732      0      1.41   117.28      0.09  4.2%
1586 readlink     1.0%     1.0%     2107     2107      0      0.42     2.40      0.07  0.1%
1587 read        18.0%    18.0%    37931    46794      0     19.17   380.65      0.20 43.2%
1588 write       10.0%    10.0%    21010    25476      0     18.87   222.92      0.20 23.6%
1589 create       1.0%     1.0%     2191     2191      0     24.91    43.89      0.28  3.2%
1590 remove       1.0%     1.0%     2083     2083      0     17.85    30.77      0.24  2.2%
1591 readdir      1.0%     1.0%     2076     2076      0      0.64     2.23      0.06  0.1%
1592 fsstat       1.0%     1.0%     2189     2189      0      0.45     2.44      0.07  0.1%
1593 access      11.0%    11.0%    23270    23270      0      1.69   138.59      0.15  2.3%
1594 commit       0.0%     0.0%        0     9180      0      0.00     0.00      0.00  9.6%
1595 readdirplus  2.0%     2.0%     4267     4267      0      1.01     2.53      0.05  0.3%
1596 --------------------------------------------------------------------------------------
1597
1598       --------------------------------------------------
1599       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1600       --------------------------+-----------------------
1601 SFS NFS V3 THROUGHPUT:  705.97 Ops/Sec   AVG. RESPONSE TIME:    7.97 Msec/Op
1602 TCP PROTOCOL
1603 FAST CALLS: 0
1604 NFS V3 MIXFILE: [ SFS Default ]
1605 CLIENT REQUESTED LOAD: 700 Ops/Sec
1606 TOTAL LOGICAL NFS V3 OPERATIONS:  211088         TEST TIME: 299 Sec
1607 TOTAL PHYSICAL NFS V3 OPERATIONS: 233597
1608 PHYSICAL NFS V3 THROUGHPUT:  781.26 Ops/Sec
1609 FILE SET SIZE CREATED: 85996512.0 KB
1610 FILE SET SIZE ACCESSED: 25802352.0 - 26285188.0 KB  (100.00% to 101.87% of Base)
1611
1612 ------------------------------------------------------------------------
1613
1614 Sat Apr 30 16:27:09 2011 Completed.
1615
1616 Client Test Parameters:
1617         Number of processes = 16
1618         Requested Load (NFS V3 operations/second) = 700
1619         Maximum number of outstanding biod writes = 8
1620         Maximum number of outstanding biod reads = 8
1621         Warm-up time (seconds) = 300
1622         Run time (seconds) = 300
1623         File Set = 161952 Files created for I/O operations
1624                    48576 Files accessed for I/O operations
1625                    3248 Files for non-I/O operations
1626                      32 Symlinks
1627                    5392 Directories
1628                         Additional non-I/O files created as necessary
1629
1630
1631 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1632 Sat Apr 30 16:27:09 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1633 Sat Apr 30 16:27:09 2011 Completed.
1634 Sat Apr 30 16:27:09 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1635
1636 ************************************************************************
1637 Sat Apr 30 16:27:10 2011 Received.
1638 Sat Apr 30 16:27:10 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1639 Sat Apr 30 16:27:10 2011 Completed.
1640 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
1641 NFS V3 Protocol Version
1642
1643 ************************************************************************
1644 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1645 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1646
1647 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1648 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1649
1650 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 16:27:12 2011
1651         Client hostname = c09-44
1652         Prime Client hostname = c09-44.sysnet.ucsd.edu
1653
1654 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1655 NFS V3 Protocol
1656 Sat Apr 30 16:27:12 2011 Mounting 16 remote test directories.
1657 Child[000]: NFS negotiated block size 32768
1658 Child[001]: NFS negotiated block size 32768
1659 Child[002]: NFS negotiated block size 32768
1660 Child[003]: NFS negotiated block size 32768
1661 Child[004]: NFS negotiated block size 32768
1662 Child[005]: NFS negotiated block size 32768
1663 Child[006]: NFS negotiated block size 32768
1664 Child[007]: NFS negotiated block size 32768
1665 Child[008]: NFS negotiated block size 32768
1666 Child[009]: NFS negotiated block size 32768
1667 Child[010]: NFS negotiated block size 32768
1668 Child[011]: NFS negotiated block size 32768
1669 Child[012]: NFS negotiated block size 32768
1670 Child[013]: NFS negotiated block size 32768
1671 Child[014]: NFS negotiated block size 32768
1672 Child[015]: NFS negotiated block size 32768
1673 Sat Apr 30 16:27:26 2011 Completed.
1674 Sat Apr 30 16:27:26 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1675 Sat Apr 30 16:27:26 2011 Completed.
1676 Sat Apr 30 16:27:26 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1677 Sat Apr 30 16:27:28 2011 Received.
1678
1679 Sat Apr 30 16:27:28 2011 Initializing test directories.
1680 Sat Apr 30 16:27:28 2011 Child 15 will create 383 directories.
1681 Sat Apr 30 16:27:28 2011 Child 6 will create 383 directories.
1682 Sat Apr 30 16:27:28 2011 Child 4 will create 383 directories.
1683 Sat Apr 30 16:27:28 2011 Child 0 will create 383 directories.
1684 Sat Apr 30 16:27:28 2011 Child 11 will create 383 directories.
1685 Sat Apr 30 16:27:28 2011 Child 5 will create 383 directories.
1686 Sat Apr 30 16:27:28 2011 Child 1 will create 383 directories.
1687 Sat Apr 30 16:27:28 2011 Child 13 will create 383 directories.
1688 Sat Apr 30 16:27:28 2011 Child 10 will create 383 directories.
1689 Sat Apr 30 16:27:28 2011 Child 3 will create 383 directories.
1690 Sat Apr 30 16:27:28 2011 Child 2 will create 383 directories.
1691 Sat Apr 30 16:27:28 2011 Child 12 will create 383 directories.
1692 Sat Apr 30 16:27:28 2011 Child 14 will create 383 directories.
1693 Sat Apr 30 16:27:28 2011 Child 8 will create 383 directories.
1694 Sat Apr 30 16:27:28 2011 Child 7 will create 383 directories.
1695 Sat Apr 30 16:27:28 2011 Child 9 will create 383 directories.
1696 Sat Apr 30 16:27:29 2011 Child 14 finished creating 383 directories.
1697 Sat Apr 30 16:27:29 2011 Child 14 will create 11710 files.
1698 Sat Apr 30 16:27:29 2011 Child 7 finished creating 383 directories.
1699 Sat Apr 30 16:27:29 2011 Child 7 will create 11710 files.
1700 Sat Apr 30 16:27:30 2011 Child 12 finished creating 383 directories.
1701 Sat Apr 30 16:27:30 2011 Child 12 will create 11710 files.
1702 Sat Apr 30 16:27:37 2011 Child 15 finished creating 383 directories.
1703 Sat Apr 30 16:27:37 2011 Child 15 will create 11710 files.
1704 Sat Apr 30 16:27:39 2011 Child 13 finished creating 383 directories.
1705 Sat Apr 30 16:27:39 2011 Child 13 will create 11710 files.
1706 Sat Apr 30 16:27:40 2011 Child 3 finished creating 383 directories.
1707 Sat Apr 30 16:27:40 2011 Child 3 will create 11710 files.
1708 Sat Apr 30 16:27:41 2011 Child 1 finished creating 383 directories.
1709 Sat Apr 30 16:27:41 2011 Child 1 will create 11710 files.
1710 Sat Apr 30 16:27:41 2011 Child 9 finished creating 383 directories.
1711 Sat Apr 30 16:27:41 2011 Child 9 will create 11710 files.
1712 Sat Apr 30 16:27:42 2011 Child 6 finished creating 383 directories.
1713 Sat Apr 30 16:27:42 2011 Child 6 will create 11710 files.
1714 Sat Apr 30 16:27:49 2011 Child 10 finished creating 383 directories.
1715 Sat Apr 30 16:27:49 2011 Child 10 will create 11710 files.
1716 Sat Apr 30 16:27:53 2011 Child 0 finished creating 383 directories.
1717 Sat Apr 30 16:27:53 2011 Child 0 will create 11710 files.
1718 Sat Apr 30 16:27:53 2011 Child 8 finished creating 383 directories.
1719 Sat Apr 30 16:27:53 2011 Child 8 will create 11710 files.
1720 Sat Apr 30 16:27:53 2011 Child 2 finished creating 383 directories.
1721 Sat Apr 30 16:27:53 2011 Child 2 will create 11710 files.
1722 Sat Apr 30 16:27:57 2011 Child 5 finished creating 383 directories.
1723 Sat Apr 30 16:27:57 2011 Child 5 will create 11710 files.
1724 Sat Apr 30 16:27:58 2011 Child 11 finished creating 383 directories.
1725 Sat Apr 30 16:27:58 2011 Child 11 will create 11710 files.
1726 Sat Apr 30 16:28:00 2011 Child 4 finished creating 383 directories.
1727 Sat Apr 30 16:28:00 2011 Child 4 will create 11710 files.
1728 Sat Apr 30 16:32:18 2011 Child 14 verified 11710 files, created 1474
1729 Sat Apr 30 16:32:18 2011 Child 14 will create 2 symlinks.
1730 Sat Apr 30 16:32:18 2011 Child 14 finished creating 2 symlinks.
1731 Sat Apr 30 16:32:34 2011 Child 12 verified 11428 files (1306 creates).
1732 Sat Apr 30 16:32:38 2011 Child 7 verified 11428 files (1306 creates).
1733 Sat Apr 30 16:32:38 2011 Child 15 verified 11048 files (926 creates).
1734 Sat Apr 30 16:32:40 2011 Child 13 verified 11051 files (929 creates).
1735 Sat Apr 30 16:32:41 2011 Child 3 verified 10862 files (740 creates).
1736 Sat Apr 30 16:32:46 2011 Child 12 verified 11710 files, created 1474
1737 Sat Apr 30 16:32:46 2011 Child 12 will create 2 symlinks.
1738 Sat Apr 30 16:32:47 2011 Child 12 finished creating 2 symlinks.
1739 Sat Apr 30 16:32:50 2011 Child 1 verified 11148 files (1026 creates).
1740 Sat Apr 30 16:32:50 2011 Child 9 verified 10972 files (850 creates).
1741 Sat Apr 30 16:32:52 2011 Child 7 verified 11710 files, created 1474
1742 Sat Apr 30 16:32:52 2011 Child 7 will create 2 symlinks.
1743 Sat Apr 30 16:32:52 2011 Child 7 finished creating 2 symlinks.
1744 Sat Apr 30 16:33:20 2011 Child 5 verified 10972 files (850 creates).
1745 Sat Apr 30 16:33:20 2011 Child 4 verified 10921 files (799 creates).
1746 Sat Apr 30 16:33:20 2011 Child 0 verified 10921 files (799 creates).
1747 Sat Apr 30 16:33:20 2011 Child 10 verified 10937 files (815 creates).
1748 Sat Apr 30 16:33:42 2011 Child 6 verified 11071 files (949 creates).
1749 Sat Apr 30 16:33:42 2011 Child 8 verified 10812 files (690 creates).
1750 Sat Apr 30 16:33:42 2011 Child 2 verified 10812 files (690 creates).
1751 Sat Apr 30 16:33:42 2011 Child 11 verified 10812 files (690 creates).
1752 Sat Apr 30 16:34:33 2011 Child 1 verified 11710 files, created 1474
1753 Sat Apr 30 16:34:33 2011 Child 1 will create 2 symlinks.
1754 Sat Apr 30 16:34:33 2011 Child 1 finished creating 2 symlinks.
1755 Sat Apr 30 16:34:53 2011 Child 15 verified 11710 files, created 1474
1756 Sat Apr 30 16:34:53 2011 Child 15 will create 2 symlinks.
1757 Sat Apr 30 16:34:53 2011 Child 15 finished creating 2 symlinks.
1758 Sat Apr 30 16:35:19 2011 Child 5 verified 11710 files, created 1474
1759 Sat Apr 30 16:35:19 2011 Child 5 will create 2 symlinks.
1760 Sat Apr 30 16:35:19 2011 Child 5 finished creating 2 symlinks.
1761 Sat Apr 30 16:35:19 2011 Child 10 verified 11710 files, created 1474
1762 Sat Apr 30 16:35:19 2011 Child 10 will create 2 symlinks.
1763 Sat Apr 30 16:35:19 2011 Child 6 verified 11710 files, created 1474
1764 Sat Apr 30 16:35:19 2011 Child 6 will create 2 symlinks.
1765 Sat Apr 30 16:35:19 2011 Child 6 finished creating 2 symlinks.
1766 Sat Apr 30 16:35:19 2011 Child 10 finished creating 2 symlinks.
1767 Sat Apr 30 16:35:19 2011 Child 9 verified 11710 files, created 1474
1768 Sat Apr 30 16:35:19 2011 Child 9 will create 2 symlinks.
1769 Sat Apr 30 16:35:19 2011 Child 9 finished creating 2 symlinks.
1770 Sat Apr 30 16:35:19 2011 Child 13 verified 11710 files, created 1474
1771 Sat Apr 30 16:35:19 2011 Child 13 will create 2 symlinks.
1772 Sat Apr 30 16:35:19 2011 Child 13 finished creating 2 symlinks.
1773 Sat Apr 30 16:35:21 2011 Child 4 verified 11710 files, created 1474
1774 Sat Apr 30 16:35:21 2011 Child 4 will create 2 symlinks.
1775 Sat Apr 30 16:35:21 2011 Child 4 finished creating 2 symlinks.
1776 Sat Apr 30 16:35:41 2011 Child 0 verified 11710 files, created 1474
1777 Sat Apr 30 16:35:41 2011 Child 0 will create 2 symlinks.
1778 Sat Apr 30 16:35:41 2011 Child 0 finished creating 2 symlinks.
1779 Sat Apr 30 16:35:41 2011 Child 3 verified 11710 files, created 1474
1780 Sat Apr 30 16:35:41 2011 Child 3 will create 2 symlinks.
1781 Sat Apr 30 16:35:42 2011 Child 3 finished creating 2 symlinks.
1782 Sat Apr 30 16:35:42 2011 Child 2 verified 11710 files, created 1474
1783 Sat Apr 30 16:35:42 2011 Child 2 will create 2 symlinks.
1784 Sat Apr 30 16:35:42 2011 Child 2 finished creating 2 symlinks.
1785 Sat Apr 30 16:35:42 2011 Child 11 verified 11710 files, created 1474
1786 Sat Apr 30 16:35:42 2011 Child 11 will create 2 symlinks.
1787 Sat Apr 30 16:35:42 2011 Child 11 finished creating 2 symlinks.
1788 Sat Apr 30 16:35:42 2011 Child 8 verified 11710 files, created 1474
1789 Sat Apr 30 16:35:42 2011 Child 8 will create 2 symlinks.
1790 Sat Apr 30 16:35:42 2011 Child 8 finished creating 2 symlinks.
1791 Sat Apr 30 16:35:43 2011 Completed.
1792 Sat Apr 30 16:35:43 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1793 Sat Apr 30 16:35:43 2011 Completed.
1794 Sat Apr 30 16:35:43 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1795 Sat Apr 30 16:35:43 2011 Received.
1796 Sat Apr 30 16:35:43 2011 Performing 300 seconds pretest warmup.
1797 Sat Apr 30 16:40:43 2011 Completed.
1798 Sat Apr 30 16:40:43 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1799 Sat Apr 30 16:40:43 2011 Completed.
1800 Sat Apr 30 16:40:43 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1801 Sat Apr 30 16:40:44 2011 Received.
1802 Sat Apr 30 16:40:44 2011 Starting 300 seconds test run.
1803 SFS Single Client (c09-44) Results, Sat Apr 30 16:45:54 2011
1804 --------------------------------------------------------------------------------------
1805 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1806 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1807 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1808            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1809 --------------------------------------------------------------------------------------
1810 getattr     26.0%    25.9%    62562    62562      0      0.64     7.92      0.02  2.0%
1811 setattr      4.0%     4.0%     9774     9774      0     24.47    72.04      0.17 11.8%
1812 lookup      24.0%    24.1%    58283    58283      0      0.61     6.18      0.02  1.7%
1813 readlink     1.0%     1.0%     2440     2440      0      0.61     3.26      0.07  0.1%
1814 read        18.0%    18.0%    43430    53168      0     17.73   103.87      0.10 38.0%
1815 write       10.0%    10.0%    24153    29199      0     20.30   100.49      0.13 24.2%
1816 create       1.0%     1.0%     2510     2510      0     36.98    75.56      0.34  4.6%
1817 remove       1.0%     1.0%     2408     2408      0     26.96    66.76      0.33  3.2%
1818 readdir      1.0%     1.0%     2444     2444      0      0.77     2.47      0.06  0.1%
1819 fsstat       1.0%     1.0%     2383     2383      0      0.61     3.87      0.08  0.1%
1820 access      11.0%    11.0%    26622    26622      0      0.77    35.68      0.07  1.0%
1821 commit       0.0%     0.0%        0    10064      0      0.00     0.00      0.00 12.9%
1822 readdirplus  2.0%     2.0%     4798     4798      0      1.17     3.35      0.05  0.3%
1823 --------------------------------------------------------------------------------------
1824
1825       --------------------------------------------------
1826       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1827       --------------------------+-----------------------
1828 SFS NFS V3 THROUGHPUT:  808.71 Ops/Sec   AVG. RESPONSE TIME:    8.36 Msec/Op
1829 TCP PROTOCOL
1830 FAST CALLS: 0
1831 NFS V3 MIXFILE: [ SFS Default ]
1832 CLIENT REQUESTED LOAD: 800 Ops/Sec
1833 TOTAL LOGICAL NFS V3 OPERATIONS:  241807         TEST TIME: 299 Sec
1834 TOTAL PHYSICAL NFS V3 OPERATIONS: 266655
1835 PHYSICAL NFS V3 THROUGHPUT:  891.82 Ops/Sec
1836 FILE SET SIZE CREATED: 97534080.0 KB
1837 FILE SET SIZE ACCESSED: 29268720.0 - 29732674.0 KB  (100.00% to 101.59% of Base)
1838
1839 ------------------------------------------------------------------------
1840
1841 Sat Apr 30 16:45:54 2011 Completed.
1842
1843 Client Test Parameters:
1844         Number of processes = 16
1845         Requested Load (NFS V3 operations/second) = 800
1846         Maximum number of outstanding biod writes = 8
1847         Maximum number of outstanding biod reads = 8
1848         Warm-up time (seconds) = 300
1849         Run time (seconds) = 300
1850         File Set = 183680 Files created for I/O operations
1851                    55104 Files accessed for I/O operations
1852                    3680 Files for non-I/O operations
1853                      32 Symlinks
1854                    6112 Directories
1855                         Additional non-I/O files created as necessary
1856
1857
1858 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1859 Sat Apr 30 16:45:54 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1860 Sat Apr 30 16:45:54 2011 Completed.
1861 Sat Apr 30 16:45:54 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1862
1863 ************************************************************************
1864 Sat Apr 30 16:45:55 2011 Received.
1865 Sat Apr 30 16:45:55 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1866 Sat Apr 30 16:45:55 2011 Completed.
1867 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
1868 NFS V3 Protocol Version
1869
1870 ************************************************************************
1871 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1872 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1873
1874 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1875 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1876
1877 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 16:45:57 2011
1878         Client hostname = c09-44
1879         Prime Client hostname = c09-44.sysnet.ucsd.edu
1880
1881 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1882 NFS V3 Protocol
1883 Sat Apr 30 16:45:57 2011 Mounting 16 remote test directories.
1884 Child[000]: NFS negotiated block size 32768
1885 Child[001]: NFS negotiated block size 32768
1886 Child[002]: NFS negotiated block size 32768
1887 Child[003]: NFS negotiated block size 32768
1888 Child[004]: NFS negotiated block size 32768
1889 Child[005]: NFS negotiated block size 32768
1890 Child[006]: NFS negotiated block size 32768
1891 Child[007]: NFS negotiated block size 32768
1892 Child[008]: NFS negotiated block size 32768
1893 Child[009]: NFS negotiated block size 32768
1894 Child[010]: NFS negotiated block size 32768
1895 Child[011]: NFS negotiated block size 32768
1896 Child[012]: NFS negotiated block size 32768
1897 Child[013]: NFS negotiated block size 32768
1898 Child[014]: NFS negotiated block size 32768
1899 Child[015]: NFS negotiated block size 32768
1900 Sat Apr 30 16:46:11 2011 Completed.
1901 Sat Apr 30 16:46:11 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1902 Sat Apr 30 16:46:11 2011 Completed.
1903 Sat Apr 30 16:46:11 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1904 Sat Apr 30 16:46:13 2011 Received.
1905
1906 Sat Apr 30 16:46:13 2011 Initializing test directories.
1907 Sat Apr 30 16:46:13 2011 Child 13 will create 433 directories.
1908 Sat Apr 30 16:46:13 2011 Child 7 will create 433 directories.
1909 Sat Apr 30 16:46:13 2011 Child 11 will create 433 directories.
1910 Sat Apr 30 16:46:13 2011 Child 12 will create 433 directories.
1911 Sat Apr 30 16:46:13 2011 Child 14 will create 433 directories.
1912 Sat Apr 30 16:46:13 2011 Child 4 will create 433 directories.
1913 Sat Apr 30 16:46:13 2011 Child 10 will create 433 directories.
1914 Sat Apr 30 16:46:13 2011 Child 15 will create 433 directories.
1915 Sat Apr 30 16:46:13 2011 Child 0 will create 433 directories.
1916 Sat Apr 30 16:46:13 2011 Child 9 will create 433 directories.
1917 Sat Apr 30 16:46:13 2011 Child 1 will create 433 directories.
1918 Sat Apr 30 16:46:13 2011 Child 8 will create 433 directories.
1919 Sat Apr 30 16:46:13 2011 Child 2 will create 433 directories.
1920 Sat Apr 30 16:46:13 2011 Child 6 will create 433 directories.
1921 Sat Apr 30 16:46:13 2011 Child 3 will create 433 directories.
1922 Sat Apr 30 16:46:13 2011 Child 5 will create 433 directories.
1923 Sat Apr 30 16:46:15 2011 Child 10 finished creating 433 directories.
1924 Sat Apr 30 16:46:15 2011 Child 10 will create 13221 files.
1925 Sat Apr 30 16:46:16 2011 Child 5 finished creating 433 directories.
1926 Sat Apr 30 16:46:16 2011 Child 5 will create 13221 files.
1927 Sat Apr 30 16:46:20 2011 Child 7 finished creating 433 directories.
1928 Sat Apr 30 16:46:20 2011 Child 7 will create 13221 files.
1929 Sat Apr 30 16:46:20 2011 Child 3 finished creating 433 directories.
1930 Sat Apr 30 16:46:20 2011 Child 3 will create 13221 files.
1931 Sat Apr 30 16:46:23 2011 Child 8 finished creating 433 directories.
1932 Sat Apr 30 16:46:23 2011 Child 8 will create 13221 files.
1933 Sat Apr 30 16:46:29 2011 Child 13 finished creating 433 directories.
1934 Sat Apr 30 16:46:29 2011 Child 13 will create 13221 files.
1935 Sat Apr 30 16:46:30 2011 Child 12 finished creating 433 directories.
1936 Sat Apr 30 16:46:30 2011 Child 12 will create 13221 files.
1937 Sat Apr 30 16:46:32 2011 Child 1 finished creating 433 directories.
1938 Sat Apr 30 16:46:32 2011 Child 1 will create 13221 files.
1939 Sat Apr 30 16:46:34 2011 Child 11 finished creating 433 directories.
1940 Sat Apr 30 16:46:34 2011 Child 11 will create 13221 files.
1941 Sat Apr 30 16:46:35 2011 Child 15 finished creating 433 directories.
1942 Sat Apr 30 16:46:35 2011 Child 15 will create 13221 files.
1943 Sat Apr 30 16:46:43 2011 Child 0 finished creating 433 directories.
1944 Sat Apr 30 16:46:43 2011 Child 0 will create 13221 files.
1945 Sat Apr 30 16:46:46 2011 Child 4 finished creating 433 directories.
1946 Sat Apr 30 16:46:46 2011 Child 4 will create 13221 files.
1947 Sat Apr 30 16:46:49 2011 Child 2 finished creating 433 directories.
1948 Sat Apr 30 16:46:49 2011 Child 2 will create 13221 files.
1949 Sat Apr 30 16:46:50 2011 Child 6 finished creating 433 directories.
1950 Sat Apr 30 16:46:50 2011 Child 6 will create 13221 files.
1951 Sat Apr 30 16:46:50 2011 Child 9 finished creating 433 directories.
1952 Sat Apr 30 16:46:50 2011 Child 9 will create 13221 files.
1953 Sat Apr 30 16:46:50 2011 Child 14 finished creating 433 directories.
1954 Sat Apr 30 16:46:50 2011 Child 14 will create 13221 files.
1955 Sat Apr 30 16:49:20 2011 Child 10 verified 13221 files, created 1612
1956 Sat Apr 30 16:49:20 2011 Child 10 will create 2 symlinks.
1957 Sat Apr 30 16:49:29 2011 Child 10 finished creating 2 symlinks.
1958 Sat Apr 30 16:50:28 2011 Child 5 verified 13221 files, created 1612
1959 Sat Apr 30 16:50:28 2011 Child 5 will create 2 symlinks.
1960 Sat Apr 30 16:50:28 2011 Child 5 finished creating 2 symlinks.
1961 Sat Apr 30 16:50:57 2011 Child 7 verified 13221 files, created 1612
1962 Sat Apr 30 16:50:57 2011 Child 7 will create 2 symlinks.
1963 Sat Apr 30 16:50:57 2011 Child 7 finished creating 2 symlinks.
1964 Sat Apr 30 16:51:09 2011 Child 3 verified 13221 files, created 1612
1965 Sat Apr 30 16:51:09 2011 Child 3 will create 2 symlinks.
1966 Sat Apr 30 16:51:09 2011 Child 3 finished creating 2 symlinks.
1967 Sat Apr 30 16:51:30 2011 Child 13 verified 12854 files (1374 creates).
1968 Sat Apr 30 16:51:31 2011 Child 8 verified 13221 files, created 1612
1969 Sat Apr 30 16:51:31 2011 Child 8 will create 2 symlinks.
1970 Sat Apr 30 16:51:31 2011 Child 8 finished creating 2 symlinks.
1971 Sat Apr 30 16:51:31 2011 Child 12 verified 12858 files (1378 creates).
1972 Sat Apr 30 16:51:34 2011 Child 1 verified 12873 files (1393 creates).
1973 Sat Apr 30 16:51:35 2011 Child 11 verified 12791 files (1311 creates).
1974 Sat Apr 30 16:51:36 2011 Child 15 verified 12802 files (1322 creates).
1975 Sat Apr 30 16:51:46 2011 Child 0 verified 12665 files (1185 creates).
1976 Sat Apr 30 16:51:47 2011 Child 4 verified 12553 files (1073 creates).
1977 Sat Apr 30 16:51:50 2011 Child 2 verified 12696 files (1216 creates).
1978 Sat Apr 30 16:51:51 2011 Child 6 verified 12592 files (1112 creates).
1979 Sat Apr 30 16:51:51 2011 Child 9 verified 12602 files (1122 creates).
1980 Sat Apr 30 16:51:51 2011 Child 14 verified 12587 files (1107 creates).
1981 Sat Apr 30 16:51:56 2011 Child 1 verified 13221 files, created 1612
1982 Sat Apr 30 16:51:56 2011 Child 1 will create 2 symlinks.
1983 Sat Apr 30 16:51:56 2011 Child 1 finished creating 2 symlinks.
1984 Sat Apr 30 16:51:57 2011 Child 12 verified 13221 files, created 1612
1985 Sat Apr 30 16:51:57 2011 Child 12 will create 2 symlinks.
1986 Sat Apr 30 16:51:57 2011 Child 12 finished creating 2 symlinks.
1987 Sat Apr 30 16:52:01 2011 Child 13 verified 13221 files, created 1612
1988 Sat Apr 30 16:52:01 2011 Child 13 will create 2 symlinks.
1989 Sat Apr 30 16:52:01 2011 Child 13 finished creating 2 symlinks.
1990 Sat Apr 30 16:52:15 2011 Child 15 verified 13221 files, created 1612
1991 Sat Apr 30 16:52:15 2011 Child 15 will create 2 symlinks.
1992 Sat Apr 30 16:52:16 2011 Child 11 verified 13221 files, created 1612
1993 Sat Apr 30 16:52:16 2011 Child 11 will create 2 symlinks.
1994 Sat Apr 30 16:52:16 2011 Child 11 finished creating 2 symlinks.
1995 Sat Apr 30 16:52:16 2011 Child 15 finished creating 2 symlinks.
1996 Sat Apr 30 16:52:40 2011 Child 2 verified 13221 files, created 1612
1997 Sat Apr 30 16:52:40 2011 Child 2 will create 2 symlinks.
1998 Sat Apr 30 16:52:40 2011 Child 2 finished creating 2 symlinks.
1999 Sat Apr 30 16:52:41 2011 Child 6 verified 13221 files, created 1612
2000 Sat Apr 30 16:52:41 2011 Child 6 will create 2 symlinks.
2001 Sat Apr 30 16:52:41 2011 Child 6 finished creating 2 symlinks.
2002 Sat Apr 30 16:52:42 2011 Child 14 verified 13221 files, created 1612
2003 Sat Apr 30 16:52:42 2011 Child 14 will create 2 symlinks.
2004 Sat Apr 30 16:52:42 2011 Child 14 finished creating 2 symlinks.
2005 Sat Apr 30 16:52:42 2011 Child 9 verified 13221 files, created 1612
2006 Sat Apr 30 16:52:42 2011 Child 9 will create 2 symlinks.
2007 Sat Apr 30 16:52:42 2011 Child 9 finished creating 2 symlinks.
2008 Sat Apr 30 16:52:42 2011 Child 4 verified 13221 files, created 1612
2009 Sat Apr 30 16:52:42 2011 Child 4 will create 2 symlinks.
2010 Sat Apr 30 16:52:42 2011 Child 4 finished creating 2 symlinks.
2011 Sat Apr 30 16:52:44 2011 Child 0 verified 13221 files, created 1612
2012 Sat Apr 30 16:52:44 2011 Child 0 will create 2 symlinks.
2013 Sat Apr 30 16:52:44 2011 Child 0 finished creating 2 symlinks.
2014 Sat Apr 30 16:52:44 2011 Completed.
2015 Sat Apr 30 16:52:44 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2016 Sat Apr 30 16:52:44 2011 Completed.
2017 Sat Apr 30 16:52:44 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2018 Sat Apr 30 16:52:44 2011 Received.
2019 Sat Apr 30 16:52:44 2011 Performing 300 seconds pretest warmup.
2020 Sat Apr 30 16:57:44 2011 Completed.
2021 Sat Apr 30 16:57:44 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2022 Sat Apr 30 16:57:44 2011 Completed.
2023 Sat Apr 30 16:57:44 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2024 Sat Apr 30 16:57:45 2011 Received.
2025 Sat Apr 30 16:57:45 2011 Starting 300 seconds test run.
2026 SFS Single Client (c09-44) Results, Sat Apr 30 17:02:55 2011
2027 --------------------------------------------------------------------------------------
2028 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2029 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2030 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2031            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2032 --------------------------------------------------------------------------------------
2033 getattr     26.0%    26.0%    71754    71754      0      2.48    50.51      0.05  3.4%
2034 setattr      4.0%     3.9%    10883    10883      0     43.56    68.94      0.16  9.1%
2035 lookup      24.0%    24.0%    66371    66371      0      2.46    39.09      0.05  3.1%
2036 readlink     1.0%     1.0%     2788     2788      0      2.30    10.27      0.12  0.1%
2037 read        18.0%    18.0%    49667    60917      0     42.68   163.08      0.11 40.8%
2038 write       10.0%    10.1%    27883    33674      0     45.85   244.07      0.18 24.6%
2039 create       1.0%     1.0%     2846     2846      0     81.85   108.89      0.38  4.5%
2040 remove       1.0%     1.0%     2749     2749      0     48.75    79.74      0.33  2.6%
2041 readdir      1.0%     1.0%     2765     2765      0      2.57     9.25      0.11  0.1%
2042 fsstat       1.0%     1.0%     2785     2785      0      1.79     6.13      0.09  0.1%
2043 access      11.0%    11.0%    30312    30312      0      2.37    54.14      0.08  1.4%
2044 commit       0.0%     0.0%        0    11293      0      0.00     0.00      0.00  9.9%
2045 readdirplus  2.0%     2.0%     5548     5548      0      2.79     7.50      0.07  0.3%
2046 --------------------------------------------------------------------------------------
2047
2048       --------------------------------------------------
2049       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2050       --------------------------+-----------------------
2051 SFS NFS V3 THROUGHPUT:  924.25 Ops/Sec   AVG. RESPONSE TIME:   18.81 Msec/Op
2052 TCP PROTOCOL
2053 FAST CALLS: 0
2054 NFS V3 MIXFILE: [ SFS Default ]
2055 CLIENT REQUESTED LOAD: 900 Ops/Sec
2056 TOTAL LOGICAL NFS V3 OPERATIONS:  276351         TEST TIME: 299 Sec
2057 TOTAL PHYSICAL NFS V3 OPERATIONS: 304685
2058 PHYSICAL NFS V3 THROUGHPUT: 1019.01 Ops/Sec
2059 FILE SET SIZE CREATED: 110116656.0 KB
2060 FILE SET SIZE ACCESSED: 33040944.0 - 33523056.0 KB  (100.00% to 101.46% of Base)
2061
2062 ------------------------------------------------------------------------
2063
2064 Sat Apr 30 17:02:55 2011 Completed.
2065
2066 Client Test Parameters:
2067         Number of processes = 16
2068         Requested Load (NFS V3 operations/second) = 900
2069         Maximum number of outstanding biod writes = 8
2070         Maximum number of outstanding biod reads = 8
2071         Warm-up time (seconds) = 300
2072         Run time (seconds) = 300
2073         File Set = 207376 Files created for I/O operations
2074                    62208 Files accessed for I/O operations
2075                    4160 Files for non-I/O operations
2076                      32 Symlinks
2077                    6912 Directories
2078                         Additional non-I/O files created as necessary
2079
2080
2081 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2082 Sat Apr 30 17:02:55 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2083 Sat Apr 30 17:02:55 2011 Completed.
2084 Sat Apr 30 17:02:55 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2085
2086 ************************************************************************
2087 Sat Apr 30 17:02:56 2011 Received.
2088 Sat Apr 30 17:02:56 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2089 Sat Apr 30 17:02:56 2011 Completed.
2090 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
2091 NFS V3 Protocol Version
2092
2093 ************************************************************************
2094 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2095 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2096
2097 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2098 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2099
2100 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 17:02:58 2011
2101         Client hostname = c09-44
2102         Prime Client hostname = c09-44.sysnet.ucsd.edu
2103
2104 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2105 NFS V3 Protocol
2106 Sat Apr 30 17:02:58 2011 Mounting 16 remote test directories.
2107 Child[000]: NFS negotiated block size 32768
2108 Child[001]: NFS negotiated block size 32768
2109 Child[002]: NFS negotiated block size 32768
2110 Child[003]: NFS negotiated block size 32768
2111 Child[004]: NFS negotiated block size 32768
2112 Child[005]: NFS negotiated block size 32768
2113 Child[006]: NFS negotiated block size 32768
2114 Child[007]: NFS negotiated block size 32768
2115 Child[008]: NFS negotiated block size 32768
2116 Child[009]: NFS negotiated block size 32768
2117 Child[010]: NFS negotiated block size 32768
2118 Child[011]: NFS negotiated block size 32768
2119 Child[012]: NFS negotiated block size 32768
2120 Child[013]: NFS negotiated block size 32768
2121 Child[014]: NFS negotiated block size 32768
2122 Child[015]: NFS negotiated block size 32768
2123 Sat Apr 30 17:03:12 2011 Completed.
2124 Sat Apr 30 17:03:12 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2125 Sat Apr 30 17:03:12 2011 Completed.
2126 Sat Apr 30 17:03:12 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2127 Sat Apr 30 17:03:14 2011 Received.
2128
2129 Sat Apr 30 17:03:14 2011 Initializing test directories.
2130 Sat Apr 30 17:03:14 2011 Child 8 will create 482 directories.
2131 Sat Apr 30 17:03:14 2011 Child 7 will create 482 directories.
2132 Sat Apr 30 17:03:14 2011 Child 4 will create 482 directories.
2133 Sat Apr 30 17:03:14 2011 Child 2 will create 482 directories.
2134 Sat Apr 30 17:03:14 2011 Child 9 will create 482 directories.
2135 Sat Apr 30 17:03:14 2011 Child 6 will create 482 directories.
2136 Sat Apr 30 17:03:14 2011 Child 3 will create 482 directories.
2137 Sat Apr 30 17:03:14 2011 Child 5 will create 482 directories.
2138 Sat Apr 30 17:03:14 2011 Child 1 will create 482 directories.
2139 Sat Apr 30 17:03:14 2011 Child 0 will create 482 directories.
2140 Sat Apr 30 17:03:14 2011 Child 10 will create 482 directories.
2141 Sat Apr 30 17:03:14 2011 Child 15 will create 482 directories.
2142 Sat Apr 30 17:03:14 2011 Child 11 will create 482 directories.
2143 Sat Apr 30 17:03:14 2011 Child 14 will create 482 directories.
2144 Sat Apr 30 17:03:14 2011 Child 12 will create 482 directories.
2145 Sat Apr 30 17:03:14 2011 Child 13 will create 482 directories.
2146 Sat Apr 30 17:03:14 2011 Child 6 finished creating 482 directories.
2147 Sat Apr 30 17:03:14 2011 Child 6 will create 14732 files.
2148 Sat Apr 30 17:03:18 2011 Child 9 finished creating 482 directories.
2149 Sat Apr 30 17:03:18 2011 Child 9 will create 14732 files.
2150 Sat Apr 30 17:03:19 2011 Child 3 finished creating 482 directories.
2151 Sat Apr 30 17:03:19 2011 Child 3 will create 14732 files.
2152 Sat Apr 30 17:03:21 2011 Child 8 finished creating 482 directories.
2153 Sat Apr 30 17:03:21 2011 Child 8 will create 14732 files.
2154 Sat Apr 30 17:03:21 2011 Child 1 finished creating 482 directories.
2155 Sat Apr 30 17:03:21 2011 Child 1 will create 14732 files.
2156 Sat Apr 30 17:03:25 2011 Child 0 finished creating 482 directories.
2157 Sat Apr 30 17:03:25 2011 Child 0 will create 14732 files.
2158 Sat Apr 30 17:03:25 2011 Child 10 finished creating 482 directories.
2159 Sat Apr 30 17:03:25 2011 Child 10 will create 14732 files.
2160 Sat Apr 30 17:03:27 2011 Child 7 finished creating 482 directories.
2161 Sat Apr 30 17:03:27 2011 Child 7 will create 14732 files.
2162 Sat Apr 30 17:03:27 2011 Child 11 finished creating 482 directories.
2163 Sat Apr 30 17:03:27 2011 Child 11 will create 14732 files.
2164 Sat Apr 30 17:03:32 2011 Child 15 finished creating 482 directories.
2165 Sat Apr 30 17:03:32 2011 Child 15 will create 14732 files.
2166 Sat Apr 30 17:03:34 2011 Child 2 finished creating 482 directories.
2167 Sat Apr 30 17:03:34 2011 Child 2 will create 14732 files.
2168 Sat Apr 30 17:03:34 2011 Child 13 finished creating 482 directories.
2169 Sat Apr 30 17:03:34 2011 Child 13 will create 14732 files.
2170 Sat Apr 30 17:03:37 2011 Child 4 finished creating 482 directories.
2171 Sat Apr 30 17:03:37 2011 Child 4 will create 14732 files.
2172 Sat Apr 30 17:03:38 2011 Child 5 finished creating 482 directories.
2173 Sat Apr 30 17:03:38 2011 Child 5 will create 14732 files.
2174 Sat Apr 30 17:03:59 2011 Child 14 finished creating 482 directories.
2175 Sat Apr 30 17:03:59 2011 Child 14 will create 14732 files.
2176 Sat Apr 30 17:03:59 2011 Child 12 finished creating 482 directories.
2177 Sat Apr 30 17:03:59 2011 Child 12 will create 14732 files.
2178 Sat Apr 30 17:08:18 2011 Child 6 verified 14732 files, created 1627
2179 Sat Apr 30 17:08:18 2011 Child 6 will create 2 symlinks.
2180 Sat Apr 30 17:08:18 2011 Child 6 finished creating 2 symlinks.
2181 Sat Apr 30 17:08:29 2011 Child 0 verified 13679 files (718 creates).
2182 Sat Apr 30 17:08:33 2011 Child 3 verified 14065 files (1104 creates).
2183 Sat Apr 30 17:08:33 2011 Child 1 verified 13903 files (942 creates).
2184 Sat Apr 30 17:08:33 2011 Child 10 verified 13629 files (668 creates).
2185 Sat Apr 30 17:08:33 2011 Child 7 verified 13629 files (668 creates).
2186 Sat Apr 30 17:08:33 2011 Child 8 verified 13903 files (942 creates).
2187 Sat Apr 30 17:08:34 2011 Child 15 verified 13388 files (427 creates).
2188 Sat Apr 30 17:08:38 2011 Child 2 verified 13498 files (537 creates).
2189 Sat Apr 30 17:08:40 2011 Child 13 verified 13383 files (422 creates).
2190 Sat Apr 30 17:08:42 2011 Child 11 verified 13629 files (668 creates).
2191 Sat Apr 30 17:08:42 2011 Child 5 verified 13369 files (408 creates).
2192 Sat Apr 30 17:08:42 2011 Child 9 verified 14065 files (1104 creates).
2193 Sat Apr 30 17:08:45 2011 Child 4 verified 13383 files (422 creates).
2194 Sat Apr 30 17:09:05 2011 Child 14 verified 13372 files (411 creates).
2195 Sat Apr 30 17:09:07 2011 Child 12 verified 13376 files (415 creates).
2196 Sat Apr 30 17:10:29 2011 Child 3 verified 14732 files, created 1627
2197 Sat Apr 30 17:10:29 2011 Child 3 will create 2 symlinks.
2198 Sat Apr 30 17:10:30 2011 Child 3 finished creating 2 symlinks.
2199 Sat Apr 30 17:10:48 2011 Child 9 verified 14732 files, created 1627
2200 Sat Apr 30 17:10:48 2011 Child 9 will create 2 symlinks.
2201 Sat Apr 30 17:10:48 2011 Child 9 finished creating 2 symlinks.
2202 Sat Apr 30 17:11:36 2011 Child 8 verified 14732 files, created 1627
2203 Sat Apr 30 17:11:36 2011 Child 8 will create 2 symlinks.
2204 Sat Apr 30 17:11:36 2011 Child 8 finished creating 2 symlinks.
2205 Sat Apr 30 17:11:41 2011 Child 1 verified 14732 files, created 1627
2206 Sat Apr 30 17:11:41 2011 Child 1 will create 2 symlinks.
2207 Sat Apr 30 17:11:48 2011 Child 1 finished creating 2 symlinks.
2208 Sat Apr 30 17:12:42 2011 Child 7 verified 14732 files, created 1627
2209 Sat Apr 30 17:12:42 2011 Child 7 will create 2 symlinks.
2210 Sat Apr 30 17:12:43 2011 Child 7 finished creating 2 symlinks.
2211 Sat Apr 30 17:12:49 2011 Child 11 verified 14732 files, created 1627
2212 Sat Apr 30 17:12:49 2011 Child 11 will create 2 symlinks.
2213 Sat Apr 30 17:12:49 2011 Child 11 finished creating 2 symlinks.
2214 Sat Apr 30 17:12:49 2011 Child 10 verified 14732 files, created 1627
2215 Sat Apr 30 17:12:49 2011 Child 10 will create 2 symlinks.
2216 Sat Apr 30 17:12:51 2011 Child 10 finished creating 2 symlinks.
2217 Sat Apr 30 17:12:52 2011 Child 2 verified 14732 files, created 1627
2218 Sat Apr 30 17:12:52 2011 Child 2 will create 2 symlinks.
2219 Sat Apr 30 17:12:57 2011 Child 2 finished creating 2 symlinks.
2220 Sat Apr 30 17:13:14 2011 Child 13 verified 14732 files, created 1627
2221 Sat Apr 30 17:13:14 2011 Child 13 will create 2 symlinks.
2222 Sat Apr 30 17:13:14 2011 Child 13 finished creating 2 symlinks.
2223 Sat Apr 30 17:13:14 2011 Child 15 verified 14732 files, created 1627
2224 Sat Apr 30 17:13:14 2011 Child 15 will create 2 symlinks.
2225 Sat Apr 30 17:13:14 2011 Child 4 verified 14732 files, created 1627
2226 Sat Apr 30 17:13:14 2011 Child 4 will create 2 symlinks.
2227 Sat Apr 30 17:13:14 2011 Child 15 finished creating 2 symlinks.
2228 Sat Apr 30 17:13:14 2011 Child 4 finished creating 2 symlinks.
2229 Sat Apr 30 17:13:16 2011 Child 14 verified 14732 files, created 1627
2230 Sat Apr 30 17:13:16 2011 Child 14 will create 2 symlinks.
2231 Sat Apr 30 17:13:16 2011 Child 14 finished creating 2 symlinks.
2232 Sat Apr 30 17:13:17 2011 Child 12 verified 14732 files, created 1627
2233 Sat Apr 30 17:13:17 2011 Child 12 will create 2 symlinks.
2234 Sat Apr 30 17:13:17 2011 Child 12 finished creating 2 symlinks.
2235 Sat Apr 30 17:13:17 2011 Child 5 verified 14732 files, created 1627
2236 Sat Apr 30 17:13:17 2011 Child 5 will create 2 symlinks.
2237 Sat Apr 30 17:13:17 2011 Child 5 finished creating 2 symlinks.
2238 Sat Apr 30 17:13:24 2011 Child 0 verified 14732 files, created 1627
2239 Sat Apr 30 17:13:24 2011 Child 0 will create 2 symlinks.
2240 Sat Apr 30 17:13:24 2011 Child 0 finished creating 2 symlinks.
2241 Sat Apr 30 17:13:25 2011 Completed.
2242 Sat Apr 30 17:13:25 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2243 Sat Apr 30 17:13:25 2011 Completed.
2244 Sat Apr 30 17:13:25 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2245 Sat Apr 30 17:13:25 2011 Received.
2246 Sat Apr 30 17:13:25 2011 Performing 300 seconds pretest warmup.
2247 Sat Apr 30 17:18:25 2011 Completed.
2248 Sat Apr 30 17:18:25 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2249 Sat Apr 30 17:18:25 2011 Completed.
2250 Sat Apr 30 17:18:25 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2251 Sat Apr 30 17:18:26 2011 Received.
2252 Sat Apr 30 17:18:26 2011 Starting 300 seconds test run.
2253 SFS Single Client (c09-44) Results, Sat Apr 30 17:23:36 2011
2254 --------------------------------------------------------------------------------------
2255 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2256 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2257 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2258            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2259 --------------------------------------------------------------------------------------
2260 getattr     26.0%    25.9%    58738    58738      0      3.30    12.29      0.03  3.3%
2261 setattr      4.0%     4.0%     9146     9146      0     55.31   116.15      0.22  8.6%
2262 lookup      24.0%    24.0%    54457    54457      0      3.66    44.14      0.06  3.4%
2263 readlink     1.0%     1.0%     2277     2277      0      3.43    10.36      0.13  0.1%
2264 read        18.0%    18.0%    40709    49768      0     62.05   289.63      0.17 43.0%
2265 write       10.0%    10.0%    22719    27617      0     61.99   330.05      0.24 24.0%
2266 create       1.0%     1.0%     2265     2265      0    103.28   165.39      0.53  4.0%
2267 remove       1.0%     1.0%     2279     2279      0     62.91   134.99      0.48  2.4%
2268 readdir      1.0%     1.0%     2226     2226      0      3.37     9.71      0.13  0.1%
2269 fsstat       1.0%     1.0%     2304     2304      0      3.34     9.77      0.13  0.1%
2270 access      11.0%    11.0%    24843    24843      0      3.82    63.69      0.10  1.6%
2271 commit       0.0%     0.0%        0     8830      0      0.00     0.00      0.00  8.9%
2272 readdirplus  2.0%     2.0%     4560     4560      0      3.82    13.55      0.11  0.3%
2273 --------------------------------------------------------------------------------------
2274
2275       --------------------------------------------------
2276       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2277       --------------------------+-----------------------
2278 SFS NFS V3 THROUGHPUT:  755.07 Ops/Sec   AVG. RESPONSE TIME:   25.91 Msec/Op
2279 TCP PROTOCOL
2280 FAST CALLS: 0
2281 NFS V3 MIXFILE: [ SFS Default ]
2282 CLIENT REQUESTED LOAD: 1000 Ops/Sec
2283 TOTAL LOGICAL NFS V3 OPERATIONS:  226523         TEST TIME: 300 Sec
2284 TOTAL PHYSICAL NFS V3 OPERATIONS: 249310
2285 PHYSICAL NFS V3 THROUGHPUT:  831.03 Ops/Sec
2286 FILE SET SIZE CREATED: 122707728.0 KB
2287 FILE SET SIZE ACCESSED: 36813168.0 - 37223108.0 KB  (100.00% to 101.11% of Base)
2288
2289 ------------------------------------------------------------------------
2290
2291 Sat Apr 30 17:23:36 2011 Completed.
2292
2293 Client Test Parameters:
2294         Number of processes = 16
2295         Requested Load (NFS V3 operations/second) = 1000
2296         Maximum number of outstanding biod writes = 8
2297         Maximum number of outstanding biod reads = 8
2298         Warm-up time (seconds) = 300
2299         Run time (seconds) = 300
2300         File Set = 231088 Files created for I/O operations
2301                    69312 Files accessed for I/O operations
2302                    4624 Files for non-I/O operations
2303                      32 Symlinks
2304                    7696 Directories
2305                         Additional non-I/O files created as necessary
2306
2307
2308 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2309 Sat Apr 30 17:23:36 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2310 Sat Apr 30 17:23:36 2011 Completed.
2311 Sat Apr 30 17:23:36 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2312
2313 ************************************************************************
2314 Sat Apr 30 17:23:37 2011 Received.
2315 Sat Apr 30 17:23:37 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2316 Sat Apr 30 17:23:37 2011 Completed.
2317 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1100  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2318 NFS V3 Protocol Version
2319
2320 ************************************************************************
2321 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2322 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2323
2324 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2325 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2326
2327 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 17:23:39 2011
2328         Client hostname = c09-44
2329         Prime Client hostname = c09-44.sysnet.ucsd.edu
2330
2331 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2332 NFS V3 Protocol
2333 Sat Apr 30 17:23:39 2011 Mounting 16 remote test directories.
2334 Child[000]: NFS negotiated block size 32768
2335 Child[001]: NFS negotiated block size 32768
2336 Child[002]: NFS negotiated block size 32768
2337 Child[003]: NFS negotiated block size 32768
2338 Child[004]: NFS negotiated block size 32768
2339 Child[005]: NFS negotiated block size 32768
2340 Child[006]: NFS negotiated block size 32768
2341 Child[007]: NFS negotiated block size 32768
2342 Child[008]: NFS negotiated block size 32768
2343 Child[009]: NFS negotiated block size 32768
2344 Child[010]: NFS negotiated block size 32768
2345 Child[011]: NFS negotiated block size 32768
2346 Child[012]: NFS negotiated block size 32768
2347 Child[013]: NFS negotiated block size 32768
2348 Child[014]: NFS negotiated block size 32768
2349 Child[015]: NFS negotiated block size 32768
2350 Sat Apr 30 17:23:53 2011 Completed.
2351 Sat Apr 30 17:23:53 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2352 Sat Apr 30 17:23:53 2011 Completed.
2353 Sat Apr 30 17:23:53 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2354 Sat Apr 30 17:23:55 2011 Received.
2355
2356 Sat Apr 30 17:23:55 2011 Initializing test directories.
2357 Sat Apr 30 17:23:55 2011 Child 14 will create 527 directories.
2358 Sat Apr 30 17:23:55 2011 Child 15 will create 527 directories.
2359 Sat Apr 30 17:23:55 2011 Child 13 will create 527 directories.
2360 Sat Apr 30 17:23:55 2011 Child 1 will create 527 directories.
2361 Sat Apr 30 17:23:55 2011 Child 9 will create 527 directories.
2362 Sat Apr 30 17:23:55 2011 Child 11 will create 527 directories.
2363 Sat Apr 30 17:23:55 2011 Child 3 will create 527 directories.
2364 Sat Apr 30 17:23:55 2011 Child 5 will create 527 directories.
2365 Sat Apr 30 17:23:55 2011 Child 2 will create 527 directories.
2366 Sat Apr 30 17:23:55 2011 Child 7 will create 527 directories.
2367 Sat Apr 30 17:23:55 2011 Child 6 will create 527 directories.
2368 Sat Apr 30 17:23:55 2011 Child 8 will create 527 directories.
2369 Sat Apr 30 17:23:55 2011 Child 0 will create 527 directories.
2370 Sat Apr 30 17:23:55 2011 Child 4 will create 527 directories.
2371 Sat Apr 30 17:23:55 2011 Child 12 will create 527 directories.
2372 Sat Apr 30 17:23:55 2011 Child 10 will create 527 directories.
2373 Sat Apr 30 17:24:00 2011 Child 4 finished creating 527 directories.
2374 Sat Apr 30 17:24:00 2011 Child 4 will create 16118 files.
2375 Sat Apr 30 17:24:01 2011 Child 14 finished creating 527 directories.
2376 Sat Apr 30 17:24:01 2011 Child 14 will create 16118 files.
2377 Sat Apr 30 17:24:04 2011 Child 2 finished creating 527 directories.
2378 Sat Apr 30 17:24:04 2011 Child 2 will create 16118 files.
2379 Sat Apr 30 17:24:07 2011 Child 1 finished creating 527 directories.
2380 Sat Apr 30 17:24:07 2011 Child 1 will create 16118 files.
2381 Sat Apr 30 17:24:09 2011 Child 6 finished creating 527 directories.
2382 Sat Apr 30 17:24:09 2011 Child 6 will create 16118 files.
2383 Sat Apr 30 17:24:10 2011 Child 7 finished creating 527 directories.
2384 Sat Apr 30 17:24:10 2011 Child 7 will create 16118 files.
2385 Sat Apr 30 17:24:12 2011 Child 8 finished creating 527 directories.
2386 Sat Apr 30 17:24:12 2011 Child 8 will create 16118 files.
2387 Sat Apr 30 17:24:21 2011 Child 12 finished creating 527 directories.
2388 Sat Apr 30 17:24:21 2011 Child 12 will create 16118 files.
2389 Sat Apr 30 17:24:23 2011 Child 15 finished creating 527 directories.
2390 Sat Apr 30 17:24:23 2011 Child 15 will create 16118 files.
2391 Sat Apr 30 17:24:26 2011 Child 5 finished creating 527 directories.
2392 Sat Apr 30 17:24:26 2011 Child 5 will create 16118 files.
2393 Sat Apr 30 17:24:27 2011 Child 13 finished creating 527 directories.
2394 Sat Apr 30 17:24:27 2011 Child 13 will create 16118 files.
2395 Sat Apr 30 17:24:27 2011 Child 9 finished creating 527 directories.
2396 Sat Apr 30 17:24:27 2011 Child 9 will create 16118 files.
2397 Sat Apr 30 17:24:27 2011 Child 11 finished creating 527 directories.
2398 Sat Apr 30 17:24:27 2011 Child 11 will create 16118 files.
2399 Sat Apr 30 17:24:38 2011 Child 0 finished creating 527 directories.
2400 Sat Apr 30 17:24:38 2011 Child 0 will create 16118 files.
2401 Sat Apr 30 17:24:39 2011 Child 3 finished creating 527 directories.
2402 Sat Apr 30 17:24:39 2011 Child 3 will create 16118 files.
2403 Sat Apr 30 17:24:45 2011 Child 10 finished creating 527 directories.
2404 Sat Apr 30 17:24:45 2011 Child 10 will create 16118 files.
2405 Sat Apr 30 17:28:40 2011 Child 14 verified 16118 files, created 1517
2406 Sat Apr 30 17:28:40 2011 Child 14 will create 2 symlinks.
2407 Sat Apr 30 17:28:40 2011 Child 4 verified 16118 files, created 1517
2408 Sat Apr 30 17:28:40 2011 Child 4 will create 2 symlinks.
2409 Sat Apr 30 17:28:40 2011 Child 14 finished creating 2 symlinks.
2410 Sat Apr 30 17:28:40 2011 Child 4 finished creating 2 symlinks.
2411 Sat Apr 30 17:29:10 2011 Child 1 verified 15573 files (1130 creates).
2412 Sat Apr 30 17:29:14 2011 Child 2 verified 15672 files (1229 creates).
2413 Sat Apr 30 17:29:14 2011 Child 6 verified 15181 files (738 creates).
2414 Sat Apr 30 17:29:14 2011 Child 7 verified 15279 files (836 creates).
2415 Sat Apr 30 17:29:14 2011 Child 8 verified 15182 files (739 creates).
2416 Sat Apr 30 17:29:24 2011 Child 12 verified 15072 files (629 creates).
2417 Sat Apr 30 17:29:24 2011 Child 15 verified 15134 files (691 creates).
2418 Sat Apr 30 17:29:48 2011 Child 10 verified 14838 files (395 creates).
2419 Sat Apr 30 17:29:54 2011 Child 11 verified 14980 files (537 creates).
2420 Sat Apr 30 17:29:54 2011 Child 13 verified 14980 files (537 creates).
2421 Sat Apr 30 17:29:54 2011 Child 5 verified 14980 files (537 creates).
2422 Sat Apr 30 17:29:54 2011 Child 3 verified 14980 files (537 creates).
2423 Sat Apr 30 17:29:54 2011 Child 0 verified 14916 files (473 creates).
2424 Sat Apr 30 17:29:54 2011 Child 9 verified 14980 files (537 creates).
2425 Sat Apr 30 17:30:07 2011 Child 2 verified 16118 files, created 1517
2426 Sat Apr 30 17:30:07 2011 Child 2 will create 2 symlinks.
2427 Sat Apr 30 17:30:09 2011 Child 2 finished creating 2 symlinks.
2428 Sat Apr 30 17:30:27 2011 Child 1 verified 16118 files, created 1517
2429 Sat Apr 30 17:30:27 2011 Child 1 will create 2 symlinks.
2430 Sat Apr 30 17:30:34 2011 Child 1 finished creating 2 symlinks.
2431 Sat Apr 30 17:31:24 2011 Child 7 verified 16118 files, created 1517
2432 Sat Apr 30 17:31:24 2011 Child 7 will create 2 symlinks.
2433 Sat Apr 30 17:31:24 2011 Child 7 finished creating 2 symlinks.
2434 Sat Apr 30 17:31:31 2011 Child 8 verified 16118 files, created 1517
2435 Sat Apr 30 17:31:31 2011 Child 8 will create 2 symlinks.
2436 Sat Apr 30 17:31:31 2011 Child 8 finished creating 2 symlinks.
2437 Sat Apr 30 17:31:31 2011 Child 6 verified 16118 files, created 1517
2438 Sat Apr 30 17:31:31 2011 Child 6 will create 2 symlinks.
2439 Sat Apr 30 17:31:31 2011 Child 6 finished creating 2 symlinks.
2440 Sat Apr 30 17:31:49 2011 Child 15 verified 16118 files, created 1517
2441 Sat Apr 30 17:31:49 2011 Child 15 will create 2 symlinks.
2442 Sat Apr 30 17:31:49 2011 Child 15 finished creating 2 symlinks.
2443 Sat Apr 30 17:31:49 2011 Child 12 verified 16118 files, created 1517
2444 Sat Apr 30 17:31:49 2011 Child 12 will create 2 symlinks.
2445 Sat Apr 30 17:31:49 2011 Child 12 finished creating 2 symlinks.
2446 Sat Apr 30 17:32:19 2011 Child 9 verified 16118 files, created 1517
2447 Sat Apr 30 17:32:19 2011 Child 9 will create 2 symlinks.
2448 Sat Apr 30 17:32:19 2011 Child 0 verified 16118 files, created 1517
2449 Sat Apr 30 17:32:19 2011 Child 0 will create 2 symlinks.
2450 Sat Apr 30 17:32:19 2011 Child 9 finished creating 2 symlinks.
2451 Sat Apr 30 17:32:19 2011 Child 0 finished creating 2 symlinks.
2452 Sat Apr 30 17:32:19 2011 Child 3 verified 16118 files, created 1517
2453 Sat Apr 30 17:32:19 2011 Child 3 will create 2 symlinks.
2454 Sat Apr 30 17:32:19 2011 Child 3 finished creating 2 symlinks.
2455 Sat Apr 30 17:32:19 2011 Child 13 verified 16118 files, created 1517
2456 Sat Apr 30 17:32:19 2011 Child 13 will create 2 symlinks.
2457 Sat Apr 30 17:32:19 2011 Child 13 finished creating 2 symlinks.
2458 Sat Apr 30 17:32:20 2011 Child 11 verified 16118 files, created 1517
2459 Sat Apr 30 17:32:20 2011 Child 11 will create 2 symlinks.
2460 Sat Apr 30 17:32:20 2011 Child 5 verified 16118 files, created 1517
2461 Sat Apr 30 17:32:20 2011 Child 5 will create 2 symlinks.
2462 Sat Apr 30 17:32:20 2011 Child 11 finished creating 2 symlinks.
2463 Sat Apr 30 17:32:20 2011 Child 5 finished creating 2 symlinks.
2464 Sat Apr 30 17:32:20 2011 Child 10 verified 16118 files, created 1517
2465 Sat Apr 30 17:32:20 2011 Child 10 will create 2 symlinks.
2466 Sat Apr 30 17:32:20 2011 Child 10 finished creating 2 symlinks.
2467 Sat Apr 30 17:32:20 2011 Completed.
2468 Sat Apr 30 17:32:20 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2469 Sat Apr 30 17:32:20 2011 Completed.
2470 Sat Apr 30 17:32:20 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2471 Sat Apr 30 17:32:21 2011 Received.
2472 Sat Apr 30 17:32:21 2011 Performing 300 seconds pretest warmup.
2473 Sat Apr 30 17:37:21 2011 Completed.
2474 Sat Apr 30 17:37:21 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2475 Sat Apr 30 17:37:21 2011 Completed.
2476 Sat Apr 30 17:37:21 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2477 Sat Apr 30 17:37:22 2011 Received.
2478 Sat Apr 30 17:37:22 2011 Starting 300 seconds test run.
2479 SFS Single Client (c09-44) Results, Sat Apr 30 17:42:32 2011
2480 --------------------------------------------------------------------------------------
2481 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2482 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2483 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2484            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2485 --------------------------------------------------------------------------------------
2486 getattr     26.0%    25.9%    46098    46098      0      4.44    35.42      0.05  3.5%
2487 setattr      4.0%     3.9%     6979     6979      0     73.00   114.17      0.25  8.8%
2488 lookup      24.0%    24.3%    43202    43202      0      4.24    15.05      0.04  3.1%
2489 readlink     1.0%     1.0%     1801     1801      0      4.60    23.05      0.22  0.1%
2490 read        18.0%    17.9%    31869    38739      0     81.25   276.65      0.18 44.5%
2491 write       10.0%    10.0%    17825    21465      0     74.97   262.30      0.24 23.0%
2492 create       1.0%     0.9%     1657     1657      0    137.93   144.87      0.58  3.9%
2493 remove       1.0%     1.0%     1839     1839      0     86.62   124.68      0.51  2.7%
2494 readdir      1.0%     1.0%     1818     1818      0      4.56    16.71      0.19  0.1%
2495 fsstat       1.0%     1.0%     1782     1782      0      4.52    12.24      0.16  0.1%
2496 access      11.0%    10.9%    19467    19467      0      4.24    14.70      0.05  1.4%
2497 commit       0.0%     0.0%        0     6653      0      0.00     0.00      0.00  8.4%
2498 readdirplus  2.0%     2.0%     3635     3635      0      4.56    11.60      0.11  0.3%
2499 --------------------------------------------------------------------------------------
2500
2501       --------------------------------------------------
2502       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2503       --------------------------+-----------------------
2504 SFS NFS V3 THROUGHPUT:  597.22 Ops/Sec   AVG. RESPONSE TIME:   32.71 Msec/Op
2505 TCP PROTOCOL
2506 FAST CALLS: 0
2507 NFS V3 MIXFILE: [ SFS Default ]
2508 CLIENT REQUESTED LOAD: 1100 Ops/Sec
2509 TOTAL LOGICAL NFS V3 OPERATIONS:  177972         TEST TIME: 298 Sec
2510 TOTAL PHYSICAL NFS V3 OPERATIONS: 195135
2511 PHYSICAL NFS V3 THROUGHPUT:  654.81 Ops/Sec
2512 FILE SET SIZE CREATED: 134245296.0 KB
2513 FILE SET SIZE ACCESSED: 40279536.0 - 40599888.0 KB  (100.00% to 100.80% of Base)
2514
2515 ------------------------------------------------------------------------
2516
2517 Sat Apr 30 17:42:32 2011 Completed.
2518
2519 Client Test Parameters:
2520         Number of processes = 16
2521         Requested Load (NFS V3 operations/second) = 1100
2522         Maximum number of outstanding biod writes = 8
2523         Maximum number of outstanding biod reads = 8
2524         Warm-up time (seconds) = 300
2525         Run time (seconds) = 300
2526         File Set = 252816 Files created for I/O operations
2527                    75840 Files accessed for I/O operations
2528                    5072 Files for non-I/O operations
2529                      32 Symlinks
2530                    8416 Directories
2531                         Additional non-I/O files created as necessary
2532
2533
2534 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2535 Sat Apr 30 17:42:32 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2536 Sat Apr 30 17:42:32 2011 Completed.
2537 Sat Apr 30 17:42:32 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2538
2539 ************************************************************************
2540 Sat Apr 30 17:42:33 2011 Received.
2541 Sat Apr 30 17:42:33 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2542 Sat Apr 30 17:42:33 2011 Completed.
2543 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1200  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2544 NFS V3 Protocol Version
2545
2546 ************************************************************************
2547 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2548 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2549
2550 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2551 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2552
2553 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 17:42:35 2011
2554         Client hostname = c09-44
2555         Prime Client hostname = c09-44.sysnet.ucsd.edu
2556
2557 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2558 NFS V3 Protocol
2559 Sat Apr 30 17:42:35 2011 Mounting 16 remote test directories.
2560 Child[000]: NFS negotiated block size 32768
2561 Child[001]: NFS negotiated block size 32768
2562 Child[002]: NFS negotiated block size 32768
2563 Child[003]: NFS negotiated block size 32768
2564 Child[004]: NFS negotiated block size 32768
2565 Child[005]: NFS negotiated block size 32768
2566 Child[006]: NFS negotiated block size 32768
2567 Child[007]: NFS negotiated block size 32768
2568 Child[008]: NFS negotiated block size 32768
2569 Child[009]: NFS negotiated block size 32768
2570 Child[010]: NFS negotiated block size 32768
2571 Child[011]: NFS negotiated block size 32768
2572 Child[012]: NFS negotiated block size 32768
2573 Child[013]: NFS negotiated block size 32768
2574 Child[014]: NFS negotiated block size 32768
2575 Child[015]: NFS negotiated block size 32768
2576 Sat Apr 30 17:42:49 2011 Completed.
2577 Sat Apr 30 17:42:49 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2578 Sat Apr 30 17:42:49 2011 Completed.
2579 Sat Apr 30 17:42:49 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2580 Sat Apr 30 17:42:51 2011 Received.
2581
2582 Sat Apr 30 17:42:51 2011 Initializing test directories.
2583 Sat Apr 30 17:42:51 2011 Child 15 will create 577 directories.
2584 Sat Apr 30 17:42:51 2011 Child 10 will create 577 directories.
2585 Sat Apr 30 17:42:51 2011 Child 3 will create 577 directories.
2586 Sat Apr 30 17:42:51 2011 Child 0 will create 577 directories.
2587 Sat Apr 30 17:42:51 2011 Child 14 will create 577 directories.
2588 Sat Apr 30 17:42:51 2011 Child 1 will create 577 directories.
2589 Sat Apr 30 17:42:51 2011 Child 8 will create 577 directories.
2590 Sat Apr 30 17:42:51 2011 Child 2 will create 577 directories.
2591 Sat Apr 30 17:42:51 2011 Child 12 will create 577 directories.
2592 Sat Apr 30 17:42:51 2011 Child 9 will create 577 directories.
2593 Sat Apr 30 17:42:51 2011 Child 13 will create 577 directories.
2594 Sat Apr 30 17:42:51 2011 Child 4 will create 577 directories.
2595 Sat Apr 30 17:42:51 2011 Child 11 will create 577 directories.
2596 Sat Apr 30 17:42:51 2011 Child 7 will create 577 directories.
2597 Sat Apr 30 17:42:51 2011 Child 5 will create 577 directories.
2598 Sat Apr 30 17:42:51 2011 Child 6 will create 577 directories.
2599 Sat Apr 30 17:42:52 2011 Child 6 finished creating 577 directories.
2600 Sat Apr 30 17:42:52 2011 Child 6 will create 17628 files.
2601 Sat Apr 30 17:42:55 2011 Child 9 finished creating 577 directories.
2602 Sat Apr 30 17:42:55 2011 Child 9 will create 17628 files.
2603 Sat Apr 30 17:42:57 2011 Child 10 finished creating 577 directories.
2604 Sat Apr 30 17:42:57 2011 Child 10 will create 17628 files.
2605 Sat Apr 30 17:43:01 2011 Child 13 finished creating 577 directories.
2606 Sat Apr 30 17:43:01 2011 Child 13 will create 17628 files.
2607 Sat Apr 30 17:43:02 2011 Child 0 finished creating 577 directories.
2608 Sat Apr 30 17:43:02 2011 Child 0 will create 17628 files.
2609 Sat Apr 30 17:43:03 2011 Child 14 finished creating 577 directories.
2610 Sat Apr 30 17:43:03 2011 Child 14 will create 17628 files.
2611 Sat Apr 30 17:43:07 2011 Child 1 finished creating 577 directories.
2612 Sat Apr 30 17:43:07 2011 Child 1 will create 17628 files.
2613 Sat Apr 30 17:43:13 2011 Child 3 finished creating 577 directories.
2614 Sat Apr 30 17:43:13 2011 Child 3 will create 17628 files.
2615 Sat Apr 30 17:43:18 2011 Child 4 finished creating 577 directories.
2616 Sat Apr 30 17:43:18 2011 Child 4 will create 17628 files.
2617 Sat Apr 30 17:43:20 2011 Child 7 finished creating 577 directories.
2618 Sat Apr 30 17:43:20 2011 Child 7 will create 17628 files.
2619 Sat Apr 30 17:43:30 2011 Child 8 finished creating 577 directories.
2620 Sat Apr 30 17:43:30 2011 Child 8 will create 17628 files.
2621 Sat Apr 30 17:43:31 2011 Child 11 finished creating 577 directories.
2622 Sat Apr 30 17:43:31 2011 Child 11 will create 17628 files.
2623 Sat Apr 30 17:43:31 2011 Child 12 finished creating 577 directories.
2624 Sat Apr 30 17:43:31 2011 Child 12 will create 17628 files.
2625 Sat Apr 30 17:43:31 2011 Child 15 finished creating 577 directories.
2626 Sat Apr 30 17:43:31 2011 Child 15 will create 17628 files.
2627 Sat Apr 30 17:43:31 2011 Child 2 finished creating 577 directories.
2628 Sat Apr 30 17:43:31 2011 Child 2 will create 17628 files.
2629 Sat Apr 30 17:43:45 2011 Child 5 finished creating 577 directories.
2630 Sat Apr 30 17:43:45 2011 Child 5 will create 17628 files.
2631 Sat Apr 30 17:46:39 2011 Child 6 verified 17628 files, created 1655
2632 Sat Apr 30 17:46:39 2011 Child 6 will create 2 symlinks.
2633 Sat Apr 30 17:46:39 2011 Child 6 finished creating 2 symlinks.
2634 Sat Apr 30 17:47:08 2011 Child 10 verified 17628 files, created 1655
2635 Sat Apr 30 17:47:08 2011 Child 10 will create 2 symlinks.
2636 Sat Apr 30 17:47:08 2011 Child 10 finished creating 2 symlinks.
2637 Sat Apr 30 17:47:09 2011 Child 9 verified 17628 files, created 1655
2638 Sat Apr 30 17:47:09 2011 Child 9 will create 2 symlinks.
2639 Sat Apr 30 17:47:09 2011 Child 9 finished creating 2 symlinks.
2640 Sat Apr 30 17:48:05 2011 Child 0 verified 17043 files (1242 creates).
2641 Sat Apr 30 17:48:08 2011 Child 1 verified 17250 files (1449 creates).
2642 Sat Apr 30 17:48:14 2011 Child 3 verified 17139 files (1338 creates).
2643 Sat Apr 30 17:48:18 2011 Child 13 verified 17628 files, created 1655
2644 Sat Apr 30 17:48:18 2011 Child 13 will create 2 symlinks.
2645 Sat Apr 30 17:48:18 2011 Child 13 finished creating 2 symlinks.
2646 Sat Apr 30 17:48:22 2011 Child 4 verified 17073 files (1272 creates).
2647 Sat Apr 30 17:48:22 2011 Child 7 verified 16974 files (1173 creates).
2648 Sat Apr 30 17:48:30 2011 Child 1 verified 17628 files, created 1655
2649 Sat Apr 30 17:48:30 2011 Child 1 will create 2 symlinks.
2650 Sat Apr 30 17:48:32 2011 Child 2 verified 16973 files (1172 creates).
2651 Sat Apr 30 17:48:32 2011 Child 12 verified 16974 files (1173 creates).
2652 Sat Apr 30 17:48:32 2011 Child 11 verified 16852 files (1051 creates).
2653 Sat Apr 30 17:48:32 2011 Child 15 verified 16974 files (1173 creates).
2654 Sat Apr 30 17:48:33 2011 Child 8 verified 16974 files (1173 creates).
2655 Sat Apr 30 17:48:34 2011 Child 1 finished creating 2 symlinks.
2656 Sat Apr 30 17:48:49 2011 Child 5 verified 17043 files (1242 creates).
2657 Sat Apr 30 17:48:50 2011 Child 4 verified 17628 files, created 1655
2658 Sat Apr 30 17:48:50 2011 Child 4 will create 2 symlinks.
2659 Sat Apr 30 17:48:51 2011 Child 4 finished creating 2 symlinks.
2660 Sat Apr 30 17:49:01 2011 Child 7 verified 17628 files, created 1655
2661 Sat Apr 30 17:49:01 2011 Child 7 will create 2 symlinks.
2662 Sat Apr 30 17:49:01 2011 Child 7 finished creating 2 symlinks.
2663 Sat Apr 30 17:49:04 2011 Child 14 verified 17628 files, created 1655
2664 Sat Apr 30 17:49:04 2011 Child 14 will create 2 symlinks.
2665 Sat Apr 30 17:49:04 2011 Child 14 finished creating 2 symlinks.
2666 Sat Apr 30 17:49:04 2011 Child 12 verified 17628 files, created 1655
2667 Sat Apr 30 17:49:04 2011 Child 12 will create 2 symlinks.
2668 Sat Apr 30 17:49:04 2011 Child 12 finished creating 2 symlinks.
2669 Sat Apr 30 17:49:05 2011 Child 5 verified 17628 files, created 1655
2670 Sat Apr 30 17:49:05 2011 Child 5 will create 2 symlinks.
2671 Sat Apr 30 17:49:05 2011 Child 5 finished creating 2 symlinks.
2672 Sat Apr 30 17:49:06 2011 Child 11 verified 17628 files, created 1655
2673 Sat Apr 30 17:49:06 2011 Child 11 will create 2 symlinks.
2674 Sat Apr 30 17:49:06 2011 Child 11 finished creating 2 symlinks.
2675 Sat Apr 30 17:49:08 2011 Child 2 verified 17628 files, created 1655
2676 Sat Apr 30 17:49:08 2011 Child 2 will create 2 symlinks.
2677 Sat Apr 30 17:49:08 2011 Child 2 finished creating 2 symlinks.
2678 Sat Apr 30 17:49:09 2011 Child 8 verified 17628 files, created 1655
2679 Sat Apr 30 17:49:09 2011 Child 8 will create 2 symlinks.
2680 Sat Apr 30 17:49:09 2011 Child 8 finished creating 2 symlinks.
2681 Sat Apr 30 17:49:12 2011 Child 15 verified 17628 files, created 1655
2682 Sat Apr 30 17:49:12 2011 Child 15 will create 2 symlinks.
2683 Sat Apr 30 17:49:12 2011 Child 15 finished creating 2 symlinks.
2684 Sat Apr 30 17:49:23 2011 Child 3 verified 17628 files, created 1655
2685 Sat Apr 30 17:49:23 2011 Child 3 will create 2 symlinks.
2686 Sat Apr 30 17:49:23 2011 Child 3 finished creating 2 symlinks.
2687 Sat Apr 30 17:49:23 2011 Child 0 verified 17628 files, created 1655
2688 Sat Apr 30 17:49:23 2011 Child 0 will create 2 symlinks.
2689 Sat Apr 30 17:49:23 2011 Child 0 finished creating 2 symlinks.
2690 Sat Apr 30 17:49:23 2011 Completed.
2691 Sat Apr 30 17:49:23 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2692 Sat Apr 30 17:49:23 2011 Completed.
2693 Sat Apr 30 17:49:23 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2694 Sat Apr 30 17:49:24 2011 Received.
2695 Sat Apr 30 17:49:24 2011 Performing 300 seconds pretest warmup.
2696 Sat Apr 30 17:54:24 2011 Completed.
2697 Sat Apr 30 17:54:24 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2698 Sat Apr 30 17:54:24 2011 Completed.
2699 Sat Apr 30 17:54:24 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2700 Sat Apr 30 17:54:25 2011 Received.
2701 Sat Apr 30 17:54:25 2011 Starting 300 seconds test run.
2702 SFS Single Client (c09-44) Results, Sat Apr 30 17:59:48 2011
2703 --------------------------------------------------------------------------------------
2704 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2705 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2706 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2707            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2708 --------------------------------------------------------------------------------------
2709 getattr     26.0%    26.1%    33566    33566      0      4.91    17.04      0.04  2.7%
2710 setattr      4.0%     4.0%     5088     5088      0    102.64   177.47      0.37  8.7%
2711 lookup      24.0%    24.2%    31076    31076      0      4.98    27.59      0.06  2.6%
2712 readlink     1.0%     0.9%     1218     1218      0      4.50    12.90      0.20  0.1%
2713 read        18.0%    17.9%    22955    28150      0    118.12  1190.54      0.45 45.2%
2714 write       10.0%     9.9%    12663    15130      0    105.23  1333.39      0.64 22.2%
2715 create       1.0%     1.0%     1249     1249      0    218.91   295.93      0.95  4.6%
2716 remove       1.0%     1.0%     1272     1272      0    130.53   238.47      0.85  2.8%
2717 readdir      1.0%     1.0%     1289     1289      0      5.66    21.21      0.25  0.1%
2718 fsstat       1.0%     1.0%     1308     1308      0      4.63    13.11      0.20  0.1%
2719 access      11.0%    11.1%    14206    14206      0      9.09   473.65      0.36  2.2%
2720 commit       0.0%     0.0%        0     4548      0      0.00     0.00      0.00  8.6%
2721 readdirplus  2.0%     2.1%     2666     2666      0      5.50    13.48      0.14  0.2%
2722 --------------------------------------------------------------------------------------
2723
2724       --------------------------------------------------
2725       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2726       --------------------------+-----------------------
2727 SFS NFS V3 THROUGHPUT:  412.03 Ops/Sec   AVG. RESPONSE TIME:   46.69 Msec/Op
2728 TCP PROTOCOL
2729 FAST CALLS: 0
2730 NFS V3 MIXFILE: [ SFS Default ]
2731 CLIENT REQUESTED LOAD: 1200 Ops/Sec
2732 TOTAL LOGICAL NFS V3 OPERATIONS:  128556         TEST TIME: 312 Sec
2733 TOTAL PHYSICAL NFS V3 OPERATIONS: 140766
2734 PHYSICAL NFS V3 THROUGHPUT:  451.17 Ops/Sec
2735 FILE SET SIZE CREATED: 146827872.0 KB
2736 FILE SET SIZE ACCESSED: 44051760.0 - 44290780.0 KB  (100.00% to 100.54% of Base)
2737
2738 ------------------------------------------------------------------------
2739
2740 Sat Apr 30 17:59:48 2011 Completed.
2741
2742 Client Test Parameters:
2743         Number of processes = 16
2744         Requested Load (NFS V3 operations/second) = 1200
2745         Maximum number of outstanding biod writes = 8
2746         Maximum number of outstanding biod reads = 8
2747         Warm-up time (seconds) = 300
2748         Run time (seconds) = 300
2749         File Set = 276512 Files created for I/O operations
2750                    82944 Files accessed for I/O operations
2751                    5536 Files for non-I/O operations
2752                      32 Symlinks
2753                    9216 Directories
2754                         Additional non-I/O files created as necessary
2755
2756
2757 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2758 Sat Apr 30 17:59:48 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2759 Sat Apr 30 17:59:48 2011 Completed.
2760 Sat Apr 30 17:59:48 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2761
2762 ************************************************************************
2763 Sat Apr 30 17:59:48 2011 Received.
2764 Sat Apr 30 17:59:48 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2765 Sat Apr 30 17:59:48 2011 Completed.
2766 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1300  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2767 NFS V3 Protocol Version
2768
2769 ************************************************************************
2770 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2771 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2772
2773 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2774 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2775
2776 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 17:59:50 2011
2777         Client hostname = c09-44
2778         Prime Client hostname = c09-44.sysnet.ucsd.edu
2779
2780 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2781 NFS V3 Protocol
2782 Sat Apr 30 17:59:50 2011 Mounting 16 remote test directories.
2783 Child[000]: NFS negotiated block size 32768
2784 Child[001]: NFS negotiated block size 32768
2785 Child[002]: NFS negotiated block size 32768
2786 Child[003]: NFS negotiated block size 32768
2787 Child[004]: NFS negotiated block size 32768
2788 Child[005]: NFS negotiated block size 32768
2789 Child[006]: NFS negotiated block size 32768
2790 Child[007]: NFS negotiated block size 32768
2791 Child[008]: NFS negotiated block size 32768
2792 Child[009]: NFS negotiated block size 32768
2793 Child[010]: NFS negotiated block size 32768
2794 Child[011]: NFS negotiated block size 32768
2795 Child[012]: NFS negotiated block size 32768
2796 Child[013]: NFS negotiated block size 32768
2797 Child[014]: NFS negotiated block size 32768
2798 Child[015]: NFS negotiated block size 32768
2799 Sat Apr 30 18:00:04 2011 Completed.
2800 Sat Apr 30 18:00:04 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2801 Sat Apr 30 18:00:04 2011 Completed.
2802 Sat Apr 30 18:00:04 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2803 Sat Apr 30 18:00:06 2011 Received.
2804
2805 Sat Apr 30 18:00:06 2011 Initializing test directories.
2806 Sat Apr 30 18:00:06 2011 Child 12 will create 626 directories.
2807 Sat Apr 30 18:00:06 2011 Child 2 will create 626 directories.
2808 Sat Apr 30 18:00:06 2011 Child 5 will create 626 directories.
2809 Sat Apr 30 18:00:06 2011 Child 6 will create 626 directories.
2810 Sat Apr 30 18:00:06 2011 Child 3 will create 626 directories.
2811 Sat Apr 30 18:00:06 2011 Child 8 will create 626 directories.
2812 Sat Apr 30 18:00:06 2011 Child 11 will create 626 directories.
2813 Sat Apr 30 18:00:06 2011 Child 14 will create 626 directories.
2814 Sat Apr 30 18:00:06 2011 Child 4 will create 626 directories.
2815 Sat Apr 30 18:00:06 2011 Child 0 will create 626 directories.
2816 Sat Apr 30 18:00:06 2011 Child 1 will create 626 directories.
2817 Sat Apr 30 18:00:06 2011 Child 13 will create 626 directories.
2818 Sat Apr 30 18:00:06 2011 Child 7 will create 626 directories.
2819 Sat Apr 30 18:00:06 2011 Child 9 will create 626 directories.
2820 Sat Apr 30 18:00:06 2011 Child 15 will create 626 directories.
2821 Sat Apr 30 18:00:06 2011 Child 10 will create 626 directories.
2822 Sat Apr 30 18:00:08 2011 Child 12 finished creating 626 directories.
2823 Sat Apr 30 18:00:08 2011 Child 12 will create 19139 files.
2824 Sat Apr 30 18:00:09 2011 Child 5 finished creating 626 directories.
2825 Sat Apr 30 18:00:09 2011 Child 5 will create 19139 files.
2826 Sat Apr 30 18:00:11 2011 Child 13 finished creating 626 directories.
2827 Sat Apr 30 18:00:11 2011 Child 13 will create 19139 files.
2828 Sat Apr 30 18:00:14 2011 Child 7 finished creating 626 directories.
2829 Sat Apr 30 18:00:14 2011 Child 7 will create 19139 files.
2830 Sat Apr 30 18:00:14 2011 Child 4 finished creating 626 directories.
2831 Sat Apr 30 18:00:14 2011 Child 4 will create 19139 files.
2832 Sat Apr 30 18:00:16 2011 Child 1 finished creating 626 directories.
2833 Sat Apr 30 18:00:16 2011 Child 1 will create 19139 files.
2834 Sat Apr 30 18:00:39 2011 Child 10 finished creating 626 directories.
2835 Sat Apr 30 18:00:39 2011 Child 10 will create 19139 files.
2836 Sat Apr 30 18:00:41 2011 Child 6 finished creating 626 directories.
2837 Sat Apr 30 18:00:41 2011 Child 6 will create 19139 files.
2838 Sat Apr 30 18:00:46 2011 Child 0 finished creating 626 directories.
2839 Sat Apr 30 18:00:46 2011 Child 0 will create 19139 files.
2840 Sat Apr 30 18:00:46 2011 Child 14 finished creating 626 directories.
2841 Sat Apr 30 18:00:46 2011 Child 14 will create 19139 files.
2842 Sat Apr 30 18:00:48 2011 Child 3 finished creating 626 directories.
2843 Sat Apr 30 18:00:48 2011 Child 3 will create 19139 files.
2844 Sat Apr 30 18:01:09 2011 Child 8 finished creating 626 directories.
2845 Sat Apr 30 18:01:09 2011 Child 8 will create 19139 files.
2846 Sat Apr 30 18:01:09 2011 Child 2 finished creating 626 directories.
2847 Sat Apr 30 18:01:09 2011 Child 2 will create 19139 files.
2848 Sat Apr 30 18:01:09 2011 Child 15 finished creating 626 directories.
2849 Sat Apr 30 18:01:09 2011 Child 15 will create 19139 files.
2850 Sat Apr 30 18:01:10 2011 Child 11 finished creating 626 directories.
2851 Sat Apr 30 18:01:10 2011 Child 11 will create 19139 files.
2852 Sat Apr 30 18:01:13 2011 Child 9 finished creating 626 directories.
2853 Sat Apr 30 18:01:13 2011 Child 9 will create 19139 files.
2854 Sat Apr 30 18:03:57 2011 Child 12 verified 19139 files, created 1670
2855 Sat Apr 30 18:03:57 2011 Child 12 will create 2 symlinks.
2856 Sat Apr 30 18:04:04 2011 Child 12 finished creating 2 symlinks.
2857 Sat Apr 30 18:04:09 2011 Child 5 verified 19139 files, created 1670
2858 Sat Apr 30 18:04:09 2011 Child 5 will create 2 symlinks.
2859 Sat Apr 30 18:04:14 2011 Child 5 finished creating 2 symlinks.
2860 Sat Apr 30 18:04:53 2011 Child 13 verified 19139 files, created 1670
2861 Sat Apr 30 18:04:53 2011 Child 13 will create 2 symlinks.
2862 Sat Apr 30 18:04:53 2011 Child 13 finished creating 2 symlinks.
2863 Sat Apr 30 18:05:38 2011 Child 4 verified 18699 files (1417 creates).
2864 Sat Apr 30 18:05:40 2011 Child 10 verified 18090 files (808 creates).
2865 Sat Apr 30 18:05:51 2011 Child 6 verified 18026 files (744 creates).
2866 Sat Apr 30 18:05:51 2011 Child 3 verified 17936 files (654 creates).
2867 Sat Apr 30 18:05:51 2011 Child 0 verified 17958 files (676 creates).
2868 Sat Apr 30 18:05:51 2011 Child 1 verified 18654 files (1372 creates).
2869 Sat Apr 30 18:05:51 2011 Child 7 verified 18654 files (1372 creates).
2870 Sat Apr 30 18:06:27 2011 Child 14 verified 17991 files (709 creates).
2871 Sat Apr 30 18:06:28 2011 Child 9 verified 17958 files (676 creates).
2872 Sat Apr 30 18:06:28 2011 Child 2 verified 17958 files (676 creates).
2873 Sat Apr 30 18:06:28 2011 Child 11 verified 17958 files (676 creates).
2874 Sat Apr 30 18:06:28 2011 Child 8 verified 17958 files (676 creates).
2875 Sat Apr 30 18:06:28 2011 Child 15 verified 17936 files (654 creates).
2876 Sat Apr 30 18:06:49 2011 Child 1 verified 19139 files, created 1670
2877 Sat Apr 30 18:06:49 2011 Child 1 will create 2 symlinks.
2878 Sat Apr 30 18:06:49 2011 Child 1 finished creating 2 symlinks.
2879 Sat Apr 30 18:06:49 2011 Child 7 verified 19139 files, created 1670
2880 Sat Apr 30 18:06:49 2011 Child 7 will create 2 symlinks.
2881 Sat Apr 30 18:06:49 2011 Child 7 finished creating 2 symlinks.
2882 Sat Apr 30 18:08:10 2011 Child 10 verified 19139 files, created 1670
2883 Sat Apr 30 18:08:10 2011 Child 10 will create 2 symlinks.
2884 Sat Apr 30 18:08:13 2011 Child 4 verified 19139 files, created 1670
2885 Sat Apr 30 18:08:13 2011 Child 4 will create 2 symlinks.
2886 Sat Apr 30 18:08:15 2011 Child 4 finished creating 2 symlinks.
2887 Sat Apr 30 18:08:26 2011 Child 10 finished creating 2 symlinks.
2888 Sat Apr 30 18:08:27 2011 Child 6 verified 19139 files, created 1670
2889 Sat Apr 30 18:08:27 2011 Child 6 will create 2 symlinks.
2890 Sat Apr 30 18:08:38 2011 Child 6 finished creating 2 symlinks.
2891 Sat Apr 30 18:08:40 2011 Child 14 verified 19139 files, created 1670
2892 Sat Apr 30 18:08:40 2011 Child 14 will create 2 symlinks.
2893 Sat Apr 30 18:08:40 2011 Child 14 finished creating 2 symlinks.
2894 Sat Apr 30 18:08:41 2011 Child 0 verified 19139 files, created 1670
2895 Sat Apr 30 18:08:41 2011 Child 0 will create 2 symlinks.
2896 Sat Apr 30 18:08:41 2011 Child 0 finished creating 2 symlinks.
2897 Sat Apr 30 18:08:43 2011 Child 2 verified 19139 files, created 1670
2898 Sat Apr 30 18:08:43 2011 Child 2 will create 2 symlinks.
2899 Sat Apr 30 18:08:43 2011 Child 8 verified 19139 files, created 1670
2900 Sat Apr 30 18:08:43 2011 Child 8 will create 2 symlinks.
2901 Sat Apr 30 18:08:43 2011 Child 2 finished creating 2 symlinks.
2902 Sat Apr 30 18:08:43 2011 Child 8 finished creating 2 symlinks.
2903 Sat Apr 30 18:08:43 2011 Child 11 verified 19139 files, created 1670
2904 Sat Apr 30 18:08:43 2011 Child 11 will create 2 symlinks.
2905 Sat Apr 30 18:08:43 2011 Child 11 finished creating 2 symlinks.
2906 Sat Apr 30 18:08:44 2011 Child 9 verified 19139 files, created 1670
2907 Sat Apr 30 18:08:44 2011 Child 9 will create 2 symlinks.
2908 Sat Apr 30 18:08:44 2011 Child 9 finished creating 2 symlinks.
2909 Sat Apr 30 18:08:44 2011 Child 3 verified 19139 files, created 1670
2910 Sat Apr 30 18:08:44 2011 Child 3 will create 2 symlinks.
2911 Sat Apr 30 18:08:44 2011 Child 3 finished creating 2 symlinks.
2912 Sat Apr 30 18:08:45 2011 Child 15 verified 19139 files, created 1670
2913 Sat Apr 30 18:08:45 2011 Child 15 will create 2 symlinks.
2914 Sat Apr 30 18:08:45 2011 Child 15 finished creating 2 symlinks.
2915 Sat Apr 30 18:08:45 2011 Completed.
2916 Sat Apr 30 18:08:45 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2917 Sat Apr 30 18:08:45 2011 Completed.
2918 Sat Apr 30 18:08:45 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2919 Sat Apr 30 18:08:45 2011 Received.
2920 Sat Apr 30 18:08:45 2011 Performing 300 seconds pretest warmup.
2921 Sat Apr 30 18:13:45 2011 Completed.
2922 Sat Apr 30 18:13:45 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2923 Sat Apr 30 18:13:45 2011 Completed.
2924 Sat Apr 30 18:13:45 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2925 Sat Apr 30 18:13:46 2011 Received.
2926 Sat Apr 30 18:13:46 2011 Starting 300 seconds test run.
2927 SFS Single Client (c09-44) Results, Sat Apr 30 18:19:18 2011
2928 --------------------------------------------------------------------------------------
2929 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2930 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2931 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2932            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2933 --------------------------------------------------------------------------------------
2934 getattr     26.0%    26.0%    30483    30483      0      7.45   320.52      0.20  3.7%
2935 setattr      4.0%     3.9%     4634     4634      0    135.45   223.83      0.43 10.3%
2936 lookup      24.0%    24.2%    28393    28393      0      5.47    17.87      0.05  2.6%
2937 readlink     1.0%     1.0%     1121     1121      0      5.96    15.15      0.23  0.1%
2938 read        18.0%    17.9%    20981    25795      0    122.66  1166.38      0.46 42.3%
2939 write       10.0%    10.0%    11696    14125      0    110.13  1369.74      0.67 21.2%
2940 create       1.0%     1.0%     1185     1185      0    271.69   333.24      1.04  5.3%
2941 remove       1.0%     0.9%     1103     1103      0    166.75   288.90      1.00  3.0%
2942 readdir      1.0%     1.0%     1203     1203      0      5.27    13.20      0.21  0.1%
2943 fsstat       1.0%     1.0%     1184     1184      0      4.91    12.31      0.20  0.1%
2944 access      11.0%    11.0%    12964    12964      0      5.62    26.99      0.09  1.2%
2945 commit       0.0%     0.0%        0     4129      0      0.00     0.00      0.00  9.9%
2946 readdirplus  2.0%     2.0%     2385     2385      0      7.99    62.64      0.32  0.3%
2947 --------------------------------------------------------------------------------------
2948
2949       --------------------------------------------------
2950       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2951       --------------------------+-----------------------
2952 SFS NFS V3 THROUGHPUT:  365.52 Ops/Sec   AVG. RESPONSE TIME:   51.88 Msec/Op
2953 TCP PROTOCOL
2954 FAST CALLS: 0
2955 NFS V3 MIXFILE: [ SFS Default ]
2956 CLIENT REQUESTED LOAD: 1300 Ops/Sec
2957 TOTAL LOGICAL NFS V3 OPERATIONS:  117332         TEST TIME: 321 Sec
2958 TOTAL PHYSICAL NFS V3 OPERATIONS: 128704
2959 PHYSICAL NFS V3 THROUGHPUT:  400.94 Ops/Sec
2960 FILE SET SIZE CREATED: 159410448.0 KB
2961 FILE SET SIZE ACCESSED: 47823984.0 - 48038480.0 KB  (100.00% to 100.45% of Base)
2962
2963 ------------------------------------------------------------------------
2964
2965 Sat Apr 30 18:19:18 2011 Completed.
2966
2967 Client Test Parameters:
2968         Number of processes = 16
2969         Requested Load (NFS V3 operations/second) = 1300
2970         Maximum number of outstanding biod writes = 8
2971         Maximum number of outstanding biod reads = 8
2972         Warm-up time (seconds) = 300
2973         Run time (seconds) = 300
2974         File Set = 300208 Files created for I/O operations
2975                    90048 Files accessed for I/O operations
2976                    6016 Files for non-I/O operations
2977                      32 Symlinks
2978                    10000 Directories
2979                         Additional non-I/O files created as necessary
2980
2981
2982 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2983 Sat Apr 30 18:19:18 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2984 Sat Apr 30 18:19:18 2011 Completed.
2985 Sat Apr 30 18:19:18 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2986
2987 ************************************************************************
2988 Sat Apr 30 18:19:18 2011 Received.
2989 Sat Apr 30 18:19:18 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2990 Sat Apr 30 18:19:18 2011 Completed.
2991 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1400  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
2992 NFS V3 Protocol Version
2993
2994 ************************************************************************
2995 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2996 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2997
2998 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2999 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3000
3001 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 18:19:20 2011
3002         Client hostname = c09-44
3003         Prime Client hostname = c09-44.sysnet.ucsd.edu
3004
3005 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3006 NFS V3 Protocol
3007 Sat Apr 30 18:19:20 2011 Mounting 16 remote test directories.
3008 Child[000]: NFS negotiated block size 32768
3009 Child[001]: NFS negotiated block size 32768
3010 Child[002]: NFS negotiated block size 32768
3011 Child[003]: NFS negotiated block size 32768
3012 Child[004]: NFS negotiated block size 32768
3013 Child[005]: NFS negotiated block size 32768
3014 Child[006]: NFS negotiated block size 32768
3015 Child[007]: NFS negotiated block size 32768
3016 Child[008]: NFS negotiated block size 32768
3017 Child[009]: NFS negotiated block size 32768
3018 Child[010]: NFS negotiated block size 32768
3019 Child[011]: NFS negotiated block size 32768
3020 Child[012]: NFS negotiated block size 32768
3021 Child[013]: NFS negotiated block size 32768
3022 Child[014]: NFS negotiated block size 32768
3023 Child[015]: NFS negotiated block size 32768
3024 Sat Apr 30 18:19:34 2011 Completed.
3025 Sat Apr 30 18:19:34 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
3026 Sat Apr 30 18:19:34 2011 Completed.
3027 Sat Apr 30 18:19:34 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
3028 Sat Apr 30 18:19:36 2011 Received.
3029
3030 Sat Apr 30 18:19:36 2011 Initializing test directories.
3031 Sat Apr 30 18:19:36 2011 Child 10 will create 675 directories.
3032 Sat Apr 30 18:19:36 2011 Child 7 will create 675 directories.
3033 Sat Apr 30 18:19:36 2011 Child 3 will create 675 directories.
3034 Sat Apr 30 18:19:36 2011 Child 4 will create 675 directories.
3035 Sat Apr 30 18:19:36 2011 Child 9 will create 675 directories.
3036 Sat Apr 30 18:19:36 2011 Child 14 will create 675 directories.
3037 Sat Apr 30 18:19:36 2011 Child 5 will create 675 directories.
3038 Sat Apr 30 18:19:36 2011 Child 6 will create 675 directories.
3039 Sat Apr 30 18:19:36 2011 Child 12 will create 675 directories.
3040 Sat Apr 30 18:19:36 2011 Child 1 will create 675 directories.
3041 Sat Apr 30 18:19:36 2011 Child 2 will create 675 directories.
3042 Sat Apr 30 18:19:36 2011 Child 8 will create 675 directories.
3043 Sat Apr 30 18:19:36 2011 Child 0 will create 675 directories.
3044 Sat Apr 30 18:19:36 2011 Child 11 will create 675 directories.
3045 Sat Apr 30 18:19:36 2011 Child 13 will create 675 directories.
3046 Sat Apr 30 18:19:36 2011 Child 15 will create 675 directories.
3047 Sat Apr 30 18:19:38 2011 Child 0 finished creating 675 directories.
3048 Sat Apr 30 18:19:38 2011 Child 0 will create 20649 files.
3049 Sat Apr 30 18:19:40 2011 Child 13 finished creating 675 directories.
3050 Sat Apr 30 18:19:40 2011 Child 13 will create 20649 files.
3051 Sat Apr 30 18:19:44 2011 Child 14 finished creating 675 directories.
3052 Sat Apr 30 18:19:44 2011 Child 14 will create 20649 files.
3053 Sat Apr 30 18:19:45 2011 Child 10 finished creating 675 directories.
3054 Sat Apr 30 18:19:45 2011 Child 10 will create 20649 files.
3055 Sat Apr 30 18:19:51 2011 Child 12 finished creating 675 directories.
3056 Sat Apr 30 18:19:51 2011 Child 12 will create 20649 files.
3057 Sat Apr 30 18:19:51 2011 Child 4 finished creating 675 directories.
3058 Sat Apr 30 18:19:51 2011 Child 4 will create 20649 files.
3059 Sat Apr 30 18:19:52 2011 Child 8 finished creating 675 directories.
3060 Sat Apr 30 18:19:52 2011 Child 8 will create 20649 files.
3061 Sat Apr 30 18:19:54 2011 Child 7 finished creating 675 directories.
3062 Sat Apr 30 18:19:54 2011 Child 7 will create 20649 files.
3063 Sat Apr 30 18:20:00 2011 Child 11 finished creating 675 directories.
3064 Sat Apr 30 18:20:00 2011 Child 11 will create 20649 files.
3065 Sat Apr 30 18:20:03 2011 Child 1 finished creating 675 directories.
3066 Sat Apr 30 18:20:03 2011 Child 1 will create 20649 files.
3067 Sat Apr 30 18:20:08 2011 Child 2 finished creating 675 directories.
3068 Sat Apr 30 18:20:08 2011 Child 2 will create 20649 files.
3069 Sat Apr 30 18:20:16 2011 Child 15 finished creating 675 directories.
3070 Sat Apr 30 18:20:16 2011 Child 15 will create 20649 files.
3071 Sat Apr 30 18:20:58 2011 Child 3 finished creating 675 directories.
3072 Sat Apr 30 18:20:58 2011 Child 3 will create 20649 files.
3073 Sat Apr 30 18:20:58 2011 Child 9 finished creating 675 directories.
3074 Sat Apr 30 18:20:58 2011 Child 9 will create 20649 files.
3075 Sat Apr 30 18:20:58 2011 Child 6 finished creating 675 directories.
3076 Sat Apr 30 18:20:58 2011 Child 6 will create 20649 files.
3077 Sat Apr 30 18:20:58 2011 Child 5 finished creating 675 directories.
3078 Sat Apr 30 18:20:58 2011 Child 5 will create 20649 files.
3079 Sat Apr 30 18:22:36 2011 Child 0 verified 20649 files, created 1684
3080 Sat Apr 30 18:22:36 2011 Child 0 will create 2 symlinks.
3081 Sat Apr 30 18:22:36 2011 Child 0 finished creating 2 symlinks.
3082 Sat Apr 30 18:23:30 2011 Child 13 verified 20649 files, created 1684
3083 Sat Apr 30 18:23:30 2011 Child 13 will create 2 symlinks.
3084 Sat Apr 30 18:23:30 2011 Child 13 finished creating 2 symlinks.
3085 Sat Apr 30 18:24:01 2011 Child 14 verified 20649 files, created 1684
3086 Sat Apr 30 18:24:01 2011 Child 14 will create 2 symlinks.
3087 Sat Apr 30 18:24:02 2011 Child 14 finished creating 2 symlinks.
3088 Sat Apr 30 18:24:27 2011 Child 7 verified 20649 files, created 1684
3089 Sat Apr 30 18:24:27 2011 Child 7 will create 2 symlinks.
3090 Sat Apr 30 18:24:27 2011 Child 7 finished creating 2 symlinks.
3091 Sat Apr 30 18:24:32 2011 Child 4 verified 20649 files, created 1684
3092 Sat Apr 30 18:24:32 2011 Child 4 will create 2 symlinks.
3093 Sat Apr 30 18:24:32 2011 Child 4 finished creating 2 symlinks.
3094 Sat Apr 30 18:24:37 2011 Child 10 verified 20649 files, created 1684
3095 Sat Apr 30 18:24:37 2011 Child 10 will create 2 symlinks.
3096 Sat Apr 30 18:24:37 2011 Child 10 finished creating 2 symlinks.
3097 Sat Apr 30 18:24:37 2011 Child 12 verified 20649 files, created 1684
3098 Sat Apr 30 18:24:37 2011 Child 12 will create 2 symlinks.
3099 Sat Apr 30 18:24:38 2011 Child 12 finished creating 2 symlinks.
3100 Sat Apr 30 18:24:42 2011 Child 8 verified 20649 files, created 1684
3101 Sat Apr 30 18:24:42 2011 Child 8 will create 2 symlinks.
3102 Sat Apr 30 18:24:42 2011 Child 8 finished creating 2 symlinks.
3103 Sat Apr 30 18:24:53 2011 Child 11 verified 20649 files, created 1684
3104 Sat Apr 30 18:24:53 2011 Child 11 will create 2 symlinks.
3105 Sat Apr 30 18:24:53 2011 Child 11 finished creating 2 symlinks.
3106 Sat Apr 30 18:24:54 2011 Child 2 verified 20649 files, created 1684
3107 Sat Apr 30 18:24:54 2011 Child 2 will create 2 symlinks.
3108 Sat Apr 30 18:24:54 2011 Child 2 finished creating 2 symlinks.
3109 Sat Apr 30 18:25:05 2011 Child 1 verified 20649 files, created 1684
3110 Sat Apr 30 18:25:05 2011 Child 1 will create 2 symlinks.
3111 Sat Apr 30 18:25:05 2011 Child 1 finished creating 2 symlinks.
3112 Sat Apr 30 18:25:26 2011 Child 15 verified 20649 files, created 1684
3113 Sat Apr 30 18:25:26 2011 Child 15 will create 2 symlinks.
3114 Sat Apr 30 18:25:26 2011 Child 15 finished creating 2 symlinks.
3115 Sat Apr 30 18:25:29 2011 Child 3 verified 20649 files, created 1684
3116 Sat Apr 30 18:25:29 2011 Child 3 will create 2 symlinks.
3117 Sat Apr 30 18:25:29 2011 Child 3 finished creating 2 symlinks.
3118 Sat Apr 30 18:25:29 2011 Child 5 verified 20649 files, created 1684
3119 Sat Apr 30 18:25:29 2011 Child 5 will create 2 symlinks.
3120 Sat Apr 30 18:25:30 2011 Child 5 finished creating 2 symlinks.
3121 Sat Apr 30 18:25:30 2011 Child 9 verified 20649 files, created 1684
3122 Sat Apr 30 18:25:30 2011 Child 9 will create 2 symlinks.
3123 Sat Apr 30 18:25:30 2011 Child 9 finished creating 2 symlinks.
3124 Sat Apr 30 18:25:30 2011 Child 6 verified 20649 files, created 1684
3125 Sat Apr 30 18:25:30 2011 Child 6 will create 2 symlinks.
3126 Sat Apr 30 18:25:30 2011 Child 6 finished creating 2 symlinks.
3127 Sat Apr 30 18:25:30 2011 Completed.
3128 Sat Apr 30 18:25:30 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
3129 Sat Apr 30 18:25:30 2011 Completed.
3130 Sat Apr 30 18:25:30 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
3131 Sat Apr 30 18:25:30 2011 Received.
3132 Sat Apr 30 18:25:30 2011 Performing 300 seconds pretest warmup.
3133 Sat Apr 30 18:30:30 2011 Completed.
3134 Sat Apr 30 18:30:30 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
3135 Sat Apr 30 18:30:30 2011 Completed.
3136 Sat Apr 30 18:30:30 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
3137 Sat Apr 30 18:30:31 2011 Received.
3138 Sat Apr 30 18:30:31 2011 Starting 300 seconds test run.
3139 SFS Single Client (c09-44) Results, Sat Apr 30 18:35:42 2011
3140 --------------------------------------------------------------------------------------
3141 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
3142 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
3143 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
3144            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
3145 --------------------------------------------------------------------------------------
3146 getattr     26.0%    25.9%    33525    33525      0      6.05    17.69      0.05  3.5%
3147 setattr      4.0%     4.0%     5177     5177      0    114.22   194.75      0.38 10.1%
3148 lookup      24.0%    24.1%    31198    31198      0      6.10    17.38      0.05  3.3%
3149 readlink     1.0%     1.0%     1243     1243      0      5.89    17.50      0.23  0.1%
3150 read        18.0%    18.0%    23262    28621      0    111.48   505.13      0.29 44.4%
3151 write       10.0%     9.9%    12737    15377      0     86.47   491.74      0.39 18.9%
3152 create       1.0%     1.0%     1339     1339      0    232.15   302.29      0.93  5.3%
3153 remove       1.0%     0.9%     1199     1199      0    134.63   210.22      0.82  2.8%
3154 readdir      1.0%     1.0%     1256     1256      0      5.70    14.10      0.21  0.1%
3155 fsstat       1.0%     1.1%     1358     1358      0      6.24    22.30      0.25  0.1%
3156 access      11.0%    11.2%    14432    14432      0      5.90    15.82      0.06  1.5%
3157 commit       0.0%     0.0%        0     4499      0      0.00     0.00      0.00  9.6%
3158 readdirplus  2.0%     2.0%     2544     2544      0      6.83    15.40      0.15  0.3%
3159 --------------------------------------------------------------------------------------
3160
3161       --------------------------------------------------
3162       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
3163       --------------------------+-----------------------
3164 SFS NFS V3 THROUGHPUT:  432.34 Ops/Sec   AVG. RESPONSE TIME:   45.13 Msec/Op
3165 TCP PROTOCOL
3166 FAST CALLS: 0
3167 NFS V3 MIXFILE: [ SFS Default ]
3168 CLIENT REQUESTED LOAD: 1400 Ops/Sec
3169 TOTAL LOGICAL NFS V3 OPERATIONS:  129270         TEST TIME: 299 Sec
3170 TOTAL PHYSICAL NFS V3 OPERATIONS: 141768
3171 PHYSICAL NFS V3 THROUGHPUT:  474.14 Ops/Sec
3172 FILE SET SIZE CREATED: 171993024.0 KB
3173 FILE SET SIZE ACCESSED: 51604704.0 - 51841592.0 KB  (100.00% to 100.46% of Base)
3174
3175 ------------------------------------------------------------------------
3176
3177 Sat Apr 30 18:35:42 2011 Completed.
3178
3179 Client Test Parameters:
3180         Number of processes = 16
3181         Requested Load (NFS V3 operations/second) = 1400
3182         Maximum number of outstanding biod writes = 8
3183         Maximum number of outstanding biod reads = 8
3184         Warm-up time (seconds) = 300
3185         Run time (seconds) = 300
3186         File Set = 323904 Files created for I/O operations
3187                    97168 Files accessed for I/O operations
3188                    6480 Files for non-I/O operations
3189                      32 Symlinks
3190                    10784 Directories
3191                         Additional non-I/O files created as necessary
3192
3193
3194 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
3195 Sat Apr 30 18:35:42 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
3196 Sat Apr 30 18:35:42 2011 Completed.
3197 Sat Apr 30 18:35:42 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
3198
3199 ************************************************************************
3200 Sat Apr 30 18:35:42 2011 Received.
3201 Sat Apr 30 18:35:42 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
3202 Sat Apr 30 18:35:42 2011 Completed.
3203 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1500  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
3204 NFS V3 Protocol Version
3205
3206 ************************************************************************
3207 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
3208 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
3209
3210 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
3211 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3212
3213 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 18:35:44 2011
3214         Client hostname = c09-44
3215         Prime Client hostname = c09-44.sysnet.ucsd.edu
3216
3217 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3218 NFS V3 Protocol
3219 Sat Apr 30 18:35:44 2011 Mounting 16 remote test directories.
3220 Child[000]: NFS negotiated block size 32768
3221 Child[001]: NFS negotiated block size 32768
3222 Child[002]: NFS negotiated block size 32768
3223 Child[003]: NFS negotiated block size 32768
3224 Child[004]: NFS negotiated block size 32768
3225 Child[005]: NFS negotiated block size 32768
3226 Child[006]: NFS negotiated block size 32768
3227 Child[007]: NFS negotiated block size 32768
3228 Child[008]: NFS negotiated block size 32768
3229 Child[009]: NFS negotiated block size 32768
3230 Child[010]: NFS negotiated block size 32768
3231 Child[011]: NFS negotiated block size 32768
3232 Child[012]: NFS negotiated block size 32768
3233 Child[013]: NFS negotiated block size 32768
3234 Child[014]: NFS negotiated block size 32768
3235 Child[015]: NFS negotiated block size 32768
3236 Sat Apr 30 18:35:58 2011 Completed.
3237 Sat Apr 30 18:35:58 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
3238 Sat Apr 30 18:35:58 2011 Completed.
3239 Sat Apr 30 18:35:58 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
3240 Sat Apr 30 18:36:00 2011 Received.
3241
3242 Sat Apr 30 18:36:00 2011 Initializing test directories.
3243 Sat Apr 30 18:36:00 2011 Child 15 will create 721 directories.
3244 Sat Apr 30 18:36:00 2011 Child 14 will create 721 directories.
3245 Sat Apr 30 18:36:00 2011 Child 7 will create 721 directories.
3246 Sat Apr 30 18:36:00 2011 Child 11 will create 721 directories.
3247 Sat Apr 30 18:36:00 2011 Child 0 will create 721 directories.
3248 Sat Apr 30 18:36:00 2011 Child 8 will create 721 directories.
3249 Sat Apr 30 18:36:00 2011 Child 4 will create 721 directories.
3250 Sat Apr 30 18:36:00 2011 Child 5 will create 721 directories.
3251 Sat Apr 30 18:36:00 2011 Child 9 will create 721 directories.
3252 Sat Apr 30 18:36:00 2011 Child 3 will create 721 directories.
3253 Sat Apr 30 18:36:00 2011 Child 13 will create 721 directories.
3254 Sat Apr 30 18:36:00 2011 Child 10 will create 721 directories.
3255 Sat Apr 30 18:36:00 2011 Child 2 will create 721 directories.
3256 Sat Apr 30 18:36:00 2011 Child 6 will create 721 directories.
3257 Sat Apr 30 18:36:00 2011 Child 12 will create 721 directories.
3258 Sat Apr 30 18:36:00 2011 Child 1 will create 721 directories.
3259 Sat Apr 30 18:36:01 2011 Child 9 finished creating 721 directories.
3260 Sat Apr 30 18:36:01 2011 Child 9 will create 22035 files.
3261 Sat Apr 30 18:36:19 2011 Child 5 finished creating 721 directories.
3262 Sat Apr 30 18:36:19 2011 Child 5 will create 22035 files.
3263 Sat Apr 30 18:36:19 2011 Child 11 finished creating 721 directories.
3264 Sat Apr 30 18:36:19 2011 Child 11 will create 22035 files.
3265 Sat Apr 30 18:36:19 2011 Child 15 finished creating 721 directories.
3266 Sat Apr 30 18:36:19 2011 Child 15 will create 22035 files.
3267 Sat Apr 30 18:36:20 2011 Child 1 finished creating 721 directories.
3268 Sat Apr 30 18:36:20 2011 Child 1 will create 22035 files.
3269 Sat Apr 30 18:36:25 2011 Child 3 finished creating 721 directories.
3270 Sat Apr 30 18:36:25 2011 Child 3 will create 22035 files.
3271 Sat Apr 30 18:36:27 2011 Child 8 finished creating 721 directories.
3272 Sat Apr 30 18:36:27 2011 Child 8 will create 22035 files.
3273 Sat Apr 30 18:36:28 2011 Child 0 finished creating 721 directories.
3274 Sat Apr 30 18:36:28 2011 Child 0 will create 22035 files.
3275 Sat Apr 30 18:36:29 2011 Child 4 finished creating 721 directories.
3276 Sat Apr 30 18:36:29 2011 Child 4 will create 22035 files.
3277 Sat Apr 30 18:36:31 2011 Child 7 finished creating 721 directories.
3278 Sat Apr 30 18:36:31 2011 Child 7 will create 22035 files.
3279 Sat Apr 30 18:36:31 2011 Child 10 finished creating 721 directories.
3280 Sat Apr 30 18:36:31 2011 Child 10 will create 22035 files.
3281 Sat Apr 30 18:36:36 2011 Child 6 finished creating 721 directories.
3282 Sat Apr 30 18:36:36 2011 Child 6 will create 22035 files.
3283 Sat Apr 30 18:36:43 2011 Child 13 finished creating 721 directories.
3284 Sat Apr 30 18:36:43 2011 Child 13 will create 22035 files.
3285 Sat Apr 30 18:36:56 2011 Child 14 finished creating 721 directories.
3286 Sat Apr 30 18:36:56 2011 Child 14 will create 22035 files.
3287 Sat Apr 30 18:37:09 2011 Child 12 finished creating 721 directories.
3288 Sat Apr 30 18:37:09 2011 Child 12 will create 22035 files.
3289 Sat Apr 30 18:37:10 2011 Child 2 finished creating 721 directories.
3290 Sat Apr 30 18:37:10 2011 Child 2 will create 22035 files.
3291 Sat Apr 30 18:37:39 2011 Child 9 verified 22035 files, created 1575
3292 Sat Apr 30 18:37:39 2011 Child 9 will create 2 symlinks.
3293 Sat Apr 30 18:37:39 2011 Child 9 finished creating 2 symlinks.
3294 Sat Apr 30 18:41:49 2011 Child 1 verified 21366 files (1122 creates).
3295 Sat Apr 30 18:41:49 2011 Child 13 verified 20642 files (398 creates).
3296 Sat Apr 30 18:41:49 2011 Child 3 verified 21041 files (797 creates).
3297 Sat Apr 30 18:41:49 2011 Child 15 verified 21185 files (941 creates).
3298 Sat Apr 30 18:41:49 2011 Child 5 verified 21395 files (1151 creates).
3299 Sat Apr 30 18:41:49 2011 Child 11 verified 21395 files (1151 creates).
3300 Sat Apr 30 18:41:49 2011 Child 0 verified 20916 files (672 creates).
3301 Sat Apr 30 18:41:50 2011 Child 4 verified 20898 files (654 creates).
3302 Sat Apr 30 18:41:50 2011 Child 10 verified 20898 files (654 creates).
3303 Sat Apr 30 18:41:50 2011 Child 6 verified 20827 files (583 creates).
3304 Sat Apr 30 18:41:50 2011 Child 7 verified 20898 files (654 creates).
3305 Sat Apr 30 18:41:50 2011 Child 8 verified 20944 files (700 creates).
3306 Sat Apr 30 18:41:58 2011 Child 14 verified 20588 files (344 creates).
3307 Sat Apr 30 18:42:19 2011 Child 12 verified 20537 files (293 creates).
3308 Sat Apr 30 18:42:19 2011 Child 2 verified 20537 files (293 creates).
3309 Sat Apr 30 18:42:45 2011 Child 5 verified 22035 files, created 1575
3310 Sat Apr 30 18:42:45 2011 Child 5 will create 2 symlinks.
3311 Sat Apr 30 18:42:45 2011 Child 5 finished creating 2 symlinks.
3312 Sat Apr 30 18:42:46 2011 Child 11 verified 22035 files, created 1575
3313 Sat Apr 30 18:42:46 2011 Child 11 will create 2 symlinks.
3314 Sat Apr 30 18:42:47 2011 Child 11 finished creating 2 symlinks.
3315 Sat Apr 30 18:42:59 2011 Child 1 verified 22035 files, created 1575
3316 Sat Apr 30 18:42:59 2011 Child 1 will create 2 symlinks.
3317 Sat Apr 30 18:42:59 2011 Child 1 finished creating 2 symlinks.
3318 Sat Apr 30 18:43:16 2011 Child 15 verified 22035 files, created 1575
3319 Sat Apr 30 18:43:16 2011 Child 15 will create 2 symlinks.
3320 Sat Apr 30 18:43:16 2011 Child 15 finished creating 2 symlinks.
3321 Sat Apr 30 18:43:33 2011 Child 3 verified 22035 files, created 1575
3322 Sat Apr 30 18:43:33 2011 Child 3 will create 2 symlinks.
3323 Sat Apr 30 18:43:33 2011 Child 3 finished creating 2 symlinks.
3324 Sat Apr 30 18:43:38 2011 Child 8 verified 22035 files, created 1575
3325 Sat Apr 30 18:43:38 2011 Child 8 will create 2 symlinks.
3326 Sat Apr 30 18:43:38 2011 Child 8 finished creating 2 symlinks.
3327 Sat Apr 30 18:43:41 2011 Child 0 verified 22035 files, created 1575
3328 Sat Apr 30 18:43:41 2011 Child 0 will create 2 symlinks.
3329 Sat Apr 30 18:43:41 2011 Child 0 finished creating 2 symlinks.
3330 Sat Apr 30 18:43:44 2011 Child 4 verified 22035 files, created 1575
3331 Sat Apr 30 18:43:44 2011 Child 4 will create 2 symlinks.
3332 Sat Apr 30 18:43:44 2011 Child 4 finished creating 2 symlinks.
3333 Sat Apr 30 18:43:45 2011 Child 7 verified 22035 files, created 1575
3334 Sat Apr 30 18:43:45 2011 Child 7 will create 2 symlinks.
3335 Sat Apr 30 18:43:45 2011 Child 10 verified 22035 files, created 1575
3336 Sat Apr 30 18:43:45 2011 Child 10 will create 2 symlinks.
3337 Sat Apr 30 18:43:46 2011 Child 7 finished creating 2 symlinks.
3338 Sat Apr 30 18:43:46 2011 Child 10 finished creating 2 symlinks.
3339 Sat Apr 30 18:43:46 2011 Child 6 verified 22035 files, created 1575
3340 Sat Apr 30 18:43:46 2011 Child 6 will create 2 symlinks.
3341 Sat Apr 30 18:43:46 2011 Child 6 finished creating 2 symlinks.
3342 Sat Apr 30 18:43:51 2011 Child 13 verified 22035 files, created 1575
3343 Sat Apr 30 18:43:51 2011 Child 13 will create 2 symlinks.
3344 Sat Apr 30 18:43:51 2011 Child 13 finished creating 2 symlinks.
3345 Sat Apr 30 18:43:55 2011 Child 14 verified 22035 files, created 1575
3346 Sat Apr 30 18:43:55 2011 Child 14 will create 2 symlinks.
3347 Sat Apr 30 18:43:55 2011 Child 14 finished creating 2 symlinks.
3348 Sat Apr 30 18:43:57 2011 Child 2 verified 22035 files, created 1575
3349 Sat Apr 30 18:43:57 2011 Child 2 will create 2 symlinks.
3350 Sat Apr 30 18:43:57 2011 Child 2 finished creating 2 symlinks.
3351 Sat Apr 30 18:43:57 2011 Child 12 verified 22035 files, created 1575
3352 Sat Apr 30 18:43:57 2011 Child 12 will create 2 symlinks.
3353 Sat Apr 30 18:43:57 2011 Child 12 finished creating 2 symlinks.
3354 Sat Apr 30 18:43:57 2011 Completed.
3355 Sat Apr 30 18:43:57 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
3356 Sat Apr 30 18:43:57 2011 Completed.
3357 Sat Apr 30 18:43:57 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
3358 Sat Apr 30 18:43:58 2011 Received.
3359 Sat Apr 30 18:43:58 2011 Performing 300 seconds pretest warmup.
3360 Sat Apr 30 18:48:58 2011 Completed.
3361 Sat Apr 30 18:48:58 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
3362 Sat Apr 30 18:48:58 2011 Completed.
3363 Sat Apr 30 18:48:58 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
3364 Sat Apr 30 18:48:59 2011 Received.
3365 Sat Apr 30 18:48:59 2011 Starting 300 seconds test run.
3366 SFS Single Client (c09-44) Results, Sat Apr 30 18:54:10 2011
3367 --------------------------------------------------------------------------------------
3368 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
3369 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
3370 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
3371            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
3372 --------------------------------------------------------------------------------------
3373 getattr     26.0%    25.9%    28386    28386      0      7.33    62.52      0.09  3.6%
3374 setattr      4.0%     4.1%     4445     4445      0    144.34   231.22      0.45 11.0%
3375 lookup      24.0%    24.1%    26434    26434      0      6.95    28.99      0.06  3.2%
3376 readlink     1.0%     1.0%     1096     1096      0      6.60    17.24      0.25  0.1%
3377 read        18.0%    18.1%    19899    24274      0    125.76   284.90      0.23 43.0%
3378 write       10.0%     9.8%    10790    13078      0    100.16   260.68      0.30 18.6%
3379 create       1.0%     1.0%     1109     1109      0    288.14   317.28      1.05  5.5%
3380 remove       1.0%     1.0%     1050     1050      0    168.11   225.66      0.91  3.0%
3381 readdir      1.0%     1.0%     1063     1063      0      7.90    19.65      0.27  0.1%
3382 fsstat       1.0%     1.0%     1112     1112      0      6.34    16.45      0.24  0.1%
3383 access      11.0%    11.0%    12112    12112      0      7.94    92.04      0.17  1.7%
3384 commit       0.0%     0.0%        0     3775      0      0.00     0.00      0.00  9.9%
3385 readdirplus  2.0%     2.0%     2149     2149      0      7.55    21.27      0.19  0.3%
3386 --------------------------------------------------------------------------------------
3387
3388       --------------------------------------------------
3389       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
3390       --------------------------+-----------------------
3391 SFS NFS V3 THROUGHPUT:  366.70 Ops/Sec   AVG. RESPONSE TIME:   53.12 Msec/Op
3392 TCP PROTOCOL
3393 FAST CALLS: 0
3394 NFS V3 MIXFILE: [ SFS Default ]
3395 CLIENT REQUESTED LOAD: 1500 Ops/Sec
3396 TOTAL LOGICAL NFS V3 OPERATIONS:  109645         TEST TIME: 299 Sec
3397 TOTAL PHYSICAL NFS V3 OPERATIONS: 120083
3398 PHYSICAL NFS V3 THROUGHPUT:  401.61 Ops/Sec
3399 FILE SET SIZE CREATED: 183530592.0 KB
3400 FILE SET SIZE ACCESSED: 55062576.0 - 55266528.0 KB  (100.00% to 100.37% of Base)
3401
3402 ------------------------------------------------------------------------
3403
3404 Sat Apr 30 18:54:10 2011 Completed.
3405
3406 Client Test Parameters:
3407         Number of processes = 16
3408         Requested Load (NFS V3 operations/second) = 1500
3409         Maximum number of outstanding biod writes = 8
3410         Maximum number of outstanding biod reads = 8
3411         Warm-up time (seconds) = 300
3412         Run time (seconds) = 300
3413         File Set = 345632 Files created for I/O operations
3414                    103680 Files accessed for I/O operations
3415                    6928 Files for non-I/O operations
3416                      32 Symlinks
3417                    11520 Directories
3418                         Additional non-I/O files created as necessary
3419
3420
3421 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
3422 Sat Apr 30 18:54:10 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
3423 Sat Apr 30 18:54:10 2011 Completed.
3424 Sat Apr 30 18:54:10 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
3425
3426 ************************************************************************
3427 Sat Apr 30 18:54:10 2011 Received.
3428 Sat Apr 30 18:54:10 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
3429 Sat Apr 30 18:54:10 2011 Completed.