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

   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 09:18:15 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 09:18:15 2011 Mounting 16 remote test directories.
  18 Child[002]: NFS negotiated block size 32768
  19 Child[003]: NFS negotiated block size 32768
  20 Child[006]: NFS negotiated block size 32768
  21 Child[007]: NFS negotiated block size 32768
  22 Child[010]: NFS negotiated block size 32768
  23 Child[012]: NFS negotiated block size 32768
  24 Child[015]: NFS negotiated block size 32768
  25 Child[000]: NFS negotiated block size 32768
  26 Child[001]: NFS negotiated block size 32768
  27 Child[004]: NFS negotiated block size 32768
  28 Child[005]: NFS negotiated block size 32768
  29 Child[008]: NFS negotiated block size 32768
  30 Child[009]: NFS negotiated block size 32768
  31 Child[011]: NFS negotiated block size 32768
  32 Child[013]: NFS negotiated block size 32768
  33 Child[014]: NFS negotiated block size 32768
  34 Sat Apr 30 09:18:33 2011 Completed.
  35 Sat Apr 30 09:18:33 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
  36 Sat Apr 30 09:18:33 2011 Completed.
  37 Sat Apr 30 09:18:33 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
  38 Sat Apr 30 09:18:33 2011 Received.
  39
  40 Sat Apr 30 09:18:33 2011 Initializing test directories.
  41 Sat Apr 30 09:18:33 2011 Child 10 will create 46 directories.
  42 Sat Apr 30 09:18:33 2011 Child 15 will create 46 directories.
  43 Sat Apr 30 09:18:33 2011 Child 6 will create 46 directories.
  44 Sat Apr 30 09:18:33 2011 Child 11 will create 46 directories.
  45 Sat Apr 30 09:18:33 2011 Child 1 will create 46 directories.
  46 Sat Apr 30 09:18:33 2011 Child 0 will create 46 directories.
  47 Sat Apr 30 09:18:33 2011 Child 2 will create 46 directories.
  48 Sat Apr 30 09:18:33 2011 Child 4 will create 46 directories.
  49 Sat Apr 30 09:18:33 2011 Child 8 will create 46 directories.
  50 Sat Apr 30 09:18:33 2011 Child 9 will create 46 directories.
  51 Sat Apr 30 09:18:33 2011 Child 5 will create 46 directories.
  52 Sat Apr 30 09:18:33 2011 Child 3 will create 46 directories.
  53 Sat Apr 30 09:18:33 2011 Child 7 will create 46 directories.
  54 Sat Apr 30 09:18:33 2011 Child 12 will create 46 directories.
  55 Sat Apr 30 09:18:33 2011 Child 13 will create 46 directories.
  56 Sat Apr 30 09:18:33 2011 Child 14 will create 46 directories.
  57 Sat Apr 30 09:18:33 2011 Child 13 finished creating 46 directories.
  58 Sat Apr 30 09:18:33 2011 Child 13 will create 1386 files.
  59 Sat Apr 30 09:18:33 2011 Child 8 finished creating 46 directories.
  60 Sat Apr 30 09:18:33 2011 Child 8 will create 1386 files.
  61 Sat Apr 30 09:18:33 2011 Child 9 finished creating 46 directories.
  62 Sat Apr 30 09:18:33 2011 Child 9 will create 1386 files.
  63 Sat Apr 30 09:18:33 2011 Child 12 finished creating 46 directories.
  64 Sat Apr 30 09:18:33 2011 Child 12 will create 1386 files.
  65 Sat Apr 30 09:18:33 2011 Child 11 finished creating 46 directories.
  66 Sat Apr 30 09:18:33 2011 Child 11 will create 1386 files.
  67 Sat Apr 30 09:18:33 2011 Child 5 finished creating 46 directories.
  68 Sat Apr 30 09:18:33 2011 Child 5 will create 1386 files.
  69 Sat Apr 30 09:18:33 2011 Child 6 finished creating 46 directories.
  70 Sat Apr 30 09:18:33 2011 Child 6 will create 1386 files.
  71 Sat Apr 30 09:18:33 2011 Child 4 finished creating 46 directories.
  72 Sat Apr 30 09:18:33 2011 Child 4 will create 1386 files.
  73 Sat Apr 30 09:18:33 2011 Child 1 finished creating 46 directories.
  74 Sat Apr 30 09:18:33 2011 Child 1 will create 1386 files.
  75 Sat Apr 30 09:18:33 2011 Child 3 finished creating 46 directories.
  76 Sat Apr 30 09:18:33 2011 Child 3 will create 1386 files.
  77 Sat Apr 30 09:18:33 2011 Child 10 finished creating 46 directories.
  78 Sat Apr 30 09:18:33 2011 Child 10 will create 1386 files.
  79 Sat Apr 30 09:18:33 2011 Child 0 finished creating 46 directories.
  80 Sat Apr 30 09:18:33 2011 Child 0 will create 1386 files.
  81 Sat Apr 30 09:18:33 2011 Child 2 finished creating 46 directories.
  82 Sat Apr 30 09:18:33 2011 Child 2 will create 1386 files.
  83 Sat Apr 30 09:18:33 2011 Child 15 finished creating 46 directories.
  84 Sat Apr 30 09:18:33 2011 Child 15 will create 1386 files.
  85 Sat Apr 30 09:18:33 2011 Child 7 finished creating 46 directories.
  86 Sat Apr 30 09:18:33 2011 Child 7 will create 1386 files.
  87 Sat Apr 30 09:18:33 2011 Child 14 finished creating 46 directories.
  88 Sat Apr 30 09:18:33 2011 Child 14 will create 1386 files.
  89 Sat Apr 30 09:23:38 2011 Child 9 verified 996 files (996 creates).
  90 Sat Apr 30 09:23:38 2011 Child 3 verified 1020 files (1020 creates).
  91 Sat Apr 30 09:23:38 2011 Child 0 verified 1020 files (1020 creates).
  92 Sat Apr 30 09:23:38 2011 Child 15 verified 1121 files (1121 creates).
  93 Sat Apr 30 09:23:38 2011 Child 10 verified 1118 files (1118 creates).
  94 Sat Apr 30 09:23:38 2011 Child 6 verified 1067 files (1067 creates).
  95 Sat Apr 30 09:23:38 2011 Child 13 verified 1118 files (1118 creates).
  96 Sat Apr 30 09:23:38 2011 Child 7 verified 1118 files (1118 creates).
  97 Sat Apr 30 09:23:38 2011 Child 11 verified 1100 files (1100 creates).
  98 Sat Apr 30 09:23:38 2011 Child 8 verified 996 files (996 creates).
  99 Sat Apr 30 09:23:38 2011 Child 4 verified 1020 files (1020 creates).
 100 Sat Apr 30 09:23:38 2011 Child 5 verified 1042 files (1042 creates).
 101 Sat Apr 30 09:23:38 2011 Child 2 verified 1042 files (1042 creates).
 102 Sat Apr 30 09:23:38 2011 Child 1 verified 1042 files (1042 creates).
 103 Sat Apr 30 09:23:38 2011 Child 14 verified 1042 files (1042 creates).
 104 Sat Apr 30 09:23:38 2011 Child 12 verified 1118 files (1118 creates).
 105 Sat Apr 30 09:24:33 2011 Child 12 verified 1386 files, created 1373
 106 Sat Apr 30 09:24:33 2011 Child 12 will create 2 symlinks.
 107 Sat Apr 30 09:24:33 2011 Child 12 finished creating 2 symlinks.
 108 Sat Apr 30 09:24:33 2011 Child 15 verified 1386 files, created 1373
 109 Sat Apr 30 09:24:33 2011 Child 15 will create 2 symlinks.
 110 Sat Apr 30 09:24:33 2011 Child 15 finished creating 2 symlinks.
 111 Sat Apr 30 09:24:36 2011 Child 7 verified 1386 files, created 1373
 112 Sat Apr 30 09:24:36 2011 Child 7 will create 2 symlinks.
 113 Sat Apr 30 09:24:36 2011 Child 13 verified 1386 files, created 1373
 114 Sat Apr 30 09:24:36 2011 Child 13 will create 2 symlinks.
 115 Sat Apr 30 09:24:36 2011 Child 7 finished creating 2 symlinks.
 116 Sat Apr 30 09:24:36 2011 Child 13 finished creating 2 symlinks.
 117 Sat Apr 30 09:24:36 2011 Child 11 verified 1386 files, created 1373
 118 Sat Apr 30 09:24:36 2011 Child 11 will create 2 symlinks.
 119 Sat Apr 30 09:24:36 2011 Child 10 verified 1386 files, created 1373
 120 Sat Apr 30 09:24:36 2011 Child 10 will create 2 symlinks.
 121 Sat Apr 30 09:24:36 2011 Child 11 finished creating 2 symlinks.
 122 Sat Apr 30 09:24:36 2011 Child 10 finished creating 2 symlinks.
 123 Sat Apr 30 09:24:39 2011 Child 2 verified 1386 files, created 1373
 124 Sat Apr 30 09:24:39 2011 Child 2 will create 2 symlinks.
 125 Sat Apr 30 09:24:39 2011 Child 2 finished creating 2 symlinks.
 126 Sat Apr 30 09:24:40 2011 Child 3 verified 1386 files, created 1373
 127 Sat Apr 30 09:24:40 2011 Child 3 will create 2 symlinks.
 128 Sat Apr 30 09:24:40 2011 Child 3 finished creating 2 symlinks.
 129 Sat Apr 30 09:24:40 2011 Child 4 verified 1386 files, created 1373
 130 Sat Apr 30 09:24:40 2011 Child 4 will create 2 symlinks.
 131 Sat Apr 30 09:24:40 2011 Child 6 verified 1386 files, created 1373
 132 Sat Apr 30 09:24:40 2011 Child 6 will create 2 symlinks.
 133 Sat Apr 30 09:24:40 2011 Child 4 finished creating 2 symlinks.
 134 Sat Apr 30 09:24:40 2011 Child 6 finished creating 2 symlinks.
 135 Sat Apr 30 09:24:40 2011 Child 1 verified 1386 files, created 1373
 136 Sat Apr 30 09:24:40 2011 Child 1 will create 2 symlinks.
 137 Sat Apr 30 09:24:40 2011 Child 1 finished creating 2 symlinks.
 138 Sat Apr 30 09:24:40 2011 Child 0 verified 1386 files, created 1373
 139 Sat Apr 30 09:24:40 2011 Child 0 will create 2 symlinks.
 140 Sat Apr 30 09:24:40 2011 Child 0 finished creating 2 symlinks.
 141 Sat Apr 30 09:24:40 2011 Child 14 verified 1386 files, created 1373
 142 Sat Apr 30 09:24:40 2011 Child 14 will create 2 symlinks.
 143 Sat Apr 30 09:24:40 2011 Child 5 verified 1386 files, created 1373
 144 Sat Apr 30 09:24:40 2011 Child 5 will create 2 symlinks.
 145 Sat Apr 30 09:24:40 2011 Child 14 finished creating 2 symlinks.
 146 Sat Apr 30 09:24:40 2011 Child 5 finished creating 2 symlinks.
 147 Sat Apr 30 09:24:41 2011 Child 8 verified 1386 files, created 1373
 148 Sat Apr 30 09:24:41 2011 Child 8 will create 2 symlinks.
 149 Sat Apr 30 09:24:41 2011 Child 8 finished creating 2 symlinks.
 150 Sat Apr 30 09:24:41 2011 Child 9 verified 1386 files, created 1373
 151 Sat Apr 30 09:24:41 2011 Child 9 will create 2 symlinks.
 152 Sat Apr 30 09:24:41 2011 Child 9 finished creating 2 symlinks.
 153 Sat Apr 30 09:24:42 2011 Completed.
 154 Sat Apr 30 09:24:42 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 155 Sat Apr 30 09:24:42 2011 Completed.
 156 Sat Apr 30 09:24:42 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 157 Sat Apr 30 09:24:42 2011 Received.
 158 Sat Apr 30 09:24:42 2011 Performing 300 seconds pretest warmup.
 159 Sat Apr 30 09:29:42 2011 Completed.
 160 Sat Apr 30 09:29:42 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 161 Sat Apr 30 09:29:42 2011 Completed.
 162 Sat Apr 30 09:29:42 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 163 Sat Apr 30 09:29:43 2011 Received.
 164 Sat Apr 30 09:29:43 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 09:34:53 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.8%     7751     7751      0      0.19     0.04      0.00  5.2%
 175 setattr      4.0%     4.0%     1191     1191      0      0.94     1.04      0.06  4.0%
 176 lookup      24.0%    23.7%     7102     7102      0      0.20     0.03      0.00  4.9%
 177 readlink     1.0%     1.0%      302      302      0      0.19     0.03      0.02  0.2%
 178 read        18.0%    18.1%     5440     6644      0      1.38     3.54      0.05 26.6%
 179 write       10.0%    10.2%     3052     3705      0      3.78    14.42      0.13 40.8%
 180 create       1.0%     1.0%      302      302      0      1.94     1.94      0.16  2.1%
 181 remove       1.0%     1.0%      297      297      0      1.23     1.25      0.13  1.3%
 182 readdir      1.0%     1.0%      288      288      0      0.36     0.05      0.02  0.4%
 183 fsstat       1.0%     1.0%      293      293      0      0.19     0.04      0.02  0.2%
 184 access      11.0%    11.1%     3338     3338      0      0.19     0.15      0.01  2.3%
 185 commit       0.0%     0.0%        0     1341      0      0.00     0.00      0.00 10.9%
 186 readdirplus  2.0%     2.1%      629      629      0      0.56     0.05      0.02  1.2%
 187 --------------------------------------------------------------------------------------
 188
 189       --------------------------------------------------
 190       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 191       --------------------------+-----------------------
 192 SFS NFS V3 THROUGHPUT:   99.95 Ops/Sec   AVG. RESPONSE TIME:    0.94 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:  29985          TEST TIME: 300 Sec
 198 TOTAL PHYSICAL NFS V3 OPERATIONS: 33183
 199 PHYSICAL NFS V3 THROUGHPUT:  110.61 Ops/Sec
 200 FILE SET SIZE CREATED: 11537568.0 KB
 201 FILE SET SIZE ACCESSED: 3466368.0 - 3536564.0 KB  (100.00% to 102.03% of Base)
 202
 203 ------------------------------------------------------------------------
 204
 205 Sat Apr 30 09:34:53 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 09:34:53 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 224 Sat Apr 30 09:34:53 2011 Completed.
 225 Sat Apr 30 09:34:53 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 226
 227 ************************************************************************
 228 Sat Apr 30 09:34:54 2011 Received.
 229 Sat Apr 30 09:34:54 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 230 Sat Apr 30 09:34:54 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 09:34:56 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 09:34:56 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 09:35:10 2011 Completed.
 265 Sat Apr 30 09:35:10 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 266 Sat Apr 30 09:35:10 2011 Completed.
 267 Sat Apr 30 09:35:10 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 268 Sat Apr 30 09:35:12 2011 Received.
 269
 270 Sat Apr 30 09:35:12 2011 Initializing test directories.
 271 Sat Apr 30 09:35:12 2011 Child 4 will create 95 directories.
 272 Sat Apr 30 09:35:12 2011 Child 10 will create 95 directories.
 273 Sat Apr 30 09:35:12 2011 Child 15 will create 95 directories.
 274 Sat Apr 30 09:35:12 2011 Child 0 will create 95 directories.
 275 Sat Apr 30 09:35:12 2011 Child 1 will create 95 directories.
 276 Sat Apr 30 09:35:12 2011 Child 14 will create 95 directories.
 277 Sat Apr 30 09:35:12 2011 Child 2 will create 95 directories.
 278 Sat Apr 30 09:35:12 2011 Child 3 will create 95 directories.
 279 Sat Apr 30 09:35:12 2011 Child 13 will create 95 directories.
 280 Sat Apr 30 09:35:12 2011 Child 9 will create 95 directories.
 281 Sat Apr 30 09:35:12 2011 Child 8 will create 95 directories.
 282 Sat Apr 30 09:35:12 2011 Child 5 will create 95 directories.
 283 Sat Apr 30 09:35:12 2011 Child 12 will create 95 directories.
 284 Sat Apr 30 09:35:12 2011 Child 6 will create 95 directories.
 285 Sat Apr 30 09:35:12 2011 Child 11 will create 95 directories.
 286 Sat Apr 30 09:35:12 2011 Child 7 will create 95 directories.
 287 Sat Apr 30 09:35:12 2011 Child 15 finished creating 95 directories.
 288 Sat Apr 30 09:35:12 2011 Child 15 will create 2897 files.
 289 Sat Apr 30 09:35:12 2011 Child 1 finished creating 95 directories.
 290 Sat Apr 30 09:35:12 2011 Child 1 will create 2897 files.
 291 Sat Apr 30 09:35:12 2011 Child 0 finished creating 95 directories.
 292 Sat Apr 30 09:35:12 2011 Child 0 will create 2897 files.
 293 Sat Apr 30 09:35:13 2011 Child 3 finished creating 95 directories.
 294 Sat Apr 30 09:35:13 2011 Child 3 will create 2897 files.
 295 Sat Apr 30 09:35:13 2011 Child 12 finished creating 95 directories.
 296 Sat Apr 30 09:35:13 2011 Child 12 will create 2897 files.
 297 Sat Apr 30 09:35:13 2011 Child 5 finished creating 95 directories.
 298 Sat Apr 30 09:35:13 2011 Child 5 will create 2897 files.
 299 Sat Apr 30 09:35:14 2011 Child 11 finished creating 95 directories.
 300 Sat Apr 30 09:35:14 2011 Child 11 will create 2897 files.
 301 Sat Apr 30 09:35:14 2011 Child 13 finished creating 95 directories.
 302 Sat Apr 30 09:35:14 2011 Child 13 will create 2897 files.
 303 Sat Apr 30 09:35:16 2011 Child 7 finished creating 95 directories.
 304 Sat Apr 30 09:35:16 2011 Child 7 will create 2897 files.
 305 Sat Apr 30 09:35:16 2011 Child 10 finished creating 95 directories.
 306 Sat Apr 30 09:35:16 2011 Child 10 will create 2897 files.
 307 Sat Apr 30 09:35:16 2011 Child 14 finished creating 95 directories.
 308 Sat Apr 30 09:35:16 2011 Child 14 will create 2897 files.
 309 Sat Apr 30 09:35:16 2011 Child 9 finished creating 95 directories.
 310 Sat Apr 30 09:35:16 2011 Child 9 will create 2897 files.
 311 Sat Apr 30 09:35:17 2011 Child 2 finished creating 95 directories.
 312 Sat Apr 30 09:35:17 2011 Child 2 will create 2897 files.
 313 Sat Apr 30 09:35:17 2011 Child 4 finished creating 95 directories.
 314 Sat Apr 30 09:35:17 2011 Child 4 will create 2897 files.
 315 Sat Apr 30 09:35:17 2011 Child 8 finished creating 95 directories.
 316 Sat Apr 30 09:35:17 2011 Child 8 will create 2897 files.
 317 Sat Apr 30 09:35:17 2011 Child 6 finished creating 95 directories.
 318 Sat Apr 30 09:35:17 2011 Child 6 will create 2897 files.
 319 Sat Apr 30 09:40:14 2011 Child 3 verified 2019 files (661 creates).
 320 Sat Apr 30 09:40:14 2011 Child 12 verified 2023 files (665 creates).
 321 Sat Apr 30 09:40:14 2011 Child 5 verified 2028 files (670 creates).
 322 Sat Apr 30 09:40:15 2011 Child 1 verified 2125 files (767 creates).
 323 Sat Apr 30 09:40:15 2011 Child 0 verified 2141 files (783 creates).
 324 Sat Apr 30 09:40:15 2011 Child 15 verified 2042 files (684 creates).
 325 Sat Apr 30 09:40:15 2011 Child 11 verified 2055 files (697 creates).
 326 Sat Apr 30 09:40:15 2011 Child 13 verified 2055 files (697 creates).
 327 Sat Apr 30 09:40:18 2011 Child 7 verified 1920 files (562 creates).
 328 Sat Apr 30 09:40:18 2011 Child 10 verified 1988 files (630 creates).
 329 Sat Apr 30 09:40:18 2011 Child 4 verified 1988 files (630 creates).
 330 Sat Apr 30 09:40:18 2011 Child 14 verified 1999 files (641 creates).
 331 Sat Apr 30 09:40:18 2011 Child 9 verified 1929 files (571 creates).
 332 Sat Apr 30 09:40:18 2011 Child 6 verified 1930 files (572 creates).
 333 Sat Apr 30 09:40:18 2011 Child 8 verified 1989 files (631 creates).
 334 Sat Apr 30 09:40:19 2011 Child 2 verified 1932 files (574 creates).
 335 Sat Apr 30 09:45:17 2011 Child 13 verified 2611 files (1253 creates).
 336 Sat Apr 30 09:45:18 2011 Child 5 verified 2600 files (1242 creates).
 337 Sat Apr 30 09:45:19 2011 Child 7 verified 2488 files (1130 creates).
 338 Sat Apr 30 09:45:23 2011 Child 10 verified 2542 files (1184 creates).
 339 Sat Apr 30 09:45:23 2011 Child 15 verified 2560 files (1202 creates).
 340 Sat Apr 30 09:45:23 2011 Child 9 verified 2501 files (1143 creates).
 341 Sat Apr 30 09:45:23 2011 Child 12 verified 2560 files (1202 creates).
 342 Sat Apr 30 09:45:23 2011 Child 14 verified 2652 files (1294 creates).
 343 Sat Apr 30 09:45:23 2011 Child 3 verified 2560 files (1202 creates).
 344 Sat Apr 30 09:45:23 2011 Child 0 verified 2741 files (1383 creates).
 345 Sat Apr 30 09:45:23 2011 Child 8 verified 2539 files (1181 creates).
 346 Sat Apr 30 09:45:23 2011 Child 11 verified 2539 files (1181 creates).
 347 Sat Apr 30 09:45:23 2011 Child 4 verified 2539 files (1181 creates).
 348 Sat Apr 30 09:45:23 2011 Child 2 verified 2501 files (1143 creates).
 349 Sat Apr 30 09:45:23 2011 Child 6 verified 2493 files (1135 creates).
 350 Sat Apr 30 09:45:29 2011 Child 1 verified 2897 files, created 1511
 351 Sat Apr 30 09:45:29 2011 Child 1 will create 2 symlinks.
 352 Sat Apr 30 09:45:29 2011 Child 1 finished creating 2 symlinks.
 353 Sat Apr 30 09:46:38 2011 Child 0 verified 2897 files, created 1511
 354 Sat Apr 30 09:46:38 2011 Child 0 will create 2 symlinks.
 355 Sat Apr 30 09:46:38 2011 Child 0 finished creating 2 symlinks.
 356 Sat Apr 30 09:47:09 2011 Child 13 verified 2897 files, created 1511
 357 Sat Apr 30 09:47:09 2011 Child 13 will create 2 symlinks.
 358 Sat Apr 30 09:47:09 2011 Child 5 verified 2897 files, created 1511
 359 Sat Apr 30 09:47:09 2011 Child 5 will create 2 symlinks.
 360 Sat Apr 30 09:47:09 2011 Child 13 finished creating 2 symlinks.
 361 Sat Apr 30 09:47:09 2011 Child 5 finished creating 2 symlinks.
 362 Sat Apr 30 09:47:12 2011 Child 3 verified 2897 files, created 1511
 363 Sat Apr 30 09:47:12 2011 Child 3 will create 2 symlinks.
 364 Sat Apr 30 09:47:12 2011 Child 14 verified 2897 files, created 1511
 365 Sat Apr 30 09:47:12 2011 Child 14 will create 2 symlinks.
 366 Sat Apr 30 09:47:13 2011 Child 12 verified 2897 files, created 1511
 367 Sat Apr 30 09:47:13 2011 Child 12 will create 2 symlinks.
 368 Sat Apr 30 09:47:14 2011 Child 12 finished creating 2 symlinks.
 369 Sat Apr 30 09:47:14 2011 Child 3 finished creating 2 symlinks.
 370 Sat Apr 30 09:47:14 2011 Child 14 finished creating 2 symlinks.
 371 Sat Apr 30 09:47:19 2011 Child 4 verified 2897 files, created 1511
 372 Sat Apr 30 09:47:19 2011 Child 4 will create 2 symlinks.
 373 Sat Apr 30 09:47:19 2011 Child 4 finished creating 2 symlinks.
 374 Sat Apr 30 09:47:21 2011 Child 15 verified 2897 files, created 1511
 375 Sat Apr 30 09:47:21 2011 Child 15 will create 2 symlinks.
 376 Sat Apr 30 09:47:21 2011 Child 15 finished creating 2 symlinks.
 377 Sat Apr 30 09:47:22 2011 Child 7 verified 2897 files, created 1511
 378 Sat Apr 30 09:47:22 2011 Child 7 will create 2 symlinks.
 379 Sat Apr 30 09:47:22 2011 Child 7 finished creating 2 symlinks.
 380 Sat Apr 30 09:47:23 2011 Child 11 verified 2897 files, created 1511
 381 Sat Apr 30 09:47:23 2011 Child 11 will create 2 symlinks.
 382 Sat Apr 30 09:47:23 2011 Child 11 finished creating 2 symlinks.
 383 Sat Apr 30 09:47:24 2011 Child 10 verified 2897 files, created 1511
 384 Sat Apr 30 09:47:24 2011 Child 10 will create 2 symlinks.
 385 Sat Apr 30 09:47:26 2011 Child 10 finished creating 2 symlinks.
 386 Sat Apr 30 09:47:26 2011 Child 8 verified 2897 files, created 1511
 387 Sat Apr 30 09:47:26 2011 Child 8 will create 2 symlinks.
 388 Sat Apr 30 09:47:26 2011 Child 8 finished creating 2 symlinks.
 389 Sat Apr 30 09:47:29 2011 Child 9 verified 2897 files, created 1511
 390 Sat Apr 30 09:47:29 2011 Child 9 will create 2 symlinks.
 391 Sat Apr 30 09:47:29 2011 Child 9 finished creating 2 symlinks.
 392 Sat Apr 30 09:47:29 2011 Child 2 verified 2897 files, created 1511
 393 Sat Apr 30 09:47:29 2011 Child 2 will create 2 symlinks.
 394 Sat Apr 30 09:47:30 2011 Child 2 finished creating 2 symlinks.
 395 Sat Apr 30 09:47:30 2011 Child 6 verified 2897 files, created 1511
 396 Sat Apr 30 09:47:30 2011 Child 6 will create 2 symlinks.
 397 Sat Apr 30 09:47:30 2011 Child 6 finished creating 2 symlinks.
 398 Sat Apr 30 09:47:31 2011 Completed.
 399 Sat Apr 30 09:47:31 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 400 Sat Apr 30 09:47:31 2011 Completed.
 401 Sat Apr 30 09:47:31 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 402 Sat Apr 30 09:47:32 2011 Received.
 403 Sat Apr 30 09:47:32 2011 Performing 300 seconds pretest warmup.
 404 Sat Apr 30 09:52:32 2011 Completed.
 405 Sat Apr 30 09:52:32 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 406 Sat Apr 30 09:52:32 2011 Completed.
 407 Sat Apr 30 09:52:32 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 408 Sat Apr 30 09:52:33 2011 Received.
 409 Sat Apr 30 09:52:33 2011 Starting 300 seconds test run.
 410 SFS Single Client (c09-44) Results, Sat Apr 30 09:57:43 2011
 411 --------------------------------------------------------------------------------------
 412 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 413 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 414 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 415            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 416 --------------------------------------------------------------------------------------
 417 getattr     26.0%    26.0%    15628    15628      0      0.19     0.05      0.00  4.6%
 418 setattr      4.0%     4.1%     2443     2443      0      1.33     2.31      0.06  4.9%
 419 lookup      24.0%    23.9%    14314    14314      0      0.20     0.06      0.00  4.3%
 420 readlink     1.0%     1.0%      620      620      0      0.20     0.14      0.03  0.2%
 421 read        18.0%    18.1%    10843    13426      0      2.29     5.75      0.05 37.4%
 422 write       10.0%     9.9%     5964     7273      0      3.22     9.21      0.08 28.9%
 423 create       1.0%     1.0%      600      600      0      3.05     2.50      0.13  2.8%
 424 remove       1.0%     1.0%      622      622      0      2.17     3.21      0.14  2.0%
 425 readdir      1.0%     1.0%      604      604      0      0.40     0.10      0.03  0.4%
 426 fsstat       1.0%     1.0%      616      616      0      0.20     0.05      0.02  0.2%
 427 access      11.0%    10.9%     6542     6542      0      0.20     0.06      0.01  1.9%
 428 commit       0.0%     0.0%        0     2599      0      0.00     0.00      0.00 11.3%
 429 readdirplus  2.0%     2.0%     1207     1207      0      0.64     0.07      0.01  1.2%
 430 --------------------------------------------------------------------------------------
 431
 432       --------------------------------------------------
 433       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 434       --------------------------+-----------------------
 435 SFS NFS V3 THROUGHPUT:  200.67 Ops/Sec   AVG. RESPONSE TIME:    1.10 Msec/Op
 436 TCP PROTOCOL
 437 FAST CALLS: 0
 438 NFS V3 MIXFILE: [ SFS Default ]
 439 CLIENT REQUESTED LOAD: 200 Ops/Sec
 440 TOTAL LOGICAL NFS V3 OPERATIONS:  60003          TEST TIME: 299 Sec
 441 TOTAL PHYSICAL NFS V3 OPERATIONS: 66494
 442 PHYSICAL NFS V3 THROUGHPUT:  222.38 Ops/Sec
 443 FILE SET SIZE CREATED: 24128640.0 KB
 444 FILE SET SIZE ACCESSED: 7247088.0 - 7391739.0 KB  (100.00% to 102.00% of Base)
 445
 446 ------------------------------------------------------------------------
 447
 448 Sat Apr 30 09:57:43 2011 Completed.
 449
 450 Client Test Parameters:
 451         Number of processes = 16
 452         Requested Load (NFS V3 operations/second) = 200
 453         Maximum number of outstanding biod writes = 8
 454         Maximum number of outstanding biod reads = 8
 455         Warm-up time (seconds) = 300
 456         Run time (seconds) = 300
 457         File Set = 45440 Files created for I/O operations
 458                    13632 Files accessed for I/O operations
 459                     912 Files for non-I/O operations
 460                      32 Symlinks
 461                    1504 Directories
 462                         Additional non-I/O files created as necessary
 463
 464
 465 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 466 Sat Apr 30 09:57:43 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 467 Sat Apr 30 09:57:43 2011 Completed.
 468 Sat Apr 30 09:57:43 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 469
 470 ************************************************************************
 471 Sat Apr 30 09:57:44 2011 Received.
 472 Sat Apr 30 09:57:44 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 473 Sat Apr 30 09:57:44 2011 Completed.
 474 Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 300  -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 16 -w 300 -U 500 -g 500 -M c09-44.sysnet.ucsd.edu  vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export vrable2.sysnet.ucsd.edu:/export
 475 NFS V3 Protocol Version
 476
 477 ************************************************************************
 478 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 479 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 480
 481 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 482 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 483
 484 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 09:57:46 2011
 485         Client hostname = c09-44
 486         Prime Client hostname = c09-44.sysnet.ucsd.edu
 487
 488 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 489 NFS V3 Protocol
 490 Sat Apr 30 09:57:46 2011 Mounting 16 remote test directories.
 491 Child[000]: NFS negotiated block size 32768
 492 Child[001]: NFS negotiated block size 32768
 493 Child[002]: NFS negotiated block size 32768
 494 Child[003]: NFS negotiated block size 32768
 495 Child[004]: NFS negotiated block size 32768
 496 Child[005]: NFS negotiated block size 32768
 497 Child[006]: NFS negotiated block size 32768
 498 Child[007]: NFS negotiated block size 32768
 499 Child[008]: NFS negotiated block size 32768
 500 Child[009]: NFS negotiated block size 32768
 501 Child[010]: NFS negotiated block size 32768
 502 Child[011]: NFS negotiated block size 32768
 503 Child[012]: NFS negotiated block size 32768
 504 Child[013]: NFS negotiated block size 32768
 505 Child[014]: NFS negotiated block size 32768
 506 Child[015]: NFS negotiated block size 32768
 507 Sat Apr 30 09:58:00 2011 Completed.
 508 Sat Apr 30 09:58:00 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 509 Sat Apr 30 09:58:00 2011 Completed.
 510 Sat Apr 30 09:58:00 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 511 Sat Apr 30 09:58:02 2011 Received.
 512
 513 Sat Apr 30 09:58:02 2011 Initializing test directories.
 514 Sat Apr 30 09:58:02 2011 Child 13 will create 145 directories.
 515 Sat Apr 30 09:58:02 2011 Child 15 will create 145 directories.
 516 Sat Apr 30 09:58:02 2011 Child 5 will create 145 directories.
 517 Sat Apr 30 09:58:02 2011 Child 0 will create 145 directories.
 518 Sat Apr 30 09:58:02 2011 Child 10 will create 145 directories.
 519 Sat Apr 30 09:58:02 2011 Child 14 will create 145 directories.
 520 Sat Apr 30 09:58:02 2011 Child 12 will create 145 directories.
 521 Sat Apr 30 09:58:02 2011 Child 9 will create 145 directories.
 522 Sat Apr 30 09:58:02 2011 Child 6 will create 145 directories.
 523 Sat Apr 30 09:58:02 2011 Child 7 will create 145 directories.
 524 Sat Apr 30 09:58:02 2011 Child 4 will create 145 directories.
 525 Sat Apr 30 09:58:02 2011 Child 8 will create 145 directories.
 526 Sat Apr 30 09:58:02 2011 Child 3 will create 145 directories.
 527 Sat Apr 30 09:58:02 2011 Child 1 will create 145 directories.
 528 Sat Apr 30 09:58:02 2011 Child 2 will create 145 directories.
 529 Sat Apr 30 09:58:02 2011 Child 11 will create 145 directories.
 530 Sat Apr 30 09:58:02 2011 Child 4 finished creating 145 directories.
 531 Sat Apr 30 09:58:02 2011 Child 4 will create 4408 files.
 532 Sat Apr 30 09:58:02 2011 Child 5 finished creating 145 directories.
 533 Sat Apr 30 09:58:02 2011 Child 5 will create 4408 files.
 534 Sat Apr 30 09:58:03 2011 Child 1 finished creating 145 directories.
 535 Sat Apr 30 09:58:03 2011 Child 1 will create 4408 files.
 536 Sat Apr 30 09:58:03 2011 Child 3 finished creating 145 directories.
 537 Sat Apr 30 09:58:03 2011 Child 3 will create 4408 files.
 538 Sat Apr 30 09:58:03 2011 Child 10 finished creating 145 directories.
 539 Sat Apr 30 09:58:03 2011 Child 10 will create 4408 files.
 540 Sat Apr 30 09:58:05 2011 Child 15 finished creating 145 directories.
 541 Sat Apr 30 09:58:05 2011 Child 15 will create 4408 files.
 542 Sat Apr 30 09:58:05 2011 Child 13 finished creating 145 directories.
 543 Sat Apr 30 09:58:05 2011 Child 13 will create 4408 files.
 544 Sat Apr 30 09:58:05 2011 Child 6 finished creating 145 directories.
 545 Sat Apr 30 09:58:05 2011 Child 6 will create 4408 files.
 546 Sat Apr 30 09:58:07 2011 Child 9 finished creating 145 directories.
 547 Sat Apr 30 09:58:07 2011 Child 9 will create 4408 files.
 548 Sat Apr 30 09:58:08 2011 Child 7 finished creating 145 directories.
 549 Sat Apr 30 09:58:08 2011 Child 7 will create 4408 files.
 550 Sat Apr 30 09:58:11 2011 Child 12 finished creating 145 directories.
 551 Sat Apr 30 09:58:11 2011 Child 12 will create 4408 files.
 552 Sat Apr 30 09:58:11 2011 Child 8 finished creating 145 directories.
 553 Sat Apr 30 09:58:11 2011 Child 8 will create 4408 files.
 554 Sat Apr 30 09:58:11 2011 Child 0 finished creating 145 directories.
 555 Sat Apr 30 09:58:11 2011 Child 0 will create 4408 files.
 556 Sat Apr 30 09:58:11 2011 Child 14 finished creating 145 directories.
 557 Sat Apr 30 09:58:11 2011 Child 14 will create 4408 files.
 558 Sat Apr 30 09:58:13 2011 Child 2 finished creating 145 directories.
 559 Sat Apr 30 09:58:13 2011 Child 2 will create 4408 files.
 560 Sat Apr 30 09:58:14 2011 Child 11 finished creating 145 directories.
 561 Sat Apr 30 09:58:14 2011 Child 11 will create 4408 files.
 562 Sat Apr 30 10:03:03 2011 Child 5 verified 3449 files (609 creates).
 563 Sat Apr 30 10:03:07 2011 Child 10 verified 3452 files (612 creates).
 564 Sat Apr 30 10:03:07 2011 Child 15 verified 3428 files (588 creates).
 565 Sat Apr 30 10:03:15 2011 Child 9 verified 3192 files (352 creates).
 566 Sat Apr 30 10:03:15 2011 Child 6 verified 3075 files (235 creates).
 567 Sat Apr 30 10:03:15 2011 Child 1 verified 3544 files (704 creates).
 568 Sat Apr 30 10:03:15 2011 Child 3 verified 3456 files (616 creates).
 569 Sat Apr 30 10:03:15 2011 Child 2 verified 3052 files (212 creates).
 570 Sat Apr 30 10:03:15 2011 Child 13 verified 3192 files (352 creates).
 571 Sat Apr 30 10:03:16 2011 Child 11 verified 3073 files (233 creates).
 572 Sat Apr 30 10:03:17 2011 Child 14 verified 3074 files (234 creates).
 573 Sat Apr 30 10:03:23 2011 Child 8 verified 3192 files (352 creates).
 574 Sat Apr 30 10:03:23 2011 Child 4 verified 3505 files (665 creates).
 575 Sat Apr 30 10:03:23 2011 Child 7 verified 3192 files (352 creates).
 576 Sat Apr 30 10:03:23 2011 Child 0 verified 3192 files (352 creates).
 577 Sat Apr 30 10:03:23 2011 Child 12 verified 3146 files (306 creates).
 578 Sat Apr 30 10:08:10 2011 Child 1 verified 4408 files, created 1525
 579 Sat Apr 30 10:08:10 2011 Child 1 will create 2 symlinks.
 580 Sat Apr 30 10:08:10 2011 Child 1 finished creating 2 symlinks.
 581 Sat Apr 30 10:08:20 2011 Child 2 verified 3778 files (938 creates).
 582 Sat Apr 30 10:08:20 2011 Child 10 verified 4094 files (1254 creates).
 583 Sat Apr 30 10:08:20 2011 Child 5 verified 4094 files (1254 creates).
 584 Sat Apr 30 10:08:21 2011 Child 3 verified 4171 files (1331 creates).
 585 Sat Apr 30 10:08:21 2011 Child 14 verified 3743 files (903 creates).
 586 Sat Apr 30 10:08:22 2011 Child 4 verified 4255 files (1415 creates).
 587 Sat Apr 30 10:08:23 2011 Child 8 verified 3826 files (986 creates).
 588 Sat Apr 30 10:08:24 2011 Child 11 verified 3805 files (965 creates).
 589 Sat Apr 30 10:08:25 2011 Child 9 verified 3873 files (1033 creates).
 590 Sat Apr 30 10:08:25 2011 Child 15 verified 4094 files (1254 creates).
 591 Sat Apr 30 10:08:25 2011 Child 0 verified 3817 files (977 creates).
 592 Sat Apr 30 10:08:25 2011 Child 7 verified 3879 files (1039 creates).
 593 Sat Apr 30 10:08:25 2011 Child 12 verified 3873 files (1033 creates).
 594 Sat Apr 30 10:08:25 2011 Child 13 verified 3873 files (1033 creates).
 595 Sat Apr 30 10:08:25 2011 Child 6 verified 3817 files (977 creates).
 596 Sat Apr 30 10:08:40 2011 Child 4 verified 4408 files, created 1525
 597 Sat Apr 30 10:08:40 2011 Child 4 will create 2 symlinks.
 598 Sat Apr 30 10:08:40 2011 Child 4 finished creating 2 symlinks.
 599 Sat Apr 30 10:08:59 2011 Child 3 verified 4408 files, created 1525
 600 Sat Apr 30 10:08:59 2011 Child 3 will create 2 symlinks.
 601 Sat Apr 30 10:09:00 2011 Child 3 finished creating 2 symlinks.
 602 Sat Apr 30 10:09:13 2011 Child 5 verified 4408 files, created 1525
 603 Sat Apr 30 10:09:13 2011 Child 5 will create 2 symlinks.
 604 Sat Apr 30 10:09:13 2011 Child 5 finished creating 2 symlinks.
 605 Sat Apr 30 10:09:13 2011 Child 15 verified 4408 files, created 1525
 606 Sat Apr 30 10:09:13 2011 Child 15 will create 2 symlinks.
 607 Sat Apr 30 10:09:13 2011 Child 15 finished creating 2 symlinks.
 608 Sat Apr 30 10:09:13 2011 Child 10 verified 4408 files, created 1525
 609 Sat Apr 30 10:09:13 2011 Child 10 will create 2 symlinks.
 610 Sat Apr 30 10:09:14 2011 Child 10 finished creating 2 symlinks.
 611 Sat Apr 30 10:09:44 2011 Child 7 verified 4408 files, created 1525
 612 Sat Apr 30 10:09:44 2011 Child 7 will create 2 symlinks.
 613 Sat Apr 30 10:09:45 2011 Child 7 finished creating 2 symlinks.
 614 Sat Apr 30 10:09:46 2011 Child 8 verified 4408 files, created 1525
 615 Sat Apr 30 10:09:46 2011 Child 8 will create 2 symlinks.
 616 Sat Apr 30 10:09:46 2011 Child 8 finished creating 2 symlinks.
 617 Sat Apr 30 10:09:46 2011 Child 13 verified 4408 files, created 1525
 618 Sat Apr 30 10:09:46 2011 Child 13 will create 2 symlinks.
 619 Sat Apr 30 10:09:46 2011 Child 13 finished creating 2 symlinks.
 620 Sat Apr 30 10:09:46 2011 Child 9 verified 4408 files, created 1525
 621 Sat Apr 30 10:09:46 2011 Child 9 will create 2 symlinks.
 622 Sat Apr 30 10:09:46 2011 Child 9 finished creating 2 symlinks.
 623 Sat Apr 30 10:09:46 2011 Child 12 verified 4408 files, created 1525
 624 Sat Apr 30 10:09:46 2011 Child 12 will create 2 symlinks.
 625 Sat Apr 30 10:09:46 2011 Child 12 finished creating 2 symlinks.
 626 Sat Apr 30 10:09:47 2011 Child 0 verified 4408 files, created 1525
 627 Sat Apr 30 10:09:47 2011 Child 0 will create 2 symlinks.
 628 Sat Apr 30 10:09:47 2011 Child 0 finished creating 2 symlinks.
 629 Sat Apr 30 10:09:49 2011 Child 11 verified 4408 files, created 1525
 630 Sat Apr 30 10:09:49 2011 Child 11 will create 2 symlinks.
 631 Sat Apr 30 10:09:49 2011 Child 14 verified 4408 files, created 1525
 632 Sat Apr 30 10:09:49 2011 Child 14 will create 2 symlinks.
 633 Sat Apr 30 10:09:49 2011 Child 2 verified 4408 files, created 1525
 634 Sat Apr 30 10:09:49 2011 Child 2 will create 2 symlinks.
 635 Sat Apr 30 10:09:49 2011 Child 11 finished creating 2 symlinks.
 636 Sat Apr 30 10:09:49 2011 Child 6 verified 4408 files, created 1525
 637 Sat Apr 30 10:09:49 2011 Child 6 will create 2 symlinks.
 638 Sat Apr 30 10:09:49 2011 Child 14 finished creating 2 symlinks.
 639 Sat Apr 30 10:09:49 2011 Child 2 finished creating 2 symlinks.
 640 Sat Apr 30 10:09:49 2011 Child 6 finished creating 2 symlinks.
 641 Sat Apr 30 10:09:49 2011 Completed.
 642 Sat Apr 30 10:09:49 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 643 Sat Apr 30 10:09:49 2011 Completed.
 644 Sat Apr 30 10:09:49 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 645 Sat Apr 30 10:09:50 2011 Received.
 646 Sat Apr 30 10:09:50 2011 Performing 300 seconds pretest warmup.
 647 Sat Apr 30 10:14:50 2011 Completed.
 648 Sat Apr 30 10:14:50 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 649 Sat Apr 30 10:14:50 2011 Completed.
 650 Sat Apr 30 10:14:50 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 651 Sat Apr 30 10:14:51 2011 Received.
 652 Sat Apr 30 10:14:51 2011 Starting 300 seconds test run.
 653 SFS Single Client (c09-44) Results, Sat Apr 30 10:20:01 2011
 654 --------------------------------------------------------------------------------------
 655 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 656 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 657 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 658            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 659 --------------------------------------------------------------------------------------
 660 getattr     26.0%    26.0%    23381    23381      0      0.20     0.13      0.00  2.2%
 661 setattr      4.0%     4.0%     3601     3601      0      2.50     5.28      0.08  4.4%
 662 lookup      24.0%    24.0%    21648    21648      0      0.21     0.25      0.01  2.2%
 663 readlink     1.0%     1.0%      919      919      0      0.19     0.05      0.01  0.1%
 664 read        18.0%    17.9%    16123    19760      0      6.95   157.14      0.19 54.4%
 665 write       10.0%    10.1%     9122    11153      0      5.23    24.44      0.10 23.2%
 666 create       1.0%     1.0%      937      937      0      5.09     6.85      0.17  2.3%
 667 remove       1.0%     1.0%      871      871      0      4.00     5.98      0.16  1.7%
 668 readdir      1.0%     1.0%      860      860      0      0.41     0.21      0.03  0.2%
 669 fsstat       1.0%     1.0%      927      927      0      0.20     0.09      0.02  0.1%
 670 access      11.0%    11.0%     9875     9875      0      0.20     0.07      0.01  0.9%
 671 commit       0.0%     0.0%        0     4025      0      0.00     0.00      0.00  7.7%
 672 readdirplus  2.0%     2.0%     1787     1787      0      0.70     0.34      0.03  0.6%
 673 --------------------------------------------------------------------------------------
 674
 675       --------------------------------------------------
 676       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 677       --------------------------+-----------------------
 678 SFS NFS V3 THROUGHPUT:  300.17 Ops/Sec   AVG. RESPONSE TIME:    2.28 Msec/Op
 679 TCP PROTOCOL
 680 FAST CALLS: 0
 681 NFS V3 MIXFILE: [ SFS Default ]
 682 CLIENT REQUESTED LOAD: 300 Ops/Sec
 683 TOTAL LOGICAL NFS V3 OPERATIONS:  90051          TEST TIME: 300 Sec
 684 TOTAL PHYSICAL NFS V3 OPERATIONS: 99744
 685 PHYSICAL NFS V3 THROUGHPUT:  332.48 Ops/Sec
 686 FILE SET SIZE CREATED: 36711216.0 KB
 687 FILE SET SIZE ACCESSED: 11019312.0 - 11236490.0 KB  (100.00% to 101.97% of Base)
 688
 689 ------------------------------------------------------------------------
 690
 691 Sat Apr 30 10:20:01 2011 Completed.
 692
 693 Client Test Parameters:
 694         Number of processes = 16
 695         Requested Load (NFS V3 operations/second) = 300
 696         Maximum number of outstanding biod writes = 8
 697         Maximum number of outstanding biod reads = 8
 698         Warm-up time (seconds) = 300
 699         Run time (seconds) = 300
 700         File Set = 69136 Files created for I/O operations
 701                    20736 Files accessed for I/O operations
 702                    1392 Files for non-I/O operations
 703                      32 Symlinks
 704                    2304 Directories
 705                         Additional non-I/O files created as necessary
 706
 707
 708 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 709 Sat Apr 30 10:20:01 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 710 Sat Apr 30 10:20:01 2011 Completed.
 711 Sat Apr 30 10:20:01 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 712
 713 ************************************************************************
 714 Sat Apr 30 10:20:02 2011 Received.
 715 Sat Apr 30 10:20:02 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 716 Sat Apr 30 10:20:02 2011 Completed.
 717 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
 718 NFS V3 Protocol Version
 719
 720 ************************************************************************
 721 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 722 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 723
 724 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 725 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 726
 727 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 10:20:04 2011
 728         Client hostname = c09-44
 729         Prime Client hostname = c09-44.sysnet.ucsd.edu
 730
 731 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 732 NFS V3 Protocol
 733 Sat Apr 30 10:20:04 2011 Mounting 16 remote test directories.
 734 Child[000]: NFS negotiated block size 32768
 735 Child[001]: NFS negotiated block size 32768
 736 Child[002]: NFS negotiated block size 32768
 737 Child[003]: NFS negotiated block size 32768
 738 Child[004]: NFS negotiated block size 32768
 739 Child[005]: NFS negotiated block size 32768
 740 Child[006]: NFS negotiated block size 32768
 741 Child[007]: NFS negotiated block size 32768
 742 Child[008]: NFS negotiated block size 32768
 743 Child[009]: NFS negotiated block size 32768
 744 Child[010]: NFS negotiated block size 32768
 745 Child[011]: NFS negotiated block size 32768
 746 Child[012]: NFS negotiated block size 32768
 747 Child[013]: NFS negotiated block size 32768
 748 Child[014]: NFS negotiated block size 32768
 749 Child[015]: NFS negotiated block size 32768
 750 Sat Apr 30 10:20:18 2011 Completed.
 751 Sat Apr 30 10:20:18 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 752 Sat Apr 30 10:20:18 2011 Completed.
 753 Sat Apr 30 10:20:18 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 754 Sat Apr 30 10:20:20 2011 Received.
 755
 756 Sat Apr 30 10:20:20 2011 Initializing test directories.
 757 Sat Apr 30 10:20:20 2011 Child 12 will create 190 directories.
 758 Sat Apr 30 10:20:20 2011 Child 6 will create 190 directories.
 759 Sat Apr 30 10:20:20 2011 Child 14 will create 190 directories.
 760 Sat Apr 30 10:20:20 2011 Child 1 will create 190 directories.
 761 Sat Apr 30 10:20:20 2011 Child 10 will create 190 directories.
 762 Sat Apr 30 10:20:20 2011 Child 4 will create 190 directories.
 763 Sat Apr 30 10:20:20 2011 Child 13 will create 190 directories.
 764 Sat Apr 30 10:20:20 2011 Child 8 will create 190 directories.
 765 Sat Apr 30 10:20:20 2011 Child 2 will create 190 directories.
 766 Sat Apr 30 10:20:20 2011 Child 3 will create 190 directories.
 767 Sat Apr 30 10:20:20 2011 Child 15 will create 190 directories.
 768 Sat Apr 30 10:20:20 2011 Child 9 will create 190 directories.
 769 Sat Apr 30 10:20:20 2011 Child 7 will create 190 directories.
 770 Sat Apr 30 10:20:20 2011 Child 5 will create 190 directories.
 771 Sat Apr 30 10:20:20 2011 Child 11 will create 190 directories.
 772 Sat Apr 30 10:20:20 2011 Child 0 will create 190 directories.
 773 Sat Apr 30 10:20:21 2011 Child 2 finished creating 190 directories.
 774 Sat Apr 30 10:20:21 2011 Child 2 will create 5793 files.
 775 Sat Apr 30 10:20:22 2011 Child 15 finished creating 190 directories.
 776 Sat Apr 30 10:20:22 2011 Child 15 will create 5793 files.
 777 Sat Apr 30 10:20:22 2011 Child 6 finished creating 190 directories.
 778 Sat Apr 30 10:20:22 2011 Child 6 will create 5793 files.
 779 Sat Apr 30 10:20:24 2011 Child 10 finished creating 190 directories.
 780 Sat Apr 30 10:20:24 2011 Child 10 will create 5793 files.
 781 Sat Apr 30 10:20:24 2011 Child 7 finished creating 190 directories.
 782 Sat Apr 30 10:20:24 2011 Child 7 will create 5793 files.
 783 Sat Apr 30 10:20:25 2011 Child 0 finished creating 190 directories.
 784 Sat Apr 30 10:20:25 2011 Child 0 will create 5793 files.
 785 Sat Apr 30 10:20:25 2011 Child 8 finished creating 190 directories.
 786 Sat Apr 30 10:20:25 2011 Child 8 will create 5793 files.
 787 Sat Apr 30 10:20:25 2011 Child 13 finished creating 190 directories.
 788 Sat Apr 30 10:20:25 2011 Child 13 will create 5793 files.
 789 Sat Apr 30 10:20:33 2011 Child 4 finished creating 190 directories.
 790 Sat Apr 30 10:20:33 2011 Child 4 will create 5793 files.
 791 Sat Apr 30 10:20:33 2011 Child 5 finished creating 190 directories.
 792 Sat Apr 30 10:20:33 2011 Child 5 will create 5793 files.
 793 Sat Apr 30 10:20:33 2011 Child 12 finished creating 190 directories.
 794 Sat Apr 30 10:20:33 2011 Child 12 will create 5793 files.
 795 Sat Apr 30 10:20:33 2011 Child 14 finished creating 190 directories.
 796 Sat Apr 30 10:20:33 2011 Child 14 will create 5793 files.
 797 Sat Apr 30 10:20:34 2011 Child 11 finished creating 190 directories.
 798 Sat Apr 30 10:20:34 2011 Child 11 will create 5793 files.
 799 Sat Apr 30 10:20:34 2011 Child 9 finished creating 190 directories.
 800 Sat Apr 30 10:20:34 2011 Child 9 will create 5793 files.
 801 Sat Apr 30 10:20:35 2011 Child 3 finished creating 190 directories.
 802 Sat Apr 30 10:20:35 2011 Child 3 will create 5793 files.
 803 Sat Apr 30 10:20:35 2011 Child 1 finished creating 190 directories.
 804 Sat Apr 30 10:20:35 2011 Child 1 will create 5793 files.
 805 Sat Apr 30 10:25:23 2011 Child 2 verified 5272 files (951 creates).
 806 Sat Apr 30 10:25:23 2011 Child 15 verified 5206 files (885 creates).
 807 Sat Apr 30 10:25:24 2011 Child 6 verified 5252 files (931 creates).
 808 Sat Apr 30 10:25:34 2011 Child 0 verified 4991 files (670 creates).
 809 Sat Apr 30 10:25:34 2011 Child 13 verified 5011 files (690 creates).
 810 Sat Apr 30 10:25:34 2011 Child 14 verified 4886 files (565 creates).
 811 Sat Apr 30 10:25:34 2011 Child 8 verified 4991 files (670 creates).
 812 Sat Apr 30 10:25:34 2011 Child 12 verified 4984 files (663 creates).
 813 Sat Apr 30 10:25:34 2011 Child 5 verified 4897 files (576 creates).
 814 Sat Apr 30 10:25:40 2011 Child 3 verified 4896 files (575 creates).
 815 Sat Apr 30 10:25:40 2011 Child 1 verified 4896 files (575 creates).
 816 Sat Apr 30 10:25:40 2011 Child 11 verified 4886 files (565 creates).
 817 Sat Apr 30 10:25:40 2011 Child 9 verified 4886 files (565 creates).
 818 Sat Apr 30 10:25:40 2011 Child 7 verified 4991 files (670 creates).
 819 Sat Apr 30 10:25:40 2011 Child 4 verified 4894 files (573 creates).
 820 Sat Apr 30 10:25:40 2011 Child 10 verified 5191 files (870 creates).
 821 Sat Apr 30 10:27:29 2011 Child 10 verified 5793 files, created 1416
 822 Sat Apr 30 10:27:29 2011 Child 10 will create 2 symlinks.
 823 Sat Apr 30 10:27:30 2011 Child 10 finished creating 2 symlinks.
 824 Sat Apr 30 10:27:30 2011 Child 2 verified 5793 files, created 1416
 825 Sat Apr 30 10:27:30 2011 Child 2 will create 2 symlinks.
 826 Sat Apr 30 10:27:30 2011 Child 2 finished creating 2 symlinks.
 827 Sat Apr 30 10:27:31 2011 Child 6 verified 5793 files, created 1416
 828 Sat Apr 30 10:27:31 2011 Child 6 will create 2 symlinks.
 829 Sat Apr 30 10:27:31 2011 Child 15 verified 5793 files, created 1416
 830 Sat Apr 30 10:27:31 2011 Child 15 will create 2 symlinks.
 831 Sat Apr 30 10:27:32 2011 Child 6 finished creating 2 symlinks.
 832 Sat Apr 30 10:27:32 2011 Child 15 finished creating 2 symlinks.
 833 Sat Apr 30 10:28:10 2011 Child 13 verified 5793 files, created 1416
 834 Sat Apr 30 10:28:10 2011 Child 13 will create 2 symlinks.
 835 Sat Apr 30 10:28:10 2011 Child 13 finished creating 2 symlinks.
 836 Sat Apr 30 10:28:10 2011 Child 12 verified 5793 files, created 1416
 837 Sat Apr 30 10:28:10 2011 Child 12 will create 2 symlinks.
 838 Sat Apr 30 10:28:10 2011 Child 12 finished creating 2 symlinks.
 839 Sat Apr 30 10:28:19 2011 Child 7 verified 5793 files, created 1416
 840 Sat Apr 30 10:28:19 2011 Child 7 will create 2 symlinks.
 841 Sat Apr 30 10:28:19 2011 Child 7 finished creating 2 symlinks.
 842 Sat Apr 30 10:28:20 2011 Child 0 verified 5793 files, created 1416
 843 Sat Apr 30 10:28:20 2011 Child 0 will create 2 symlinks.
 844 Sat Apr 30 10:28:20 2011 Child 0 finished creating 2 symlinks.
 845 Sat Apr 30 10:28:20 2011 Child 8 verified 5793 files, created 1416
 846 Sat Apr 30 10:28:20 2011 Child 8 will create 2 symlinks.
 847 Sat Apr 30 10:28:20 2011 Child 8 finished creating 2 symlinks.
 848 Sat Apr 30 10:28:21 2011 Child 1 verified 5793 files, created 1416
 849 Sat Apr 30 10:28:21 2011 Child 5 verified 5793 files, created 1416
 850 Sat Apr 30 10:28:21 2011 Child 1 will create 2 symlinks.
 851 Sat Apr 30 10:28:21 2011 Child 5 will create 2 symlinks.
 852 Sat Apr 30 10:28:21 2011 Child 1 finished creating 2 symlinks.
 853 Sat Apr 30 10:28:21 2011 Child 5 finished creating 2 symlinks.
 854 Sat Apr 30 10:28:21 2011 Child 3 verified 5793 files, created 1416
 855 Sat Apr 30 10:28:21 2011 Child 3 will create 2 symlinks.
 856 Sat Apr 30 10:28:21 2011 Child 3 finished creating 2 symlinks.
 857 Sat Apr 30 10:28:27 2011 Child 14 verified 5793 files, created 1416
 858 Sat Apr 30 10:28:27 2011 Child 14 will create 2 symlinks.
 859 Sat Apr 30 10:28:27 2011 Child 11 verified 5793 files, created 1416
 860 Sat Apr 30 10:28:27 2011 Child 11 will create 2 symlinks.
 861 Sat Apr 30 10:28:27 2011 Child 4 verified 5793 files, created 1416
 862 Sat Apr 30 10:28:27 2011 Child 4 will create 2 symlinks.
 863 Sat Apr 30 10:28:27 2011 Child 14 finished creating 2 symlinks.
 864 Sat Apr 30 10:28:27 2011 Child 4 finished creating 2 symlinks.
 865 Sat Apr 30 10:28:27 2011 Child 11 finished creating 2 symlinks.
 866 Sat Apr 30 10:28:27 2011 Child 9 verified 5793 files, created 1416
 867 Sat Apr 30 10:28:27 2011 Child 9 will create 2 symlinks.
 868 Sat Apr 30 10:28:27 2011 Child 9 finished creating 2 symlinks.
 869 Sat Apr 30 10:28:27 2011 Completed.
 870 Sat Apr 30 10:28:27 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
 871 Sat Apr 30 10:28:27 2011 Completed.
 872 Sat Apr 30 10:28:27 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
 873 Sat Apr 30 10:28:28 2011 Received.
 874 Sat Apr 30 10:28:28 2011 Performing 300 seconds pretest warmup.
 875 Sat Apr 30 10:33:28 2011 Completed.
 876 Sat Apr 30 10:33:28 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
 877 Sat Apr 30 10:33:28 2011 Completed.
 878 Sat Apr 30 10:33:28 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
 879 Sat Apr 30 10:33:29 2011 Received.
 880 Sat Apr 30 10:33:29 2011 Starting 300 seconds test run.
 881 SFS Single Client (c09-44) Results, Sat Apr 30 10:38:39 2011
 882 --------------------------------------------------------------------------------------
 883 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 884 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
 885 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
 886            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 887 --------------------------------------------------------------------------------------
 888 getattr     26.0%    25.9%    31126    31126      0      0.46     7.38      0.03  3.8%
 889 setattr      4.0%     4.0%     4796     4796      0      4.54    10.83      0.09  5.7%
 890 lookup      24.0%    23.9%    28666    28666      0      0.46     7.34      0.03  3.4%
 891 readlink     1.0%     1.0%     1226     1226      0      0.70    10.00      0.18  0.2%
 892 read        18.0%    18.1%    21795    26826      0      6.51    54.19      0.10 37.2%
 893 write       10.0%    10.1%    12167    14944      0     10.48    37.89      0.11 33.4%
 894 create       1.0%     1.0%     1172     1172      0      7.41     9.99      0.18  2.3%
 895 remove       1.0%     1.0%     1207     1207      0      6.69    12.12      0.20  2.1%
 896 readdir      1.0%     1.0%     1175     1175      0      0.60     5.98      0.14  0.2%
 897 fsstat       1.0%     1.0%     1253     1253      0      0.52     7.84      0.15  0.2%
 898 access      11.0%    11.0%    13202    13202      0      0.48     7.64      0.05  1.7%
 899 commit       0.0%     0.0%        0     5307      0      0.00     0.00      0.00  9.4%
 900 readdirplus  2.0%     2.0%     2387     2387      0      0.82     5.17      0.09  0.5%
 901 --------------------------------------------------------------------------------------
 902
 903       --------------------------------------------------
 904       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
 905       --------------------------+-----------------------
 906 SFS NFS V3 THROUGHPUT:  400.57 Ops/Sec   AVG. RESPONSE TIME:    3.17 Msec/Op
 907 TCP PROTOCOL
 908 FAST CALLS: 0
 909 NFS V3 MIXFILE: [ SFS Default ]
 910 CLIENT REQUESTED LOAD: 400 Ops/Sec
 911 TOTAL LOGICAL NFS V3 OPERATIONS:  120172         TEST TIME: 300 Sec
 912 TOTAL PHYSICAL NFS V3 OPERATIONS: 133287
 913 PHYSICAL NFS V3 THROUGHPUT:  444.29 Ops/Sec
 914 FILE SET SIZE CREATED: 48248784.0 KB
 915 FILE SET SIZE ACCESSED: 14477184.0 - 14766192.0 KB  (100.00% to 102.00% of Base)
 916
 917 ------------------------------------------------------------------------
 918
 919 Sat Apr 30 10:38:39 2011 Completed.
 920
 921 Client Test Parameters:
 922         Number of processes = 16
 923         Requested Load (NFS V3 operations/second) = 400
 924         Maximum number of outstanding biod writes = 8
 925         Maximum number of outstanding biod reads = 8
 926         Warm-up time (seconds) = 300
 927         Run time (seconds) = 300
 928         File Set = 90864 Files created for I/O operations
 929                    27248 Files accessed for I/O operations
 930                    1824 Files for non-I/O operations
 931                      32 Symlinks
 932                    3024 Directories
 933                         Additional non-I/O files created as necessary
 934
 935
 936 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
 937 Sat Apr 30 10:38:39 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
 938 Sat Apr 30 10:38:39 2011 Completed.
 939 Sat Apr 30 10:38:39 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
 940
 941 ************************************************************************
 942 Sat Apr 30 10:38:40 2011 Received.
 943 Sat Apr 30 10:38:40 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
 944 Sat Apr 30 10:38:40 2011 Completed.
 945 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
 946 NFS V3 Protocol Version
 947
 948 ************************************************************************
 949 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
 950 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
 951
 952 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
 953 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 954
 955 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 10:38:42 2011
 956         Client hostname = c09-44
 957         Prime Client hostname = c09-44.sysnet.ucsd.edu
 958
 959 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 960 NFS V3 Protocol
 961 Sat Apr 30 10:38:42 2011 Mounting 16 remote test directories.
 962 Child[000]: NFS negotiated block size 32768
 963 Child[001]: NFS negotiated block size 32768
 964 Child[002]: NFS negotiated block size 32768
 965 Child[003]: NFS negotiated block size 32768
 966 Child[004]: NFS negotiated block size 32768
 967 Child[005]: NFS negotiated block size 32768
 968 Child[006]: NFS negotiated block size 32768
 969 Child[007]: NFS negotiated block size 32768
 970 Child[008]: NFS negotiated block size 32768
 971 Child[009]: NFS negotiated block size 32768
 972 Child[010]: NFS negotiated block size 32768
 973 Child[011]: NFS negotiated block size 32768
 974 Child[012]: NFS negotiated block size 32768
 975 Child[013]: NFS negotiated block size 32768
 976 Child[014]: NFS negotiated block size 32768
 977 Child[015]: NFS negotiated block size 32768
 978 Sat Apr 30 10:38:56 2011 Completed.
 979 Sat Apr 30 10:38:56 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
 980 Sat Apr 30 10:38:56 2011 Completed.
 981 Sat Apr 30 10:38:56 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
 982 Sat Apr 30 10:38:58 2011 Received.
 983
 984 Sat Apr 30 10:38:58 2011 Initializing test directories.
 985 Sat Apr 30 10:38:58 2011 Child 15 will create 239 directories.
 986 Sat Apr 30 10:38:58 2011 Child 12 will create 239 directories.
 987 Sat Apr 30 10:38:58 2011 Child 11 will create 239 directories.
 988 Sat Apr 30 10:38:58 2011 Child 0 will create 239 directories.
 989 Sat Apr 30 10:38:58 2011 Child 14 will create 239 directories.
 990 Sat Apr 30 10:38:58 2011 Child 2 will create 239 directories.
 991 Sat Apr 30 10:38:58 2011 Child 9 will create 239 directories.
 992 Sat Apr 30 10:38:58 2011 Child 1 will create 239 directories.
 993 Sat Apr 30 10:38:58 2011 Child 13 will create 239 directories.
 994 Sat Apr 30 10:38:58 2011 Child 4 will create 239 directories.
 995 Sat Apr 30 10:38:58 2011 Child 7 will create 239 directories.
 996 Sat Apr 30 10:38:58 2011 Child 10 will create 239 directories.
 997 Sat Apr 30 10:38:58 2011 Child 3 will create 239 directories.
 998 Sat Apr 30 10:38:58 2011 Child 5 will create 239 directories.
 999 Sat Apr 30 10:38:58 2011 Child 6 will create 239 directories.
1000 Sat Apr 30 10:38:58 2011 Child 8 will create 239 directories.
1001 Sat Apr 30 10:38:59 2011 Child 2 finished creating 239 directories.
1002 Sat Apr 30 10:38:59 2011 Child 2 will create 7304 files.
1003 Sat Apr 30 10:39:00 2011 Child 6 finished creating 239 directories.
1004 Sat Apr 30 10:39:00 2011 Child 6 will create 7304 files.
1005 Sat Apr 30 10:39:00 2011 Child 3 finished creating 239 directories.
1006 Sat Apr 30 10:39:00 2011 Child 3 will create 7304 files.
1007 Sat Apr 30 10:39:03 2011 Child 10 finished creating 239 directories.
1008 Sat Apr 30 10:39:03 2011 Child 10 will create 7304 files.
1009 Sat Apr 30 10:39:04 2011 Child 12 finished creating 239 directories.
1010 Sat Apr 30 10:39:04 2011 Child 12 will create 7304 files.
1011 Sat Apr 30 10:39:07 2011 Child 9 finished creating 239 directories.
1012 Sat Apr 30 10:39:07 2011 Child 9 will create 7304 files.
1013 Sat Apr 30 10:39:07 2011 Child 15 finished creating 239 directories.
1014 Sat Apr 30 10:39:07 2011 Child 15 will create 7304 files.
1015 Sat Apr 30 10:39:11 2011 Child 0 finished creating 239 directories.
1016 Sat Apr 30 10:39:11 2011 Child 0 will create 7304 files.
1017 Sat Apr 30 10:39:11 2011 Child 1 finished creating 239 directories.
1018 Sat Apr 30 10:39:11 2011 Child 1 will create 7304 files.
1019 Sat Apr 30 10:39:11 2011 Child 11 finished creating 239 directories.
1020 Sat Apr 30 10:39:11 2011 Child 11 will create 7304 files.
1021 Sat Apr 30 10:39:11 2011 Child 4 finished creating 239 directories.
1022 Sat Apr 30 10:39:11 2011 Child 4 will create 7304 files.
1023 Sat Apr 30 10:39:13 2011 Child 7 finished creating 239 directories.
1024 Sat Apr 30 10:39:13 2011 Child 7 will create 7304 files.
1025 Sat Apr 30 10:39:13 2011 Child 13 finished creating 239 directories.
1026 Sat Apr 30 10:39:13 2011 Child 13 will create 7304 files.
1027 Sat Apr 30 10:39:15 2011 Child 5 finished creating 239 directories.
1028 Sat Apr 30 10:39:15 2011 Child 5 will create 7304 files.
1029 Sat Apr 30 10:39:16 2011 Child 14 finished creating 239 directories.
1030 Sat Apr 30 10:39:16 2011 Child 14 will create 7304 files.
1031 Sat Apr 30 10:39:18 2011 Child 8 finished creating 239 directories.
1032 Sat Apr 30 10:39:18 2011 Child 8 will create 7304 files.
1033 Sat Apr 30 10:44:00 2011 Child 2 verified 6783 files (1104 creates).
1034 Sat Apr 30 10:44:01 2011 Child 6 verified 6822 files (1143 creates).
1035 Sat Apr 30 10:44:01 2011 Child 3 verified 6822 files (1143 creates).
1036 Sat Apr 30 10:44:12 2011 Child 0 verified 6424 files (745 creates).
1037 Sat Apr 30 10:44:12 2011 Child 9 verified 6515 files (836 creates).
1038 Sat Apr 30 10:44:15 2011 Child 1 verified 6353 files (674 creates).
1039 Sat Apr 30 10:44:15 2011 Child 13 verified 6354 files (675 creates).
1040 Sat Apr 30 10:44:15 2011 Child 10 verified 6547 files (868 creates).
1041 Sat Apr 30 10:44:15 2011 Child 7 verified 6308 files (629 creates).
1042 Sat Apr 30 10:44:18 2011 Child 11 verified 6337 files (658 creates).
1043 Sat Apr 30 10:44:18 2011 Child 5 verified 6337 files (658 creates).
1044 Sat Apr 30 10:44:18 2011 Child 4 verified 6337 files (658 creates).
1045 Sat Apr 30 10:44:18 2011 Child 15 verified 6500 files (821 creates).
1046 Sat Apr 30 10:44:18 2011 Child 12 verified 6500 files (821 creates).
1047 Sat Apr 30 10:44:18 2011 Child 14 verified 6307 files (628 creates).
1048 Sat Apr 30 10:44:19 2011 Child 8 verified 6364 files (685 creates).
1049 Sat Apr 30 10:47:02 2011 Child 3 verified 7304 files, created 1554
1050 Sat Apr 30 10:47:02 2011 Child 3 will create 2 symlinks.
1051 Sat Apr 30 10:47:03 2011 Child 3 finished creating 2 symlinks.
1052 Sat Apr 30 10:47:11 2011 Child 6 verified 7304 files, created 1554
1053 Sat Apr 30 10:47:11 2011 Child 6 will create 2 symlinks.
1054 Sat Apr 30 10:47:11 2011 Child 6 finished creating 2 symlinks.
1055 Sat Apr 30 10:47:19 2011 Child 2 verified 7304 files, created 1554
1056 Sat Apr 30 10:47:19 2011 Child 2 will create 2 symlinks.
1057 Sat Apr 30 10:47:19 2011 Child 2 finished creating 2 symlinks.
1058 Sat Apr 30 10:47:45 2011 Child 10 verified 7304 files, created 1554
1059 Sat Apr 30 10:47:45 2011 Child 10 will create 2 symlinks.
1060 Sat Apr 30 10:47:45 2011 Child 10 finished creating 2 symlinks.
1061 Sat Apr 30 10:48:14 2011 Child 9 verified 7304 files, created 1554
1062 Sat Apr 30 10:48:14 2011 Child 9 will create 2 symlinks.
1063 Sat Apr 30 10:48:14 2011 Child 9 finished creating 2 symlinks.
1064 Sat Apr 30 10:48:15 2011 Child 15 verified 7304 files, created 1554
1065 Sat Apr 30 10:48:15 2011 Child 15 will create 2 symlinks.
1066 Sat Apr 30 10:48:16 2011 Child 15 finished creating 2 symlinks.
1067 Sat Apr 30 10:48:20 2011 Child 12 verified 7304 files, created 1554
1068 Sat Apr 30 10:48:20 2011 Child 12 will create 2 symlinks.
1069 Sat Apr 30 10:48:20 2011 Child 12 finished creating 2 symlinks.
1070 Sat Apr 30 10:48:23 2011 Child 13 verified 7304 files, created 1554
1071 Sat Apr 30 10:48:23 2011 Child 13 will create 2 symlinks.
1072 Sat Apr 30 10:48:23 2011 Child 13 finished creating 2 symlinks.
1073 Sat Apr 30 10:48:32 2011 Child 8 verified 7304 files, created 1554
1074 Sat Apr 30 10:48:32 2011 Child 8 will create 2 symlinks.
1075 Sat Apr 30 10:48:32 2011 Child 8 finished creating 2 symlinks.
1076 Sat Apr 30 10:48:49 2011 Child 5 verified 7304 files, created 1554
1077 Sat Apr 30 10:48:49 2011 Child 5 will create 2 symlinks.
1078 Sat Apr 30 10:48:49 2011 Child 5 finished creating 2 symlinks.
1079 Sat Apr 30 10:48:49 2011 Child 4 verified 7304 files, created 1554
1080 Sat Apr 30 10:48:49 2011 Child 4 will create 2 symlinks.
1081 Sat Apr 30 10:48:50 2011 Child 4 finished creating 2 symlinks.
1082 Sat Apr 30 10:48:50 2011 Child 0 verified 7304 files, created 1554
1083 Sat Apr 30 10:48:50 2011 Child 0 will create 2 symlinks.
1084 Sat Apr 30 10:48:51 2011 Child 0 finished creating 2 symlinks.
1085 Sat Apr 30 10:48:52 2011 Child 11 verified 7304 files, created 1554
1086 Sat Apr 30 10:48:52 2011 Child 11 will create 2 symlinks.
1087 Sat Apr 30 10:48:52 2011 Child 11 finished creating 2 symlinks.
1088 Sat Apr 30 10:48:52 2011 Child 1 verified 7304 files, created 1554
1089 Sat Apr 30 10:48:52 2011 Child 1 will create 2 symlinks.
1090 Sat Apr 30 10:48:52 2011 Child 1 finished creating 2 symlinks.
1091 Sat Apr 30 10:48:58 2011 Child 7 verified 7304 files, created 1554
1092 Sat Apr 30 10:48:58 2011 Child 7 will create 2 symlinks.
1093 Sat Apr 30 10:48:58 2011 Child 14 verified 7304 files, created 1554
1094 Sat Apr 30 10:48:58 2011 Child 14 will create 2 symlinks.
1095 Sat Apr 30 10:48:58 2011 Child 7 finished creating 2 symlinks.
1096 Sat Apr 30 10:48:58 2011 Child 14 finished creating 2 symlinks.
1097 Sat Apr 30 10:48:58 2011 Completed.
1098 Sat Apr 30 10:48:58 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1099 Sat Apr 30 10:48:58 2011 Completed.
1100 Sat Apr 30 10:48:58 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1101 Sat Apr 30 10:48:59 2011 Received.
1102 Sat Apr 30 10:48:59 2011 Performing 300 seconds pretest warmup.
1103 Sat Apr 30 10:53:59 2011 Completed.
1104 Sat Apr 30 10:53:59 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1105 Sat Apr 30 10:53:59 2011 Completed.
1106 Sat Apr 30 10:53:59 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1107 Sat Apr 30 10:54:00 2011 Received.
1108 Sat Apr 30 10:54:00 2011 Starting 300 seconds test run.
1109 SFS Single Client (c09-44) Results, Sat Apr 30 10:59:10 2011
1110 --------------------------------------------------------------------------------------
1111 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1112 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1113 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1114            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1115 --------------------------------------------------------------------------------------
1116 getattr     26.0%    26.0%    39089    39089      0      0.30     4.28      0.02  2.1%
1117 setattr      4.0%     4.0%     5971     5971      0      6.56    14.66      0.10  7.0%
1118 lookup      24.0%    23.8%    35813    35813      0      0.38     5.63      0.02  2.4%
1119 readlink     1.0%     1.0%     1491     1491      0      0.34     5.18      0.12  0.1%
1120 read        18.0%    18.2%    27273    33625      0      7.72    41.20      0.08 37.5%
1121 write       10.0%    10.0%    15066    18287      0     12.65    40.04      0.10 33.9%
1122 create       1.0%     1.0%     1512     1512      0     10.03    15.09      0.20  2.7%
1123 remove       1.0%     1.0%     1497     1497      0      8.85    18.30      0.22  2.4%
1124 readdir      1.0%     1.0%     1512     1512      0      0.44     0.41      0.03  0.1%
1125 fsstat       1.0%     1.0%     1474     1474      0      0.39     5.56      0.12  0.1%
1126 access      11.0%    11.0%    16480    16480      0      0.33     4.81      0.03  1.0%
1127 commit       0.0%     0.0%        0     6640      0      0.00     0.00      0.00 10.4%
1128 readdirplus  2.0%     2.0%     3063     3063      0      0.74     0.83      0.03  0.4%
1129 --------------------------------------------------------------------------------------
1130
1131       --------------------------------------------------
1132       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1133       --------------------------+-----------------------
1134 SFS NFS V3 THROUGHPUT:  500.80 Ops/Sec   AVG. RESPONSE TIME:    3.73 Msec/Op
1135 TCP PROTOCOL
1136 FAST CALLS: 0
1137 NFS V3 MIXFILE: [ SFS Default ]
1138 CLIENT REQUESTED LOAD: 500 Ops/Sec
1139 TOTAL LOGICAL NFS V3 OPERATIONS:  150241         TEST TIME: 300 Sec
1140 TOTAL PHYSICAL NFS V3 OPERATIONS: 166454
1141 PHYSICAL NFS V3 THROUGHPUT:  554.84 Ops/Sec
1142 FILE SET SIZE CREATED: 60831360.0 KB
1143 FILE SET SIZE ACCESSED: 18257904.0 - 18612268.0 KB  (100.00% to 101.94% of Base)
1144
1145 ------------------------------------------------------------------------
1146
1147 Sat Apr 30 10:59:10 2011 Completed.
1148
1149 Client Test Parameters:
1150         Number of processes = 16
1151         Requested Load (NFS V3 operations/second) = 500
1152         Maximum number of outstanding biod writes = 8
1153         Maximum number of outstanding biod reads = 8
1154         Warm-up time (seconds) = 300
1155         Run time (seconds) = 300
1156         File Set = 114560 Files created for I/O operations
1157                    34368 Files accessed for I/O operations
1158                    2304 Files for non-I/O operations
1159                      32 Symlinks
1160                    3808 Directories
1161                         Additional non-I/O files created as necessary
1162
1163
1164 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1165 Sat Apr 30 10:59:10 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1166 Sat Apr 30 10:59:10 2011 Completed.
1167 Sat Apr 30 10:59:10 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1168
1169 ************************************************************************
1170 Sat Apr 30 10:59:11 2011 Received.
1171 Sat Apr 30 10:59:11 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1172 Sat Apr 30 10:59:11 2011 Completed.
1173 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
1174 NFS V3 Protocol Version
1175
1176 ************************************************************************
1177 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1178 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1179
1180 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1181 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1182
1183 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 10:59:13 2011
1184         Client hostname = c09-44
1185         Prime Client hostname = c09-44.sysnet.ucsd.edu
1186
1187 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1188 NFS V3 Protocol
1189 Sat Apr 30 10:59:13 2011 Mounting 16 remote test directories.
1190 Child[000]: NFS negotiated block size 32768
1191 Child[001]: NFS negotiated block size 32768
1192 Child[002]: NFS negotiated block size 32768
1193 Child[003]: NFS negotiated block size 32768
1194 Child[004]: NFS negotiated block size 32768
1195 Child[005]: NFS negotiated block size 32768
1196 Child[006]: NFS negotiated block size 32768
1197 Child[007]: NFS negotiated block size 32768
1198 Child[008]: NFS negotiated block size 32768
1199 Child[009]: NFS negotiated block size 32768
1200 Child[010]: NFS negotiated block size 32768
1201 Child[011]: NFS negotiated block size 32768
1202 Child[012]: NFS negotiated block size 32768
1203 Child[013]: NFS negotiated block size 32768
1204 Child[014]: NFS negotiated block size 32768
1205 Child[015]: NFS negotiated block size 32768
1206 Sat Apr 30 10:59:27 2011 Completed.
1207 Sat Apr 30 10:59:27 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1208 Sat Apr 30 10:59:27 2011 Completed.
1209 Sat Apr 30 10:59:27 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1210 Sat Apr 30 10:59:29 2011 Received.
1211
1212 Sat Apr 30 10:59:29 2011 Initializing test directories.
1213 Sat Apr 30 10:59:29 2011 Child 14 will create 289 directories.
1214 Sat Apr 30 10:59:29 2011 Child 7 will create 289 directories.
1215 Sat Apr 30 10:59:29 2011 Child 12 will create 289 directories.
1216 Sat Apr 30 10:59:29 2011 Child 2 will create 289 directories.
1217 Sat Apr 30 10:59:29 2011 Child 13 will create 289 directories.
1218 Sat Apr 30 10:59:29 2011 Child 1 will create 289 directories.
1219 Sat Apr 30 10:59:29 2011 Child 5 will create 289 directories.
1220 Sat Apr 30 10:59:29 2011 Child 9 will create 289 directories.
1221 Sat Apr 30 10:59:29 2011 Child 8 will create 289 directories.
1222 Sat Apr 30 10:59:29 2011 Child 4 will create 289 directories.
1223 Sat Apr 30 10:59:29 2011 Child 3 will create 289 directories.
1224 Sat Apr 30 10:59:29 2011 Child 11 will create 289 directories.
1225 Sat Apr 30 10:59:29 2011 Child 6 will create 289 directories.
1226 Sat Apr 30 10:59:29 2011 Child 0 will create 289 directories.
1227 Sat Apr 30 10:59:29 2011 Child 15 will create 289 directories.
1228 Sat Apr 30 10:59:29 2011 Child 10 will create 289 directories.
1229 Sat Apr 30 10:59:33 2011 Child 2 finished creating 289 directories.
1230 Sat Apr 30 10:59:33 2011 Child 2 will create 8814 files.
1231 Sat Apr 30 10:59:35 2011 Child 13 finished creating 289 directories.
1232 Sat Apr 30 10:59:35 2011 Child 13 will create 8814 files.
1233 Sat Apr 30 10:59:36 2011 Child 3 finished creating 289 directories.
1234 Sat Apr 30 10:59:36 2011 Child 3 will create 8814 files.
1235 Sat Apr 30 10:59:37 2011 Child 9 finished creating 289 directories.
1236 Sat Apr 30 10:59:37 2011 Child 9 will create 8814 files.
1237 Sat Apr 30 10:59:40 2011 Child 5 finished creating 289 directories.
1238 Sat Apr 30 10:59:40 2011 Child 5 will create 8814 files.
1239 Sat Apr 30 10:59:42 2011 Child 10 finished creating 289 directories.
1240 Sat Apr 30 10:59:42 2011 Child 10 will create 8814 files.
1241 Sat Apr 30 10:59:42 2011 Child 14 finished creating 289 directories.
1242 Sat Apr 30 10:59:42 2011 Child 14 will create 8814 files.
1243 Sat Apr 30 10:59:45 2011 Child 15 finished creating 289 directories.
1244 Sat Apr 30 10:59:45 2011 Child 15 will create 8814 files.
1245 Sat Apr 30 10:59:46 2011 Child 8 finished creating 289 directories.
1246 Sat Apr 30 10:59:46 2011 Child 8 will create 8814 files.
1247 Sat Apr 30 10:59:48 2011 Child 12 finished creating 289 directories.
1248 Sat Apr 30 10:59:48 2011 Child 12 will create 8814 files.
1249 Sat Apr 30 10:59:49 2011 Child 1 finished creating 289 directories.
1250 Sat Apr 30 10:59:49 2011 Child 1 will create 8814 files.
1251 Sat Apr 30 10:59:49 2011 Child 7 finished creating 289 directories.
1252 Sat Apr 30 10:59:49 2011 Child 7 will create 8814 files.
1253 Sat Apr 30 10:59:49 2011 Child 4 finished creating 289 directories.
1254 Sat Apr 30 10:59:49 2011 Child 4 will create 8814 files.
1255 Sat Apr 30 10:59:53 2011 Child 11 finished creating 289 directories.
1256 Sat Apr 30 10:59:53 2011 Child 11 will create 8814 files.
1257 Sat Apr 30 10:59:53 2011 Child 0 finished creating 289 directories.
1258 Sat Apr 30 10:59:53 2011 Child 0 will create 8814 files.
1259 Sat Apr 30 10:59:53 2011 Child 6 finished creating 289 directories.
1260 Sat Apr 30 10:59:53 2011 Child 6 will create 8814 files.
1261 Sat Apr 30 11:03:45 2011 Child 2 verified 8814 files, created 1568
1262 Sat Apr 30 11:03:45 2011 Child 2 will create 2 symlinks.
1263 Sat Apr 30 11:03:45 2011 Child 2 finished creating 2 symlinks.
1264 Sat Apr 30 11:04:35 2011 Child 13 verified 8814 files, created 1568
1265 Sat Apr 30 11:04:35 2011 Child 13 will create 2 symlinks.
1266 Sat Apr 30 11:04:35 2011 Child 13 finished creating 2 symlinks.
1267 Sat Apr 30 11:04:39 2011 Child 9 verified 8613 files (1453 creates).
1268 Sat Apr 30 11:04:39 2011 Child 3 verified 8613 files (1453 creates).
1269 Sat Apr 30 11:04:44 2011 Child 10 verified 8415 files (1255 creates).
1270 Sat Apr 30 11:04:44 2011 Child 5 verified 8414 files (1254 creates).
1271 Sat Apr 30 11:04:49 2011 Child 14 verified 8385 files (1225 creates).
1272 Sat Apr 30 11:04:49 2011 Child 15 verified 8345 files (1185 creates).
1273 Sat Apr 30 11:04:49 2011 Child 8 verified 8205 files (1045 creates).
1274 Sat Apr 30 11:04:50 2011 Child 12 verified 8195 files (1035 creates).
1275 Sat Apr 30 11:04:50 2011 Child 1 verified 8227 files (1067 creates).
1276 Sat Apr 30 11:04:50 2011 Child 4 verified 8142 files (982 creates).
1277 Sat Apr 30 11:04:53 2011 Child 7 verified 8229 files (1069 creates).
1278 Sat Apr 30 11:04:55 2011 Child 11 verified 8160 files (1000 creates).
1279 Sat Apr 30 11:04:55 2011 Child 6 verified 8160 files (1000 creates).
1280 Sat Apr 30 11:04:56 2011 Child 3 verified 8814 files, created 1568
1281 Sat Apr 30 11:04:56 2011 Child 3 will create 2 symlinks.
1282 Sat Apr 30 11:04:56 2011 Child 3 finished creating 2 symlinks.
1283 Sat Apr 30 11:04:56 2011 Child 9 verified 8814 files, created 1568
1284 Sat Apr 30 11:04:56 2011 Child 9 will create 2 symlinks.
1285 Sat Apr 30 11:04:56 2011 Child 9 finished creating 2 symlinks.
1286 Sat Apr 30 11:05:01 2011 Child 0 verified 8233 files (1073 creates).
1287 Sat Apr 30 11:05:54 2011 Child 5 verified 8814 files, created 1568
1288 Sat Apr 30 11:05:54 2011 Child 5 will create 2 symlinks.
1289 Sat Apr 30 11:05:54 2011 Child 5 finished creating 2 symlinks.
1290 Sat Apr 30 11:06:10 2011 Child 10 verified 8814 files, created 1568
1291 Sat Apr 30 11:06:10 2011 Child 10 will create 2 symlinks.
1292 Sat Apr 30 11:06:10 2011 Child 10 finished creating 2 symlinks.
1293 Sat Apr 30 11:06:20 2011 Child 14 verified 8814 files, created 1568
1294 Sat Apr 30 11:06:20 2011 Child 14 will create 2 symlinks.
1295 Sat Apr 30 11:06:21 2011 Child 14 finished creating 2 symlinks.
1296 Sat Apr 30 11:06:23 2011 Child 15 verified 8814 files, created 1568
1297 Sat Apr 30 11:06:23 2011 Child 15 will create 2 symlinks.
1298 Sat Apr 30 11:06:23 2011 Child 15 finished creating 2 symlinks.
1299 Sat Apr 30 11:06:46 2011 Child 7 verified 8814 files, created 1568
1300 Sat Apr 30 11:06:46 2011 Child 7 will create 2 symlinks.
1301 Sat Apr 30 11:06:46 2011 Child 1 verified 8814 files, created 1568
1302 Sat Apr 30 11:06:46 2011 Child 1 will create 2 symlinks.
1303 Sat Apr 30 11:06:46 2011 Child 0 verified 8814 files, created 1568
1304 Sat Apr 30 11:06:46 2011 Child 0 will create 2 symlinks.
1305 Sat Apr 30 11:06:46 2011 Child 7 finished creating 2 symlinks.
1306 Sat Apr 30 11:06:49 2011 Child 1 finished creating 2 symlinks.
1307 Sat Apr 30 11:06:52 2011 Child 12 verified 8814 files, created 1568
1308 Sat Apr 30 11:06:52 2011 Child 12 will create 2 symlinks.
1309 Sat Apr 30 11:06:52 2011 Child 12 finished creating 2 symlinks.
1310 Sat Apr 30 11:06:53 2011 Child 0 finished creating 2 symlinks.
1311 Sat Apr 30 11:06:55 2011 Child 4 verified 8814 files, created 1568
1312 Sat Apr 30 11:06:55 2011 Child 4 will create 2 symlinks.
1313 Sat Apr 30 11:06:55 2011 Child 4 finished creating 2 symlinks.
1314 Sat Apr 30 11:06:55 2011 Child 8 verified 8814 files, created 1568
1315 Sat Apr 30 11:06:55 2011 Child 8 will create 2 symlinks.
1316 Sat Apr 30 11:06:55 2011 Child 8 finished creating 2 symlinks.
1317 Sat Apr 30 11:07:01 2011 Child 6 verified 8814 files, created 1568
1318 Sat Apr 30 11:07:01 2011 Child 6 will create 2 symlinks.
1319 Sat Apr 30 11:07:01 2011 Child 6 finished creating 2 symlinks.
1320 Sat Apr 30 11:07:01 2011 Child 11 verified 8814 files, created 1568
1321 Sat Apr 30 11:07:01 2011 Child 11 will create 2 symlinks.
1322 Sat Apr 30 11:07:01 2011 Child 11 finished creating 2 symlinks.
1323 Sat Apr 30 11:07:01 2011 Completed.
1324 Sat Apr 30 11:07:01 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1325 Sat Apr 30 11:07:01 2011 Completed.
1326 Sat Apr 30 11:07:01 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1327 Sat Apr 30 11:07:01 2011 Received.
1328 Sat Apr 30 11:07:01 2011 Performing 300 seconds pretest warmup.
1329 Sat Apr 30 11:12:01 2011 Completed.
1330 Sat Apr 30 11:12:01 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1331 Sat Apr 30 11:12:01 2011 Completed.
1332 Sat Apr 30 11:12:01 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1333 Sat Apr 30 11:12:02 2011 Received.
1334 Sat Apr 30 11:12:02 2011 Starting 300 seconds test run.
1335 Child 5:  0 calls during measurement interval
1336 Child 5:  probably unstable, try fewer, or more processes.
1337 SFS Single Client (c09-44) Results, Sat Apr 30 11:17:12 2011
1338 --------------------------------------------------------------------------------------
1339 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1340 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1341 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1342            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1343 --------------------------------------------------------------------------------------
1344 getattr     26.0%    26.2%    47264    47264      0      0.58     8.17      0.03  2.9%
1345 setattr      4.0%     4.0%     7265     7265      0     13.20    30.35      0.13 10.3%
1346 lookup      24.0%    23.9%    43083    43083      0      0.58     7.83      0.03  2.7%
1347 readlink     1.0%     1.0%     1812     1812      0      0.52     6.63      0.12  0.1%
1348 read        18.0%    18.0%    32448    40002      0      8.50    35.73      0.07 29.6%
1349 write       10.0%     9.9%    17955    21896      0     16.93   138.29      0.17 32.6%
1350 create       1.0%     1.0%     1836     1836      0     21.38    42.69      0.30  4.2%
1351 remove       1.0%     1.0%     1840     1840      0     14.74    23.96      0.22  2.9%
1352 readdir      1.0%     1.0%     1820     1820      0      0.58     4.84      0.10  0.1%
1353 fsstat       1.0%     1.0%     1768     1768      0      0.55     7.26      0.13  0.1%
1354 access      11.0%    10.9%    19693    19693      0      0.53     7.74      0.04  1.1%
1355 commit       0.0%     0.0%        0     7823      0      0.00     0.00      0.00 12.7%
1356 readdirplus  2.0%     2.1%     3723     3723      0      1.37    10.98      0.11  0.5%
1357 --------------------------------------------------------------------------------------
1358
1359       --------------------------------------------------
1360       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1361       --------------------------+-----------------------
1362 SFS NFS V3 THROUGHPUT:  603.70 Ops/Sec   AVG. RESPONSE TIME:    5.16 Msec/Op
1363 TCP PROTOCOL
1364 FAST CALLS: 0
1365 NFS V3 MIXFILE: [ SFS Default ]
1366 CLIENT REQUESTED LOAD: 600 Ops/Sec
1367 TOTAL LOGICAL NFS V3 OPERATIONS:  180507         TEST TIME: 299 Sec
1368 TOTAL PHYSICAL NFS V3 OPERATIONS: 199825
1369 PHYSICAL NFS V3 THROUGHPUT:  668.31 Ops/Sec
1370 FILE SET SIZE CREATED: 73413936.0 KB
1371 FILE SET SIZE ACCESSED: 22030128.0 - 22446974.0 KB  (100.00% to 101.89% of Base)
1372
1373 ------------------------------------------------------------------------
1374
1375 Sat Apr 30 11:17:12 2011 Completed.
1376
1377 Client Test Parameters:
1378         Number of processes = 16
1379         Requested Load (NFS V3 operations/second) = 600
1380         Maximum number of outstanding biod writes = 8
1381         Maximum number of outstanding biod reads = 8
1382         Warm-up time (seconds) = 300
1383         Run time (seconds) = 300
1384         File Set = 138256 Files created for I/O operations
1385                    41472 Files accessed for I/O operations
1386                    2768 Files for non-I/O operations
1387                      32 Symlinks
1388                    4608 Directories
1389                         Additional non-I/O files created as necessary
1390
1391
1392 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1393 Sat Apr 30 11:17:12 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1394 Sat Apr 30 11:17:12 2011 Completed.
1395 Sat Apr 30 11:17:12 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1396
1397 ************************************************************************
1398 Sat Apr 30 11:17:13 2011 Received.
1399 Sat Apr 30 11:17:13 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1400 Sat Apr 30 11:17:13 2011 Completed.
1401 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
1402 NFS V3 Protocol Version
1403 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1404 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1405
1406 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1407 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1408
1409 ************************************************************************
1410
1411 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 11:17:15 2011
1412         Client hostname = c09-44
1413         Prime Client hostname = c09-44.sysnet.ucsd.edu
1414
1415 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1416 NFS V3 Protocol
1417 Sat Apr 30 11:17:15 2011 Mounting 16 remote test directories.
1418 Child[000]: NFS negotiated block size 32768
1419 Child[001]: NFS negotiated block size 32768
1420 Child[002]: NFS negotiated block size 32768
1421 Child[003]: NFS negotiated block size 32768
1422 Child[004]: NFS negotiated block size 32768
1423 Child[005]: NFS negotiated block size 32768
1424 Child[006]: NFS negotiated block size 32768
1425 Child[007]: NFS negotiated block size 32768
1426 Child[008]: NFS negotiated block size 32768
1427 Child[009]: NFS negotiated block size 32768
1428 Child[010]: NFS negotiated block size 32768
1429 Child[011]: NFS negotiated block size 32768
1430 Child[012]: NFS negotiated block size 32768
1431 Child[013]: NFS negotiated block size 32768
1432 Child[014]: NFS negotiated block size 32768
1433 Child[015]: NFS negotiated block size 32768
1434 Sat Apr 30 11:17:29 2011 Completed.
1435 Sat Apr 30 11:17:29 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1436 Sat Apr 30 11:17:29 2011 Completed.
1437 Sat Apr 30 11:17:29 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1438 Sat Apr 30 11:17:31 2011 Received.
1439
1440 Sat Apr 30 11:17:31 2011 Initializing test directories.
1441 Sat Apr 30 11:17:31 2011 Child 4 will create 338 directories.
1442 Sat Apr 30 11:17:31 2011 Child 8 will create 338 directories.
1443 Sat Apr 30 11:17:31 2011 Child 10 will create 338 directories.
1444 Sat Apr 30 11:17:31 2011 Child 3 will create 338 directories.
1445 Sat Apr 30 11:17:31 2011 Child 11 will create 338 directories.
1446 Sat Apr 30 11:17:31 2011 Child 2 will create 338 directories.
1447 Sat Apr 30 11:17:31 2011 Child 1 will create 338 directories.
1448 Sat Apr 30 11:17:31 2011 Child 15 will create 338 directories.
1449 Sat Apr 30 11:17:31 2011 Child 12 will create 338 directories.
1450 Sat Apr 30 11:17:31 2011 Child 6 will create 338 directories.
1451 Sat Apr 30 11:17:31 2011 Child 5 will create 338 directories.
1452 Sat Apr 30 11:17:31 2011 Child 13 will create 338 directories.
1453 Sat Apr 30 11:17:31 2011 Child 9 will create 338 directories.
1454 Sat Apr 30 11:17:31 2011 Child 14 will create 338 directories.
1455 Sat Apr 30 11:17:31 2011 Child 7 will create 338 directories.
1456 Sat Apr 30 11:17:31 2011 Child 0 will create 338 directories.
1457 Sat Apr 30 11:17:36 2011 Child 0 finished creating 338 directories.
1458 Sat Apr 30 11:17:36 2011 Child 0 will create 10325 files.
1459 Sat Apr 30 11:17:36 2011 Child 8 finished creating 338 directories.
1460 Sat Apr 30 11:17:36 2011 Child 8 will create 10325 files.
1461 Sat Apr 30 11:17:36 2011 Child 2 finished creating 338 directories.
1462 Sat Apr 30 11:17:36 2011 Child 2 will create 10325 files.
1463 Sat Apr 30 11:17:38 2011 Child 5 finished creating 338 directories.
1464 Sat Apr 30 11:17:38 2011 Child 5 will create 10325 files.
1465 Sat Apr 30 11:17:40 2011 Child 6 finished creating 338 directories.
1466 Sat Apr 30 11:17:40 2011 Child 6 will create 10325 files.
1467 Sat Apr 30 11:17:41 2011 Child 4 finished creating 338 directories.
1468 Sat Apr 30 11:17:41 2011 Child 4 will create 10325 files.
1469 Sat Apr 30 11:17:43 2011 Child 11 finished creating 338 directories.
1470 Sat Apr 30 11:17:43 2011 Child 11 will create 10325 files.
1471 Sat Apr 30 11:17:43 2011 Child 10 finished creating 338 directories.
1472 Sat Apr 30 11:17:43 2011 Child 10 will create 10325 files.
1473 Sat Apr 30 11:17:44 2011 Child 7 finished creating 338 directories.
1474 Sat Apr 30 11:17:44 2011 Child 7 will create 10325 files.
1475 Sat Apr 30 11:17:46 2011 Child 13 finished creating 338 directories.
1476 Sat Apr 30 11:17:46 2011 Child 13 will create 10325 files.
1477 Sat Apr 30 11:17:47 2011 Child 14 finished creating 338 directories.
1478 Sat Apr 30 11:17:47 2011 Child 14 will create 10325 files.
1479 Sat Apr 30 11:17:47 2011 Child 9 finished creating 338 directories.
1480 Sat Apr 30 11:17:47 2011 Child 9 will create 10325 files.
1481 Sat Apr 30 11:17:54 2011 Child 12 finished creating 338 directories.
1482 Sat Apr 30 11:17:54 2011 Child 12 will create 10325 files.
1483 Sat Apr 30 11:17:54 2011 Child 15 finished creating 338 directories.
1484 Sat Apr 30 11:17:54 2011 Child 15 will create 10325 files.
1485 Sat Apr 30 11:17:56 2011 Child 1 finished creating 338 directories.
1486 Sat Apr 30 11:17:56 2011 Child 1 will create 10325 files.
1487 Sat Apr 30 11:17:58 2011 Child 3 finished creating 338 directories.
1488 Sat Apr 30 11:17:58 2011 Child 3 will create 10325 files.
1489 Sat Apr 30 11:22:37 2011 Child 8 verified 10014 files (1373 creates).
1490 Sat Apr 30 11:22:37 2011 Child 0 verified 10081 files (1440 creates).
1491 Sat Apr 30 11:22:37 2011 Child 2 verified 10080 files (1439 creates).
1492 Sat Apr 30 11:22:39 2011 Child 5 verified 9904 files (1263 creates).
1493 Sat Apr 30 11:22:43 2011 Child 6 verified 9761 files (1120 creates).
1494 Sat Apr 30 11:22:43 2011 Child 4 verified 9755 files (1114 creates).
1495 Sat Apr 30 11:22:46 2011 Child 11 verified 9750 files (1109 creates).
1496 Sat Apr 30 11:22:46 2011 Child 7 verified 9761 files (1120 creates).
1497 Sat Apr 30 11:22:46 2011 Child 10 verified 9640 files (999 creates).
1498 Sat Apr 30 11:22:47 2011 Child 13 verified 9689 files (1048 creates).
1499 Sat Apr 30 11:22:48 2011 Child 14 verified 9708 files (1067 creates).
1500 Sat Apr 30 11:22:49 2011 Child 0 verified 10325 files, created 1583
1501 Sat Apr 30 11:22:49 2011 Child 0 will create 2 symlinks.
1502 Sat Apr 30 11:22:49 2011 Child 0 finished creating 2 symlinks.
1503 Sat Apr 30 11:22:50 2011 Child 8 verified 10325 files, created 1583
1504 Sat Apr 30 11:22:50 2011 Child 8 will create 2 symlinks.
1505 Sat Apr 30 11:22:50 2011 Child 8 finished creating 2 symlinks.
1506 Sat Apr 30 11:22:59 2011 Child 2 verified 10325 files, created 1583
1507 Sat Apr 30 11:22:59 2011 Child 2 will create 2 symlinks.
1508 Sat Apr 30 11:22:59 2011 Child 2 finished creating 2 symlinks.
1509 Sat Apr 30 11:23:08 2011 Child 15 verified 9508 files (867 creates).
1510 Sat Apr 30 11:23:08 2011 Child 3 verified 9508 files (867 creates).
1511 Sat Apr 30 11:23:10 2011 Child 1 verified 9508 files (867 creates).
1512 Sat Apr 30 11:23:10 2011 Child 12 verified 9508 files (867 creates).
1513 Sat Apr 30 11:23:11 2011 Child 9 verified 9653 files (1012 creates).
1514 Sat Apr 30 11:23:32 2011 Child 5 verified 10325 files, created 1583
1515 Sat Apr 30 11:23:32 2011 Child 5 will create 2 symlinks.
1516 Sat Apr 30 11:23:32 2011 Child 5 finished creating 2 symlinks.
1517 Sat Apr 30 11:24:45 2011 Child 4 verified 10325 files, created 1583
1518 Sat Apr 30 11:24:45 2011 Child 4 will create 2 symlinks.
1519 Sat Apr 30 11:24:46 2011 Child 4 finished creating 2 symlinks.
1520 Sat Apr 30 11:24:50 2011 Child 7 verified 10325 files, created 1583
1521 Sat Apr 30 11:24:50 2011 Child 7 will create 2 symlinks.
1522 Sat Apr 30 11:24:50 2011 Child 7 finished creating 2 symlinks.
1523 Sat Apr 30 11:24:51 2011 Child 6 verified 10325 files, created 1583
1524 Sat Apr 30 11:24:51 2011 Child 6 will create 2 symlinks.
1525 Sat Apr 30 11:24:51 2011 Child 6 finished creating 2 symlinks.
1526 Sat Apr 30 11:24:58 2011 Child 11 verified 10325 files, created 1583
1527 Sat Apr 30 11:24:58 2011 Child 11 will create 2 symlinks.
1528 Sat Apr 30 11:24:58 2011 Child 11 finished creating 2 symlinks.
1529 Sat Apr 30 11:25:01 2011 Child 13 verified 10325 files, created 1583
1530 Sat Apr 30 11:25:01 2011 Child 13 will create 2 symlinks.
1531 Sat Apr 30 11:25:01 2011 Child 13 finished creating 2 symlinks.
1532 Sat Apr 30 11:25:02 2011 Child 14 verified 10325 files, created 1583
1533 Sat Apr 30 11:25:02 2011 Child 14 will create 2 symlinks.
1534 Sat Apr 30 11:25:02 2011 Child 14 finished creating 2 symlinks.
1535 Sat Apr 30 11:25:07 2011 Child 10 verified 10325 files, created 1583
1536 Sat Apr 30 11:25:07 2011 Child 10 will create 2 symlinks.
1537 Sat Apr 30 11:25:07 2011 Child 9 verified 10325 files, created 1583
1538 Sat Apr 30 11:25:07 2011 Child 9 will create 2 symlinks.
1539 Sat Apr 30 11:25:07 2011 Child 10 finished creating 2 symlinks.
1540 Sat Apr 30 11:25:07 2011 Child 9 finished creating 2 symlinks.
1541 Sat Apr 30 11:25:12 2011 Child 3 verified 10325 files, created 1583
1542 Sat Apr 30 11:25:12 2011 Child 3 will create 2 symlinks.
1543 Sat Apr 30 11:25:12 2011 Child 3 finished creating 2 symlinks.
1544 Sat Apr 30 11:25:13 2011 Child 15 verified 10325 files, created 1583
1545 Sat Apr 30 11:25:13 2011 Child 15 will create 2 symlinks.
1546 Sat Apr 30 11:25:13 2011 Child 15 finished creating 2 symlinks.
1547 Sat Apr 30 11:25:14 2011 Child 12 verified 10325 files, created 1583
1548 Sat Apr 30 11:25:14 2011 Child 12 will create 2 symlinks.
1549 Sat Apr 30 11:25:14 2011 Child 12 finished creating 2 symlinks.
1550 Sat Apr 30 11:25:14 2011 Child 1 verified 10325 files, created 1583
1551 Sat Apr 30 11:25:14 2011 Child 1 will create 2 symlinks.
1552 Sat Apr 30 11:25:14 2011 Child 1 finished creating 2 symlinks.
1553 Sat Apr 30 11:25:14 2011 Completed.
1554 Sat Apr 30 11:25:14 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1555 Sat Apr 30 11:25:14 2011 Completed.
1556 Sat Apr 30 11:25:14 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1557 Sat Apr 30 11:25:15 2011 Received.
1558 Sat Apr 30 11:25:15 2011 Performing 300 seconds pretest warmup.
1559 Sat Apr 30 11:30:15 2011 Completed.
1560 Sat Apr 30 11:30:15 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1561 Sat Apr 30 11:30:15 2011 Completed.
1562 Sat Apr 30 11:30:15 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1563 Sat Apr 30 11:30:16 2011 Received.
1564 Sat Apr 30 11:30:16 2011 Starting 300 seconds test run.
1565 SFS Single Client (c09-44) Results, Sat Apr 30 11:35:31 2011
1566 --------------------------------------------------------------------------------------
1567 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1568 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1569 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1570            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1571 --------------------------------------------------------------------------------------
1572 getattr     26.0%    25.8%    46109    46109      0      7.51   547.90      0.21  7.6%
1573 setattr      4.0%     4.0%     7181     7181      0     59.48   123.68      0.26  9.3%
1574 lookup      24.0%    24.0%    42956    42956      0      4.60   364.35      0.18  4.3%
1575 readlink     1.0%     1.0%     1796     1796      0      2.93    59.28      0.36  0.1%
1576 read        18.0%    18.1%    32362    40039      0     55.66  1200.37      0.38 39.3%
1577 write       10.0%    10.0%    17972    21933      0     53.23   972.80      0.46 20.9%
1578 create       1.0%     1.0%     1840     1840      0     97.46   136.77      0.53  3.9%
1579 remove       1.0%     1.0%     1780     1780      0     59.32    72.69      0.40  2.3%
1580 readdir      1.0%     1.0%     1734     1734      0      2.07    12.18      0.16  0.1%
1581 fsstat       1.0%     1.0%     1776     1776      0      8.17   287.27      0.79  0.3%
1582 access      11.0%    11.1%    19809    19809      0      4.51   374.51      0.27  2.0%
1583 commit       0.0%     0.0%        0     7246      0      0.00     0.00      0.00  9.4%
1584 readdirplus  2.0%     2.0%     3603     3603      0      5.92   201.86      0.46  0.5%
1585 --------------------------------------------------------------------------------------
1586
1587       --------------------------------------------------
1588       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1589       --------------------------+-----------------------
1590 SFS NFS V3 THROUGHPUT:  588.54 Ops/Sec   AVG. RESPONSE TIME:   25.59 Msec/Op
1591 TCP PROTOCOL
1592 FAST CALLS: 0
1593 NFS V3 MIXFILE: [ SFS Default ]
1594 CLIENT REQUESTED LOAD: 700 Ops/Sec
1595 TOTAL LOGICAL NFS V3 OPERATIONS:  178918         TEST TIME: 304 Sec
1596 TOTAL PHYSICAL NFS V3 OPERATIONS: 197802
1597 PHYSICAL NFS V3 THROUGHPUT:  650.66 Ops/Sec
1598 FILE SET SIZE CREATED: 85996512.0 KB
1599 FILE SET SIZE ACCESSED: 25802352.0 - 26155396.0 KB  (100.00% to 101.37% of Base)
1600
1601 ------------------------------------------------------------------------
1602
1603 Sat Apr 30 11:35:31 2011 Completed.
1604
1605 Client Test Parameters:
1606         Number of processes = 16
1607         Requested Load (NFS V3 operations/second) = 700
1608         Maximum number of outstanding biod writes = 8
1609         Maximum number of outstanding biod reads = 8
1610         Warm-up time (seconds) = 300
1611         Run time (seconds) = 300
1612         File Set = 161952 Files created for I/O operations
1613                    48576 Files accessed for I/O operations
1614                    3248 Files for non-I/O operations
1615                      32 Symlinks
1616                    5392 Directories
1617                         Additional non-I/O files created as necessary
1618
1619
1620 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1621 Sat Apr 30 11:35:31 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1622 Sat Apr 30 11:35:31 2011 Completed.
1623 Sat Apr 30 11:35:31 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1624
1625 ************************************************************************
1626 Sat Apr 30 11:35:31 2011 Received.
1627 Sat Apr 30 11:35:31 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1628 Sat Apr 30 11:35:31 2011 Completed.
1629 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
1630 NFS V3 Protocol Version
1631
1632 ************************************************************************
1633 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1634 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1635
1636 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1637 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1638
1639 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 11:35:33 2011
1640         Client hostname = c09-44
1641         Prime Client hostname = c09-44.sysnet.ucsd.edu
1642
1643 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1644 NFS V3 Protocol
1645 Sat Apr 30 11:35:33 2011 Mounting 16 remote test directories.
1646 Child[000]: NFS negotiated block size 32768
1647 Child[001]: NFS negotiated block size 32768
1648 Child[002]: NFS negotiated block size 32768
1649 Child[003]: NFS negotiated block size 32768
1650 Child[004]: NFS negotiated block size 32768
1651 Child[005]: NFS negotiated block size 32768
1652 Child[006]: NFS negotiated block size 32768
1653 Child[007]: NFS negotiated block size 32768
1654 Child[008]: NFS negotiated block size 32768
1655 Child[009]: NFS negotiated block size 32768
1656 Child[010]: NFS negotiated block size 32768
1657 Child[011]: NFS negotiated block size 32768
1658 Child[012]: NFS negotiated block size 32768
1659 Child[013]: NFS negotiated block size 32768
1660 Child[014]: NFS negotiated block size 32768
1661 Child[015]: NFS negotiated block size 32768
1662 Sat Apr 30 11:35:47 2011 Completed.
1663 Sat Apr 30 11:35:47 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1664 Sat Apr 30 11:35:47 2011 Completed.
1665 Sat Apr 30 11:35:47 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1666 Sat Apr 30 11:35:49 2011 Received.
1667
1668 Sat Apr 30 11:35:49 2011 Initializing test directories.
1669 Sat Apr 30 11:35:49 2011 Child 15 will create 383 directories.
1670 Sat Apr 30 11:35:49 2011 Child 12 will create 383 directories.
1671 Sat Apr 30 11:35:49 2011 Child 10 will create 383 directories.
1672 Sat Apr 30 11:35:49 2011 Child 2 will create 383 directories.
1673 Sat Apr 30 11:35:49 2011 Child 11 will create 383 directories.
1674 Sat Apr 30 11:35:49 2011 Child 7 will create 383 directories.
1675 Sat Apr 30 11:35:49 2011 Child 0 will create 383 directories.
1676 Sat Apr 30 11:35:49 2011 Child 6 will create 383 directories.
1677 Sat Apr 30 11:35:49 2011 Child 14 will create 383 directories.
1678 Sat Apr 30 11:35:49 2011 Child 5 will create 383 directories.
1679 Sat Apr 30 11:35:49 2011 Child 1 will create 383 directories.
1680 Sat Apr 30 11:35:49 2011 Child 13 will create 383 directories.
1681 Sat Apr 30 11:35:49 2011 Child 9 will create 383 directories.
1682 Sat Apr 30 11:35:49 2011 Child 4 will create 383 directories.
1683 Sat Apr 30 11:35:49 2011 Child 8 will create 383 directories.
1684 Sat Apr 30 11:35:49 2011 Child 3 will create 383 directories.
1685 Sat Apr 30 11:35:50 2011 Child 14 finished creating 383 directories.
1686 Sat Apr 30 11:35:50 2011 Child 14 will create 11710 files.
1687 Sat Apr 30 11:35:50 2011 Child 7 finished creating 383 directories.
1688 Sat Apr 30 11:35:50 2011 Child 7 will create 11710 files.
1689 Sat Apr 30 11:35:52 2011 Child 12 finished creating 383 directories.
1690 Sat Apr 30 11:35:52 2011 Child 12 will create 11710 files.
1691 Sat Apr 30 11:35:59 2011 Child 15 finished creating 383 directories.
1692 Sat Apr 30 11:35:59 2011 Child 15 will create 11710 files.
1693 Sat Apr 30 11:36:00 2011 Child 13 finished creating 383 directories.
1694 Sat Apr 30 11:36:00 2011 Child 13 will create 11710 files.
1695 Sat Apr 30 11:36:01 2011 Child 3 finished creating 383 directories.
1696 Sat Apr 30 11:36:01 2011 Child 3 will create 11710 files.
1697 Sat Apr 30 11:36:03 2011 Child 1 finished creating 383 directories.
1698 Sat Apr 30 11:36:03 2011 Child 1 will create 11710 files.
1699 Sat Apr 30 11:36:03 2011 Child 9 finished creating 383 directories.
1700 Sat Apr 30 11:36:03 2011 Child 9 will create 11710 files.
1701 Sat Apr 30 11:36:04 2011 Child 6 finished creating 383 directories.
1702 Sat Apr 30 11:36:04 2011 Child 6 will create 11710 files.
1703 Sat Apr 30 11:36:11 2011 Child 10 finished creating 383 directories.
1704 Sat Apr 30 11:36:11 2011 Child 10 will create 11710 files.
1705 Sat Apr 30 11:36:13 2011 Child 8 finished creating 383 directories.
1706 Sat Apr 30 11:36:13 2011 Child 8 will create 11710 files.
1707 Sat Apr 30 11:36:13 2011 Child 0 finished creating 383 directories.
1708 Sat Apr 30 11:36:13 2011 Child 0 will create 11710 files.
1709 Sat Apr 30 11:36:15 2011 Child 2 finished creating 383 directories.
1710 Sat Apr 30 11:36:15 2011 Child 2 will create 11710 files.
1711 Sat Apr 30 11:36:19 2011 Child 5 finished creating 383 directories.
1712 Sat Apr 30 11:36:19 2011 Child 5 will create 11710 files.
1713 Sat Apr 30 11:36:19 2011 Child 11 finished creating 383 directories.
1714 Sat Apr 30 11:36:19 2011 Child 11 will create 11710 files.
1715 Sat Apr 30 11:36:22 2011 Child 4 finished creating 383 directories.
1716 Sat Apr 30 11:36:22 2011 Child 4 will create 11710 files.
1717 Sat Apr 30 11:40:51 2011 Child 14 verified 11318 files (1196 creates).
1718 Sat Apr 30 11:40:51 2011 Child 7 verified 11265 files (1143 creates).
1719 Sat Apr 30 11:40:53 2011 Child 12 verified 11192 files (1070 creates).
1720 Sat Apr 30 11:41:04 2011 Child 15 verified 10972 files (850 creates).
1721 Sat Apr 30 11:41:04 2011 Child 3 verified 10812 files (690 creates).
1722 Sat Apr 30 11:41:04 2011 Child 13 verified 10812 files (690 creates).
1723 Sat Apr 30 11:41:04 2011 Child 1 verified 10806 files (684 creates).
1724 Sat Apr 30 11:41:04 2011 Child 9 verified 10803 files (681 creates).
1725 Sat Apr 30 11:41:05 2011 Child 6 verified 10705 files (583 creates).
1726 Sat Apr 30 11:41:12 2011 Child 10 verified 10528 files (406 creates).
1727 Sat Apr 30 11:41:19 2011 Child 2 verified 10639 files (517 creates).
1728 Sat Apr 30 11:41:20 2011 Child 0 verified 10737 files (615 creates).
1729 Sat Apr 30 11:41:20 2011 Child 8 verified 10635 files (513 creates).
1730 Sat Apr 30 11:41:20 2011 Child 5 verified 10754 files (632 creates).
1731 Sat Apr 30 11:41:20 2011 Child 11 verified 10536 files (414 creates).
1732 Sat Apr 30 11:41:23 2011 Child 4 verified 10567 files (445 creates).
1733 Sat Apr 30 11:41:28 2011 Child 14 verified 11710 files, created 1474
1734 Sat Apr 30 11:41:28 2011 Child 14 will create 2 symlinks.
1735 Sat Apr 30 11:41:28 2011 Child 14 finished creating 2 symlinks.
1736 Sat Apr 30 11:41:59 2011 Child 7 verified 11710 files, created 1474
1737 Sat Apr 30 11:41:59 2011 Child 7 will create 2 symlinks.
1738 Sat Apr 30 11:41:59 2011 Child 7 finished creating 2 symlinks.
1739 Sat Apr 30 11:42:05 2011 Child 12 verified 11710 files, created 1474
1740 Sat Apr 30 11:42:05 2011 Child 12 will create 2 symlinks.
1741 Sat Apr 30 11:42:05 2011 Child 12 finished creating 2 symlinks.
1742 Sat Apr 30 11:42:48 2011 Child 15 verified 11710 files, created 1474
1743 Sat Apr 30 11:42:48 2011 Child 15 will create 2 symlinks.
1744 Sat Apr 30 11:42:48 2011 Child 15 finished creating 2 symlinks.
1745 Sat Apr 30 11:43:33 2011 Child 3 verified 11710 files, created 1474
1746 Sat Apr 30 11:43:33 2011 Child 3 will create 2 symlinks.
1747 Sat Apr 30 11:43:36 2011 Child 3 finished creating 2 symlinks.
1748 Sat Apr 30 11:43:43 2011 Child 9 verified 11710 files, created 1474
1749 Sat Apr 30 11:43:43 2011 Child 9 will create 2 symlinks.
1750 Sat Apr 30 11:43:43 2011 Child 1 verified 11710 files, created 1474
1751 Sat Apr 30 11:43:43 2011 Child 1 will create 2 symlinks.
1752 Sat Apr 30 11:43:43 2011 Child 6 verified 11710 files, created 1474
1753 Sat Apr 30 11:43:43 2011 Child 6 will create 2 symlinks.
1754 Sat Apr 30 11:43:43 2011 Child 13 verified 11710 files, created 1474
1755 Sat Apr 30 11:43:43 2011 Child 13 will create 2 symlinks.
1756 Sat Apr 30 11:43:54 2011 Child 2 verified 11710 files, created 1474
1757 Sat Apr 30 11:43:54 2011 Child 2 will create 2 symlinks.
1758 Sat Apr 30 11:43:54 2011 Child 5 verified 11710 files, created 1474
1759 Sat Apr 30 11:43:54 2011 Child 5 will create 2 symlinks.
1760 Sat Apr 30 11:43:54 2011 Child 2 finished creating 2 symlinks.
1761 Sat Apr 30 11:43:54 2011 Child 5 finished creating 2 symlinks.
1762 Sat Apr 30 11:43:55 2011 Child 0 verified 11710 files, created 1474
1763 Sat Apr 30 11:43:55 2011 Child 0 will create 2 symlinks.
1764 Sat Apr 30 11:43:55 2011 Child 0 finished creating 2 symlinks.
1765 Sat Apr 30 11:43:56 2011 Child 9 finished creating 2 symlinks.
1766 Sat Apr 30 11:43:56 2011 Child 1 finished creating 2 symlinks.
1767 Sat Apr 30 11:43:56 2011 Child 13 finished creating 2 symlinks.
1768 Sat Apr 30 11:43:56 2011 Child 6 finished creating 2 symlinks.
1769 Sat Apr 30 11:44:00 2011 Child 8 verified 11710 files, created 1474
1770 Sat Apr 30 11:44:00 2011 Child 8 will create 2 symlinks.
1771 Sat Apr 30 11:44:01 2011 Child 8 finished creating 2 symlinks.
1772 Sat Apr 30 11:44:01 2011 Child 11 verified 11710 files, created 1474
1773 Sat Apr 30 11:44:01 2011 Child 11 will create 2 symlinks.
1774 Sat Apr 30 11:44:01 2011 Child 11 finished creating 2 symlinks.
1775 Sat Apr 30 11:44:02 2011 Child 10 verified 11710 files, created 1474
1776 Sat Apr 30 11:44:02 2011 Child 10 will create 2 symlinks.
1777 Sat Apr 30 11:44:02 2011 Child 10 finished creating 2 symlinks.
1778 Sat Apr 30 11:44:03 2011 Child 4 verified 11710 files, created 1474
1779 Sat Apr 30 11:44:03 2011 Child 4 will create 2 symlinks.
1780 Sat Apr 30 11:44:03 2011 Child 4 finished creating 2 symlinks.
1781 Sat Apr 30 11:44:03 2011 Completed.
1782 Sat Apr 30 11:44:03 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
1783 Sat Apr 30 11:44:03 2011 Completed.
1784 Sat Apr 30 11:44:03 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
1785 Sat Apr 30 11:44:03 2011 Received.
1786 Sat Apr 30 11:44:03 2011 Performing 300 seconds pretest warmup.
1787 Sat Apr 30 11:49:03 2011 Completed.
1788 Sat Apr 30 11:49:03 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
1789 Sat Apr 30 11:49:03 2011 Completed.
1790 Sat Apr 30 11:49:03 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
1791 Sat Apr 30 11:49:04 2011 Received.
1792 Sat Apr 30 11:49:04 2011 Starting 300 seconds test run.
1793 SFS Single Client (c09-44) Results, Sat Apr 30 11:54:14 2011
1794 --------------------------------------------------------------------------------------
1795 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
1796 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
1797 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
1798            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
1799 --------------------------------------------------------------------------------------
1800 getattr     26.0%    25.9%    34886    34886      0      4.74    19.98      0.05  3.0%
1801 setattr      4.0%     4.0%     5335     5335      0    173.98   303.25      0.47 16.8%
1802 lookup      24.0%    24.1%    32456    32456      0      4.91    21.40      0.05  2.9%
1803 readlink     1.0%     0.9%     1276     1276      0      5.18    20.11      0.25  0.1%
1804 read        18.0%    18.0%    24254    29862      0     68.06   252.82      0.20 29.8%
1805 write       10.0%    10.0%    13419    16175      0     64.02   163.33      0.22 15.5%
1806 create       1.0%     1.0%     1402     1402      0    319.66   406.73      1.06  8.1%
1807 remove       1.0%     1.0%     1355     1355      0    192.34   319.13      0.95  4.7%
1808 readdir      1.0%     1.0%     1324     1324      0      5.03    18.68      0.23  0.1%
1809 fsstat       1.0%     1.0%     1325     1325      0      5.06    20.10      0.24  0.1%
1810 access      11.0%    10.9%    14723    14723      0      4.92    21.63      0.08  1.3%
1811 commit       0.0%     0.0%        0     5023      0      0.00     0.00      0.00 17.3%
1812 readdirplus  2.0%     2.1%     2761     2761      0      4.89    17.72      0.16  0.2%
1813 --------------------------------------------------------------------------------------
1814
1815       --------------------------------------------------
1816       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
1817       --------------------------+-----------------------
1818 SFS NFS V3 THROUGHPUT:  448.38 Ops/Sec   AVG. RESPONSE TIME:   41.13 Msec/Op
1819 TCP PROTOCOL
1820 FAST CALLS: 0
1821 NFS V3 MIXFILE: [ SFS Default ]
1822 CLIENT REQUESTED LOAD: 800 Ops/Sec
1823 TOTAL LOGICAL NFS V3 OPERATIONS:  134516         TEST TIME: 300 Sec
1824 TOTAL PHYSICAL NFS V3 OPERATIONS: 147903
1825 PHYSICAL NFS V3 THROUGHPUT:  493.01 Ops/Sec
1826 FILE SET SIZE CREATED: 97534080.0 KB
1827 FILE SET SIZE ACCESSED: 29268720.0 - 29508700.0 KB  (100.00% to 100.82% of Base)
1828
1829 ------------------------------------------------------------------------
1830
1831 Sat Apr 30 11:54:14 2011 Completed.
1832
1833 Client Test Parameters:
1834         Number of processes = 16
1835         Requested Load (NFS V3 operations/second) = 800
1836         Maximum number of outstanding biod writes = 8
1837         Maximum number of outstanding biod reads = 8
1838         Warm-up time (seconds) = 300
1839         Run time (seconds) = 300
1840         File Set = 183680 Files created for I/O operations
1841                    55104 Files accessed for I/O operations
1842                    3680 Files for non-I/O operations
1843                      32 Symlinks
1844                    6112 Directories
1845                         Additional non-I/O files created as necessary
1846
1847
1848 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
1849 Sat Apr 30 11:54:14 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
1850 Sat Apr 30 11:54:14 2011 Completed.
1851 Sat Apr 30 11:54:14 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
1852
1853 ************************************************************************
1854 Sat Apr 30 11:54:15 2011 Received.
1855 Sat Apr 30 11:54:15 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
1856 Sat Apr 30 11:54:15 2011 Completed.
1857 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
1858 NFS V3 Protocol Version
1859
1860 ************************************************************************
1861 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
1862 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
1863
1864 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
1865 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1866
1867 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 11:54:17 2011
1868         Client hostname = c09-44
1869         Prime Client hostname = c09-44.sysnet.ucsd.edu
1870
1871 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
1872 NFS V3 Protocol
1873 Sat Apr 30 11:54:17 2011 Mounting 16 remote test directories.
1874 Child[000]: NFS negotiated block size 32768
1875 Child[001]: NFS negotiated block size 32768
1876 Child[002]: NFS negotiated block size 32768
1877 Child[003]: NFS negotiated block size 32768
1878 Child[004]: NFS negotiated block size 32768
1879 Child[005]: NFS negotiated block size 32768
1880 Child[006]: NFS negotiated block size 32768
1881 Child[007]: NFS negotiated block size 32768
1882 Child[008]: NFS negotiated block size 32768
1883 Child[009]: NFS negotiated block size 32768
1884 Child[010]: NFS negotiated block size 32768
1885 Child[011]: NFS negotiated block size 32768
1886 Child[012]: NFS negotiated block size 32768
1887 Child[013]: NFS negotiated block size 32768
1888 Child[014]: NFS negotiated block size 32768
1889 Child[015]: NFS negotiated block size 32768
1890 Sat Apr 30 11:54:31 2011 Completed.
1891 Sat Apr 30 11:54:31 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
1892 Sat Apr 30 11:54:31 2011 Completed.
1893 Sat Apr 30 11:54:31 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
1894 Sat Apr 30 11:54:33 2011 Received.
1895
1896 Sat Apr 30 11:54:33 2011 Initializing test directories.
1897 Sat Apr 30 11:54:33 2011 Child 14 will create 433 directories.
1898 Sat Apr 30 11:54:33 2011 Child 5 will create 433 directories.
1899 Sat Apr 30 11:54:33 2011 Child 15 will create 433 directories.
1900 Sat Apr 30 11:54:33 2011 Child 1 will create 433 directories.
1901 Sat Apr 30 11:54:33 2011 Child 10 will create 433 directories.
1902 Sat Apr 30 11:54:33 2011 Child 0 will create 433 directories.
1903 Sat Apr 30 11:54:33 2011 Child 11 will create 433 directories.
1904 Sat Apr 30 11:54:33 2011 Child 3 will create 433 directories.
1905 Sat Apr 30 11:54:33 2011 Child 13 will create 433 directories.
1906 Sat Apr 30 11:54:33 2011 Child 6 will create 433 directories.
1907 Sat Apr 30 11:54:33 2011 Child 9 will create 433 directories.
1908 Sat Apr 30 11:54:33 2011 Child 12 will create 433 directories.
1909 Sat Apr 30 11:54:33 2011 Child 7 will create 433 directories.
1910 Sat Apr 30 11:54:33 2011 Child 8 will create 433 directories.
1911 Sat Apr 30 11:54:33 2011 Child 4 will create 433 directories.
1912 Sat Apr 30 11:54:33 2011 Child 2 will create 433 directories.
1913 Sat Apr 30 11:54:35 2011 Child 10 finished creating 433 directories.
1914 Sat Apr 30 11:54:35 2011 Child 10 will create 13221 files.
1915 Sat Apr 30 11:54:36 2011 Child 5 finished creating 433 directories.
1916 Sat Apr 30 11:54:36 2011 Child 5 will create 13221 files.
1917 Sat Apr 30 11:54:40 2011 Child 7 finished creating 433 directories.
1918 Sat Apr 30 11:54:40 2011 Child 7 will create 13221 files.
1919 Sat Apr 30 11:54:41 2011 Child 3 finished creating 433 directories.
1920 Sat Apr 30 11:54:41 2011 Child 3 will create 13221 files.
1921 Sat Apr 30 11:54:43 2011 Child 8 finished creating 433 directories.
1922 Sat Apr 30 11:54:43 2011 Child 8 will create 13221 files.
1923 Sat Apr 30 11:54:51 2011 Child 13 finished creating 433 directories.
1924 Sat Apr 30 11:54:51 2011 Child 13 will create 13221 files.
1925 Sat Apr 30 11:54:51 2011 Child 12 finished creating 433 directories.
1926 Sat Apr 30 11:54:51 2011 Child 12 will create 13221 files.
1927 Sat Apr 30 11:54:51 2011 Child 1 finished creating 433 directories.
1928 Sat Apr 30 11:54:51 2011 Child 1 will create 13221 files.
1929 Sat Apr 30 11:54:54 2011 Child 11 finished creating 433 directories.
1930 Sat Apr 30 11:54:54 2011 Child 11 will create 13221 files.
1931 Sat Apr 30 11:54:56 2011 Child 15 finished creating 433 directories.
1932 Sat Apr 30 11:54:56 2011 Child 15 will create 13221 files.
1933 Sat Apr 30 11:55:02 2011 Child 0 finished creating 433 directories.
1934 Sat Apr 30 11:55:02 2011 Child 0 will create 13221 files.
1935 Sat Apr 30 11:55:06 2011 Child 4 finished creating 433 directories.
1936 Sat Apr 30 11:55:06 2011 Child 4 will create 13221 files.
1937 Sat Apr 30 11:55:09 2011 Child 2 finished creating 433 directories.
1938 Sat Apr 30 11:55:09 2011 Child 2 will create 13221 files.
1939 Sat Apr 30 11:55:10 2011 Child 6 finished creating 433 directories.
1940 Sat Apr 30 11:55:10 2011 Child 6 will create 13221 files.
1941 Sat Apr 30 11:55:11 2011 Child 9 finished creating 433 directories.
1942 Sat Apr 30 11:55:11 2011 Child 9 will create 13221 files.
1943 Sat Apr 30 11:55:12 2011 Child 14 finished creating 433 directories.
1944 Sat Apr 30 11:55:12 2011 Child 14 will create 13221 files.
1945 Sat Apr 30 11:58:44 2011 Child 10 verified 13221 files, created 1612
1946 Sat Apr 30 11:58:44 2011 Child 10 will create 2 symlinks.
1947 Sat Apr 30 11:58:44 2011 Child 10 finished creating 2 symlinks.
1948 Sat Apr 30 11:58:59 2011 Child 5 verified 13221 files, created 1612
1949 Sat Apr 30 11:58:59 2011 Child 5 will create 2 symlinks.
1950 Sat Apr 30 11:59:01 2011 Child 5 finished creating 2 symlinks.
1951 Sat Apr 30 11:59:43 2011 Child 3 verified 12859 files (1379 creates).
1952 Sat Apr 30 11:59:43 2011 Child 7 verified 12952 files (1472 creates).
1953 Sat Apr 30 11:59:46 2011 Child 8 verified 12790 files (1310 creates).
1954 Sat Apr 30 11:59:52 2011 Child 13 verified 12671 files (1191 creates).
1955 Sat Apr 30 11:59:52 2011 Child 12 verified 12775 files (1295 creates).
1956 Sat Apr 30 11:59:58 2011 Child 1 verified 12750 files (1270 creates).
1957 Sat Apr 30 11:59:58 2011 Child 15 verified 12648 files (1168 creates).
1958 Sat Apr 30 11:59:58 2011 Child 11 verified 12647 files (1167 creates).
1959 Sat Apr 30 12:00:05 2011 Child 0 verified 12594 files (1114 creates).
1960 Sat Apr 30 12:00:08 2011 Child 4 verified 12623 files (1143 creates).
1961 Sat Apr 30 12:00:10 2011 Child 2 verified 12480 files (1000 creates).
1962 Sat Apr 30 12:00:11 2011 Child 6 verified 12537 files (1057 creates).
1963 Sat Apr 30 12:00:15 2011 Child 9 verified 12446 files (966 creates).
1964 Sat Apr 30 12:00:15 2011 Child 14 verified 12437 files (957 creates).
1965 Sat Apr 30 12:00:18 2011 Child 3 verified 13221 files, created 1612
1966 Sat Apr 30 12:00:18 2011 Child 3 will create 2 symlinks.
1967 Sat Apr 30 12:00:18 2011 Child 3 finished creating 2 symlinks.
1968 Sat Apr 30 12:00:26 2011 Child 8 verified 13221 files, created 1612
1969 Sat Apr 30 12:00:26 2011 Child 8 will create 2 symlinks.
1970 Sat Apr 30 12:00:27 2011 Child 12 verified 13221 files, created 1612
1971 Sat Apr 30 12:00:27 2011 Child 12 will create 2 symlinks.
1972 Sat Apr 30 12:00:28 2011 Child 12 finished creating 2 symlinks.
1973 Sat Apr 30 12:00:29 2011 Child 8 finished creating 2 symlinks.
1974 Sat Apr 30 12:00:33 2011 Child 1 verified 13221 files, created 1612
1975 Sat Apr 30 12:00:33 2011 Child 1 will create 2 symlinks.
1976 Sat Apr 30 12:00:33 2011 Child 1 finished creating 2 symlinks.
1977 Sat Apr 30 12:00:36 2011 Child 13 verified 13221 files, created 1612
1978 Sat Apr 30 12:00:36 2011 Child 13 will create 2 symlinks.
1979 Sat Apr 30 12:00:36 2011 Child 13 finished creating 2 symlinks.
1980 Sat Apr 30 12:00:40 2011 Child 7 verified 13221 files, created 1612
1981 Sat Apr 30 12:00:40 2011 Child 7 will create 2 symlinks.
1982 Sat Apr 30 12:00:40 2011 Child 7 finished creating 2 symlinks.
1983 Sat Apr 30 12:00:46 2011 Child 15 verified 13221 files, created 1612
1984 Sat Apr 30 12:00:46 2011 Child 15 will create 2 symlinks.
1985 Sat Apr 30 12:00:46 2011 Child 15 finished creating 2 symlinks.
1986 Sat Apr 30 12:00:59 2011 Child 4 verified 13221 files, created 1612
1987 Sat Apr 30 12:00:59 2011 Child 4 will create 2 symlinks.
1988 Sat Apr 30 12:00:59 2011 Child 4 finished creating 2 symlinks.
1989 Sat Apr 30 12:01:00 2011 Child 11 verified 13221 files, created 1612
1990 Sat Apr 30 12:01:00 2011 Child 11 will create 2 symlinks.
1991 Sat Apr 30 12:01:00 2011 Child 11 finished creating 2 symlinks.
1992 Sat Apr 30 12:01:00 2011 Child 0 verified 13221 files, created 1612
1993 Sat Apr 30 12:01:00 2011 Child 0 will create 2 symlinks.
1994 Sat Apr 30 12:01:00 2011 Child 0 finished creating 2 symlinks.
1995 Sat Apr 30 12:01:08 2011 Child 6 verified 13221 files, created 1612
1996 Sat Apr 30 12:01:08 2011 Child 6 will create 2 symlinks.
1997 Sat Apr 30 12:01:09 2011 Child 6 finished creating 2 symlinks.
1998 Sat Apr 30 12:01:21 2011 Child 9 verified 13221 files, created 1612
1999 Sat Apr 30 12:01:21 2011 Child 9 will create 2 symlinks.
2000 Sat Apr 30 12:01:21 2011 Child 9 finished creating 2 symlinks.
2001 Sat Apr 30 12:01:21 2011 Child 2 verified 13221 files, created 1612
2002 Sat Apr 30 12:01:21 2011 Child 2 will create 2 symlinks.
2003 Sat Apr 30 12:01:21 2011 Child 2 finished creating 2 symlinks.
2004 Sat Apr 30 12:01:22 2011 Child 14 verified 13221 files, created 1612
2005 Sat Apr 30 12:01:22 2011 Child 14 will create 2 symlinks.
2006 Sat Apr 30 12:01:22 2011 Child 14 finished creating 2 symlinks.
2007 Sat Apr 30 12:01:23 2011 Completed.
2008 Sat Apr 30 12:01:23 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2009 Sat Apr 30 12:01:23 2011 Completed.
2010 Sat Apr 30 12:01:23 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2011 Sat Apr 30 12:01:24 2011 Received.
2012 Sat Apr 30 12:01:24 2011 Performing 300 seconds pretest warmup.
2013 Sat Apr 30 12:06:24 2011 Completed.
2014 Sat Apr 30 12:06:24 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2015 Sat Apr 30 12:06:24 2011 Completed.
2016 Sat Apr 30 12:06:24 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2017 Sat Apr 30 12:06:25 2011 Received.
2018 Sat Apr 30 12:06:25 2011 Starting 300 seconds test run.
2019 SFS Single Client (c09-44) Results, Sat Apr 30 12:11:36 2011
2020 --------------------------------------------------------------------------------------
2021 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2022 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2023 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2024            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2025 --------------------------------------------------------------------------------------
2026 getattr     26.0%    26.2%    17274    17274      0      8.93   187.05      0.20  2.7%
2027 setattr      4.0%     4.0%     2633     2633      0    312.98   554.78      0.90 14.5%
2028 lookup      24.0%    24.0%    15774    15774      0      8.07    97.62      0.15  2.2%
2029 readlink     1.0%     0.9%      610      610      0      7.81    26.02      0.40  0.1%
2030 read        18.0%    18.0%    11822    14457      0    180.45   992.93      0.57 37.5%
2031 write       10.0%     9.9%     6504     7932      0    140.82   767.11      0.67 16.1%
2032 create       1.0%     1.0%      651      651      0    639.71   698.96      2.03  7.3%
2033 remove       1.0%     1.0%      688      688      0    313.11   237.11      1.15  3.8%
2034 readdir      1.0%     1.0%      681      681      0     37.57   845.06      2.18  0.4%
2035 fsstat       1.0%     1.0%      672      672      0      8.19    29.41      0.41  0.1%
2036 access      11.0%    11.0%     7244     7244      0      9.84   260.12      0.37  1.3%
2037 commit       0.0%     0.0%        0     2295      0      0.00     0.00      0.00 13.9%
2038 readdirplus  2.0%     1.9%     1282     1282      0      7.19    22.30      0.26  0.2%
2039 --------------------------------------------------------------------------------------
2040
2041       --------------------------------------------------
2042       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2043       --------------------------+-----------------------
2044 SFS NFS V3 THROUGHPUT:  220.18 Ops/Sec   AVG. RESPONSE TIME:   86.48 Msec/Op
2045 TCP PROTOCOL
2046 FAST CALLS: 0
2047 NFS V3 MIXFILE: [ SFS Default ]
2048 CLIENT REQUESTED LOAD: 900 Ops/Sec
2049 TOTAL LOGICAL NFS V3 OPERATIONS:  65835          TEST TIME: 299 Sec
2050 TOTAL PHYSICAL NFS V3 OPERATIONS: 72193
2051 PHYSICAL NFS V3 THROUGHPUT:  241.44 Ops/Sec
2052 FILE SET SIZE CREATED: 110116656.0 KB
2053 FILE SET SIZE ACCESSED: 33040944.0 - 33171102.0 KB  (100.00% to 100.39% of Base)
2054
2055 ------------------------------------------------------------------------
2056
2057 Sat Apr 30 12:11:36 2011 Completed.
2058
2059 Client Test Parameters:
2060         Number of processes = 16
2061         Requested Load (NFS V3 operations/second) = 900
2062         Maximum number of outstanding biod writes = 8
2063         Maximum number of outstanding biod reads = 8
2064         Warm-up time (seconds) = 300
2065         Run time (seconds) = 300
2066         File Set = 207376 Files created for I/O operations
2067                    62208 Files accessed for I/O operations
2068                    4160 Files for non-I/O operations
2069                      32 Symlinks
2070                    6912 Directories
2071                         Additional non-I/O files created as necessary
2072
2073
2074 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2075 Sat Apr 30 12:11:36 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2076 Sat Apr 30 12:11:36 2011 Completed.
2077 Sat Apr 30 12:11:36 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2078
2079 ************************************************************************
2080 Sat Apr 30 12:11:36 2011 Received.
2081 Sat Apr 30 12:11:36 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2082 Sat Apr 30 12:11:36 2011 Completed.
2083 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
2084 NFS V3 Protocol Version
2085
2086 ************************************************************************
2087 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2088 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2089
2090 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2091 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2092
2093 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 12:11:38 2011
2094         Client hostname = c09-44
2095         Prime Client hostname = c09-44.sysnet.ucsd.edu
2096
2097 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2098 NFS V3 Protocol
2099 Sat Apr 30 12:11:38 2011 Mounting 16 remote test directories.
2100 Child[000]: NFS negotiated block size 32768
2101 Child[001]: NFS negotiated block size 32768
2102 Child[002]: NFS negotiated block size 32768
2103 Child[003]: NFS negotiated block size 32768
2104 Child[004]: NFS negotiated block size 32768
2105 Child[005]: NFS negotiated block size 32768
2106 Child[006]: NFS negotiated block size 32768
2107 Child[007]: NFS negotiated block size 32768
2108 Child[008]: NFS negotiated block size 32768
2109 Child[009]: NFS negotiated block size 32768
2110 Child[010]: NFS negotiated block size 32768
2111 Child[011]: NFS negotiated block size 32768
2112 Child[012]: NFS negotiated block size 32768
2113 Child[013]: NFS negotiated block size 32768
2114 Child[014]: NFS negotiated block size 32768
2115 Child[015]: NFS negotiated block size 32768
2116 Sat Apr 30 12:11:52 2011 Completed.
2117 Sat Apr 30 12:11:52 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2118 Sat Apr 30 12:11:52 2011 Completed.
2119 Sat Apr 30 12:11:52 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2120 Sat Apr 30 12:11:54 2011 Received.
2121
2122 Sat Apr 30 12:11:54 2011 Initializing test directories.
2123 Sat Apr 30 12:11:54 2011 Child 15 will create 482 directories.
2124 Sat Apr 30 12:11:54 2011 Child 13 will create 482 directories.
2125 Sat Apr 30 12:11:54 2011 Child 2 will create 482 directories.
2126 Sat Apr 30 12:11:54 2011 Child 1 will create 482 directories.
2127 Sat Apr 30 12:11:54 2011 Child 12 will create 482 directories.
2128 Sat Apr 30 12:11:54 2011 Child 14 will create 482 directories.
2129 Sat Apr 30 12:11:54 2011 Child 4 will create 482 directories.
2130 Sat Apr 30 12:11:54 2011 Child 11 will create 482 directories.
2131 Sat Apr 30 12:11:54 2011 Child 3 will create 482 directories.
2132 Sat Apr 30 12:11:54 2011 Child 5 will create 482 directories.
2133 Sat Apr 30 12:11:54 2011 Child 10 will create 482 directories.
2134 Sat Apr 30 12:11:54 2011 Child 0 will create 482 directories.
2135 Sat Apr 30 12:11:54 2011 Child 6 will create 482 directories.
2136 Sat Apr 30 12:11:54 2011 Child 9 will create 482 directories.
2137 Sat Apr 30 12:11:54 2011 Child 7 will create 482 directories.
2138 Sat Apr 30 12:11:54 2011 Child 8 will create 482 directories.
2139 Sat Apr 30 12:11:55 2011 Child 6 finished creating 482 directories.
2140 Sat Apr 30 12:11:55 2011 Child 6 will create 14732 files.
2141 Sat Apr 30 12:11:59 2011 Child 9 finished creating 482 directories.
2142 Sat Apr 30 12:11:59 2011 Child 9 will create 14732 files.
2143 Sat Apr 30 12:11:59 2011 Child 3 finished creating 482 directories.
2144 Sat Apr 30 12:11:59 2011 Child 3 will create 14732 files.
2145 Sat Apr 30 12:12:01 2011 Child 8 finished creating 482 directories.
2146 Sat Apr 30 12:12:01 2011 Child 8 will create 14732 files.
2147 Sat Apr 30 12:12:02 2011 Child 1 finished creating 482 directories.
2148 Sat Apr 30 12:12:02 2011 Child 1 will create 14732 files.
2149 Sat Apr 30 12:12:05 2011 Child 0 finished creating 482 directories.
2150 Sat Apr 30 12:12:05 2011 Child 0 will create 14732 files.
2151 Sat Apr 30 12:12:06 2011 Child 10 finished creating 482 directories.
2152 Sat Apr 30 12:12:06 2011 Child 10 will create 14732 files.
2153 Sat Apr 30 12:12:07 2011 Child 7 finished creating 482 directories.
2154 Sat Apr 30 12:12:07 2011 Child 7 will create 14732 files.
2155 Sat Apr 30 12:12:07 2011 Child 11 finished creating 482 directories.
2156 Sat Apr 30 12:12:07 2011 Child 11 will create 14732 files.
2157 Sat Apr 30 12:12:13 2011 Child 15 finished creating 482 directories.
2158 Sat Apr 30 12:12:13 2011 Child 15 will create 14732 files.
2159 Sat Apr 30 12:12:15 2011 Child 13 finished creating 482 directories.
2160 Sat Apr 30 12:12:15 2011 Child 13 will create 14732 files.
2161 Sat Apr 30 12:12:15 2011 Child 2 finished creating 482 directories.
2162 Sat Apr 30 12:12:15 2011 Child 2 will create 14732 files.
2163 Sat Apr 30 12:12:17 2011 Child 4 finished creating 482 directories.
2164 Sat Apr 30 12:12:17 2011 Child 4 will create 14732 files.
2165 Sat Apr 30 12:12:18 2011 Child 5 finished creating 482 directories.
2166 Sat Apr 30 12:12:18 2011 Child 5 will create 14732 files.
2167 Sat Apr 30 12:12:40 2011 Child 14 finished creating 482 directories.
2168 Sat Apr 30 12:12:40 2011 Child 14 will create 14732 files.
2169 Sat Apr 30 12:12:43 2011 Child 12 finished creating 482 directories.
2170 Sat Apr 30 12:12:43 2011 Child 12 will create 14732 files.
2171 Sat Apr 30 12:16:56 2011 Child 6 verified 14175 files (1214 creates).
2172 Sat Apr 30 12:17:04 2011 Child 1 verified 13755 files (794 creates).
2173 Sat Apr 30 12:17:14 2011 Child 9 verified 13761 files (800 creates).
2174 Sat Apr 30 12:17:14 2011 Child 10 verified 13629 files (668 creates).
2175 Sat Apr 30 12:17:17 2011 Child 13 verified 13376 files (415 creates).
2176 Sat Apr 30 12:17:17 2011 Child 2 verified 13523 files (562 creates).
2177 Sat Apr 30 12:17:22 2011 Child 4 verified 13481 files (520 creates).
2178 Sat Apr 30 12:17:37 2011 Child 3 verified 13903 files (942 creates).
2179 Sat Apr 30 12:17:37 2011 Child 5 verified 13469 files (508 creates).
2180 Sat Apr 30 12:17:37 2011 Child 11 verified 13629 files (668 creates).
2181 Sat Apr 30 12:17:37 2011 Child 8 verified 13812 files (851 creates).
2182 Sat Apr 30 12:17:37 2011 Child 15 verified 13369 files (408 creates).
2183 Sat Apr 30 12:17:37 2011 Child 7 verified 13376 files (415 creates).
2184 Sat Apr 30 12:17:37 2011 Child 0 verified 13629 files (668 creates).
2185 Sat Apr 30 12:17:59 2011 Child 12 verified 13376 files (415 creates).
2186 Sat Apr 30 12:18:33 2011 Child 14 verified 13383 files (422 creates).
2187 Sat Apr 30 12:19:07 2011 Child 6 verified 14732 files, created 1627
2188 Sat Apr 30 12:19:07 2011 Child 6 will create 2 symlinks.
2189 Sat Apr 30 12:19:07 2011 Child 6 finished creating 2 symlinks.
2190 Sat Apr 30 12:21:15 2011 Child 3 verified 14732 files, created 1627
2191 Sat Apr 30 12:21:15 2011 Child 3 will create 2 symlinks.
2192 Sat Apr 30 12:21:15 2011 Child 3 finished creating 2 symlinks.
2193 Sat Apr 30 12:22:11 2011 Child 1 verified 14346 files (1385 creates).
2194 Sat Apr 30 12:22:15 2011 Child 9 verified 14379 files (1418 creates).
2195 Sat Apr 30 12:22:32 2011 Child 2 verified 14277 files (1316 creates).
2196 Sat Apr 30 12:22:32 2011 Child 4 verified 14065 files (1104 creates).
2197 Sat Apr 30 12:22:32 2011 Child 10 verified 14346 files (1385 creates).
2198 Sat Apr 30 12:22:32 2011 Child 13 verified 14001 files (1040 creates).
2199 Sat Apr 30 12:22:34 2011 Child 15 verified 13837 files (876 creates).
2200 Sat Apr 30 12:22:34 2011 Child 8 verified 14369 files (1408 creates).
2201 Sat Apr 30 12:22:35 2011 Child 5 verified 14166 files (1205 creates).
2202 Sat Apr 30 12:22:36 2011 Child 0 verified 14443 files (1482 creates).
2203 Sat Apr 30 12:22:40 2011 Child 11 verified 14326 files (1365 creates).
2204 Sat Apr 30 12:22:40 2011 Child 7 verified 14028 files (1067 creates).
2205 Sat Apr 30 12:22:43 2011 Child 0 verified 14732 files, created 1627
2206 Sat Apr 30 12:22:43 2011 Child 0 will create 2 symlinks.
2207 Sat Apr 30 12:22:43 2011 Child 1 verified 14732 files, created 1627
2208 Sat Apr 30 12:22:43 2011 Child 1 will create 2 symlinks.
2209 Sat Apr 30 12:22:50 2011 Child 9 verified 14732 files, created 1627
2210 Sat Apr 30 12:22:50 2011 Child 9 will create 2 symlinks.
2211 Sat Apr 30 12:22:50 2011 Child 9 finished creating 2 symlinks.
2212 Sat Apr 30 12:22:52 2011 Child 8 verified 14732 files, created 1627
2213 Sat Apr 30 12:22:52 2011 Child 8 will create 2 symlinks.
2214 Sat Apr 30 12:22:52 2011 Child 8 finished creating 2 symlinks.
2215 Sat Apr 30 12:22:53 2011 Child 10 verified 14732 files, created 1627
2216 Sat Apr 30 12:22:53 2011 Child 10 will create 2 symlinks.
2217 Sat Apr 30 12:22:53 2011 Child 10 finished creating 2 symlinks.
2218 Sat Apr 30 12:22:56 2011 Child 0 finished creating 2 symlinks.
2219 Sat Apr 30 12:22:56 2011 Child 1 finished creating 2 symlinks.
2220 Sat Apr 30 12:23:00 2011 Child 12 verified 13973 files (1012 creates).
2221 Sat Apr 30 12:23:00 2011 Child 14 verified 14034 files (1073 creates).
2222 Sat Apr 30 12:23:05 2011 Child 11 verified 14732 files, created 1627
2223 Sat Apr 30 12:23:05 2011 Child 11 will create 2 symlinks.
2224 Sat Apr 30 12:23:05 2011 Child 2 verified 14732 files, created 1627
2225 Sat Apr 30 12:23:05 2011 Child 2 will create 2 symlinks.
2226 Sat Apr 30 12:23:06 2011 Child 2 finished creating 2 symlinks.
2227 Sat Apr 30 12:23:06 2011 Child 11 finished creating 2 symlinks.
2228 Sat Apr 30 12:23:30 2011 Child 5 verified 14732 files, created 1627
2229 Sat Apr 30 12:23:30 2011 Child 5 will create 2 symlinks.
2230 Sat Apr 30 12:23:30 2011 Child 5 finished creating 2 symlinks.
2231 Sat Apr 30 12:23:30 2011 Child 4 verified 14732 files, created 1627
2232 Sat Apr 30 12:23:30 2011 Child 4 will create 2 symlinks.
2233 Sat Apr 30 12:23:30 2011 Child 4 finished creating 2 symlinks.
2234 Sat Apr 30 12:23:45 2011 Child 7 verified 14732 files, created 1627
2235 Sat Apr 30 12:23:45 2011 Child 7 will create 2 symlinks.
2236 Sat Apr 30 12:23:45 2011 Child 7 finished creating 2 symlinks.
2237 Sat Apr 30 12:23:49 2011 Child 12 verified 14732 files, created 1627
2238 Sat Apr 30 12:23:49 2011 Child 12 will create 2 symlinks.
2239 Sat Apr 30 12:23:49 2011 Child 15 verified 14732 files, created 1627
2240 Sat Apr 30 12:23:49 2011 Child 15 will create 2 symlinks.
2241 Sat Apr 30 12:23:49 2011 Child 12 finished creating 2 symlinks.
2242 Sat Apr 30 12:23:49 2011 Child 15 finished creating 2 symlinks.
2243 Sat Apr 30 12:24:01 2011 Child 14 verified 14732 files, created 1627
2244 Sat Apr 30 12:24:01 2011 Child 14 will create 2 symlinks.
2245 Sat Apr 30 12:24:01 2011 Child 14 finished creating 2 symlinks.
2246 Sat Apr 30 12:24:12 2011 Child 13 verified 14732 files, created 1627
2247 Sat Apr 30 12:24:12 2011 Child 13 will create 2 symlinks.
2248 Sat Apr 30 12:24:12 2011 Child 13 finished creating 2 symlinks.
2249 Sat Apr 30 12:24:12 2011 Completed.
2250 Sat Apr 30 12:24:12 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2251 Sat Apr 30 12:24:12 2011 Completed.
2252 Sat Apr 30 12:24:12 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2253 Sat Apr 30 12:24:12 2011 Received.
2254 Sat Apr 30 12:24:12 2011 Performing 300 seconds pretest warmup.
2255 Sat Apr 30 12:29:12 2011 Completed.
2256 Sat Apr 30 12:29:12 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2257 Sat Apr 30 12:29:12 2011 Completed.
2258 Sat Apr 30 12:29:12 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2259 Sat Apr 30 12:29:13 2011 Received.
2260 Sat Apr 30 12:29:13 2011 Starting 300 seconds test run.
2261 SFS Single Client (c09-44) Results, Sat Apr 30 12:34:24 2011
2262 --------------------------------------------------------------------------------------
2263 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2264 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2265 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2266            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2267 --------------------------------------------------------------------------------------
2268 getattr     26.0%    26.2%    10375    10375      0     15.79   381.87      0.38  2.9%
2269 setattr      4.0%     4.1%     1634     1634      0    467.58   629.22      1.22 13.7%
2270 lookup      24.0%    23.9%     9435     9435      0     17.40   412.63      0.41  2.9%
2271 readlink     1.0%     1.0%      391      391      0      8.53    22.53      0.47  0.1%
2272 read        18.0%    18.1%     7173     8791      0    342.32  2106.35      1.06 44.0%
2273 write       10.0%     9.6%     3810     4539      0    182.67   553.37      0.75 12.5%
2274 create       1.0%     0.9%      373      373      0    790.55   521.03      2.32  5.3%
2275 remove       1.0%     1.1%      418      418      0    522.96   816.21      2.74  3.9%
2276 readdir      1.0%     1.0%      377      377      0     17.89    74.21      0.87  0.1%
2277 fsstat       1.0%     1.0%      404      404      0     11.92    32.57      0.56  0.1%
2278 access      11.0%    11.1%     4376     4376      0     30.70   836.04      0.86  2.4%
2279 commit       0.0%     0.0%        0     1319      0      0.00     0.00      0.00 11.9%
2280 readdirplus  2.0%     1.9%      760      760      0     10.59    31.22      0.40  0.1%
2281 --------------------------------------------------------------------------------------
2282
2283       --------------------------------------------------
2284       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2285       --------------------------+-----------------------
2286 SFS NFS V3 THROUGHPUT:  134.90 Ops/Sec   AVG. RESPONSE TIME:  141.08 Msec/Op
2287 TCP PROTOCOL
2288 FAST CALLS: 0
2289 NFS V3 MIXFILE: [ SFS Default ]
2290 CLIENT REQUESTED LOAD: 1000 Ops/Sec
2291 TOTAL LOGICAL NFS V3 OPERATIONS:  39526          TEST TIME: 293 Sec
2292 TOTAL PHYSICAL NFS V3 OPERATIONS: 43192
2293 PHYSICAL NFS V3 THROUGHPUT:  147.41 Ops/Sec
2294 FILE SET SIZE CREATED: 122707728.0 KB
2295 FILE SET SIZE ACCESSED: 36813168.0 - 36900700.0 KB  (100.00% to 100.24% of Base)
2296
2297 ------------------------------------------------------------------------
2298
2299 Sat Apr 30 12:34:24 2011 Completed.
2300
2301 Client Test Parameters:
2302         Number of processes = 16
2303         Requested Load (NFS V3 operations/second) = 1000
2304         Maximum number of outstanding biod writes = 8
2305         Maximum number of outstanding biod reads = 8
2306         Warm-up time (seconds) = 300
2307         Run time (seconds) = 300
2308         File Set = 231088 Files created for I/O operations
2309                    69312 Files accessed for I/O operations
2310                    4624 Files for non-I/O operations
2311                      32 Symlinks
2312                    7696 Directories
2313                         Additional non-I/O files created as necessary
2314
2315
2316 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2317 Sat Apr 30 12:34:24 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2318 Sat Apr 30 12:34:24 2011 Completed.
2319 Sat Apr 30 12:34:24 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2320
2321 ************************************************************************
2322 Sat Apr 30 12:34:24 2011 Received.
2323 Sat Apr 30 12:34:24 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2324 Sat Apr 30 12:34:24 2011 Completed.
2325 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
2326 NFS V3 Protocol Version
2327
2328 ************************************************************************
2329 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2330 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2331
2332 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2333 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2334
2335 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 12:34:26 2011
2336         Client hostname = c09-44
2337         Prime Client hostname = c09-44.sysnet.ucsd.edu
2338
2339 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2340 NFS V3 Protocol
2341 Sat Apr 30 12:34:26 2011 Mounting 16 remote test directories.
2342 Child[000]: NFS negotiated block size 32768
2343 Child[001]: NFS negotiated block size 32768
2344 Child[002]: NFS negotiated block size 32768
2345 Child[003]: NFS negotiated block size 32768
2346 Child[004]: NFS negotiated block size 32768
2347 Child[005]: NFS negotiated block size 32768
2348 Child[006]: NFS negotiated block size 32768
2349 Child[007]: NFS negotiated block size 32768
2350 Child[008]: NFS negotiated block size 32768
2351 Child[009]: NFS negotiated block size 32768
2352 Child[010]: NFS negotiated block size 32768
2353 Child[011]: NFS negotiated block size 32768
2354 Child[012]: NFS negotiated block size 32768
2355 Child[013]: NFS negotiated block size 32768
2356 Child[014]: NFS negotiated block size 32768
2357 Child[015]: NFS negotiated block size 32768
2358 Sat Apr 30 12:34:40 2011 Completed.
2359 Sat Apr 30 12:34:40 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2360 Sat Apr 30 12:34:40 2011 Completed.
2361 Sat Apr 30 12:34:40 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2362 Sat Apr 30 12:34:42 2011 Received.
2363
2364 Sat Apr 30 12:34:42 2011 Initializing test directories.
2365 Sat Apr 30 12:34:42 2011 Child 14 will create 527 directories.
2366 Sat Apr 30 12:34:42 2011 Child 13 will create 527 directories.
2367 Sat Apr 30 12:34:42 2011 Child 0 will create 527 directories.
2368 Sat Apr 30 12:34:42 2011 Child 10 will create 527 directories.
2369 Sat Apr 30 12:34:42 2011 Child 2 will create 527 directories.
2370 Sat Apr 30 12:34:42 2011 Child 1 will create 527 directories.
2371 Sat Apr 30 12:34:42 2011 Child 12 will create 527 directories.
2372 Sat Apr 30 12:34:42 2011 Child 4 will create 527 directories.
2373 Sat Apr 30 12:34:42 2011 Child 5 will create 527 directories.
2374 Sat Apr 30 12:34:42 2011 Child 9 will create 527 directories.
2375 Sat Apr 30 12:34:42 2011 Child 11 will create 527 directories.
2376 Sat Apr 30 12:34:42 2011 Child 3 will create 527 directories.
2377 Sat Apr 30 12:34:42 2011 Child 15 will create 527 directories.
2378 Sat Apr 30 12:34:42 2011 Child 6 will create 527 directories.
2379 Sat Apr 30 12:34:42 2011 Child 8 will create 527 directories.
2380 Sat Apr 30 12:34:42 2011 Child 7 will create 527 directories.
2381 Sat Apr 30 12:34:47 2011 Child 4 finished creating 527 directories.
2382 Sat Apr 30 12:34:47 2011 Child 4 will create 16118 files.
2383 Sat Apr 30 12:34:48 2011 Child 14 finished creating 527 directories.
2384 Sat Apr 30 12:34:48 2011 Child 14 will create 16118 files.
2385 Sat Apr 30 12:34:51 2011 Child 2 finished creating 527 directories.
2386 Sat Apr 30 12:34:51 2011 Child 2 will create 16118 files.
2387 Sat Apr 30 12:34:55 2011 Child 1 finished creating 527 directories.
2388 Sat Apr 30 12:34:55 2011 Child 1 will create 16118 files.
2389 Sat Apr 30 12:34:56 2011 Child 6 finished creating 527 directories.
2390 Sat Apr 30 12:34:56 2011 Child 6 will create 16118 files.
2391 Sat Apr 30 12:34:57 2011 Child 7 finished creating 527 directories.
2392 Sat Apr 30 12:34:57 2011 Child 7 will create 16118 files.
2393 Sat Apr 30 12:35:01 2011 Child 8 finished creating 527 directories.
2394 Sat Apr 30 12:35:01 2011 Child 8 will create 16118 files.
2395 Sat Apr 30 12:35:09 2011 Child 12 finished creating 527 directories.
2396 Sat Apr 30 12:35:09 2011 Child 12 will create 16118 files.
2397 Sat Apr 30 12:35:10 2011 Child 15 finished creating 527 directories.
2398 Sat Apr 30 12:35:10 2011 Child 15 will create 16118 files.
2399 Sat Apr 30 12:35:13 2011 Child 5 finished creating 527 directories.
2400 Sat Apr 30 12:35:13 2011 Child 5 will create 16118 files.
2401 Sat Apr 30 12:35:14 2011 Child 11 finished creating 527 directories.
2402 Sat Apr 30 12:35:14 2011 Child 11 will create 16118 files.
2403 Sat Apr 30 12:35:14 2011 Child 13 finished creating 527 directories.
2404 Sat Apr 30 12:35:14 2011 Child 13 will create 16118 files.
2405 Sat Apr 30 12:35:15 2011 Child 9 finished creating 527 directories.
2406 Sat Apr 30 12:35:15 2011 Child 9 will create 16118 files.
2407 Sat Apr 30 12:35:24 2011 Child 0 finished creating 527 directories.
2408 Sat Apr 30 12:35:24 2011 Child 0 will create 16118 files.
2409 Sat Apr 30 12:35:29 2011 Child 3 finished creating 527 directories.
2410 Sat Apr 30 12:35:29 2011 Child 3 will create 16118 files.
2411 Sat Apr 30 12:35:34 2011 Child 10 finished creating 527 directories.
2412 Sat Apr 30 12:35:34 2011 Child 10 will create 16118 files.
2413 Sat Apr 30 12:38:57 2011 Child 14 verified 16118 files, created 1517
2414 Sat Apr 30 12:38:57 2011 Child 14 will create 2 symlinks.
2415 Sat Apr 30 12:38:57 2011 Child 4 verified 16118 files, created 1517
2416 Sat Apr 30 12:38:57 2011 Child 4 will create 2 symlinks.
2417 Sat Apr 30 12:38:57 2011 Child 14 finished creating 2 symlinks.
2418 Sat Apr 30 12:38:57 2011 Child 4 finished creating 2 symlinks.
2419 Sat Apr 30 12:39:52 2011 Child 2 verified 15633 files (1190 creates).
2420 Sat Apr 30 12:39:56 2011 Child 1 verified 15450 files (1007 creates).
2421 Sat Apr 30 12:39:57 2011 Child 6 verified 15518 files (1075 creates).
2422 Sat Apr 30 12:39:58 2011 Child 7 verified 15466 files (1023 creates).
2423 Sat Apr 30 12:40:03 2011 Child 8 verified 15279 files (836 creates).
2424 Sat Apr 30 12:40:10 2011 Child 12 verified 15279 files (836 creates).
2425 Sat Apr 30 12:40:11 2011 Child 15 verified 15256 files (813 creates).
2426 Sat Apr 30 12:40:15 2011 Child 11 verified 15182 files (739 creates).
2427 Sat Apr 30 12:40:15 2011 Child 13 verified 15280 files (837 creates).
2428 Sat Apr 30 12:40:15 2011 Child 5 verified 15225 files (782 creates).
2429 Sat Apr 30 12:40:18 2011 Child 9 verified 15181 files (738 creates).
2430 Sat Apr 30 12:40:25 2011 Child 0 verified 15197 files (754 creates).
2431 Sat Apr 30 12:40:30 2011 Child 3 verified 15170 files (727 creates).
2432 Sat Apr 30 12:40:38 2011 Child 10 verified 15234 files (791 creates).
2433 Sat Apr 30 12:40:44 2011 Child 2 verified 16118 files, created 1517
2434 Sat Apr 30 12:40:44 2011 Child 2 will create 2 symlinks.
2435 Sat Apr 30 12:40:44 2011 Child 2 finished creating 2 symlinks.
2436 Sat Apr 30 12:40:50 2011 Child 6 verified 16118 files, created 1517
2437 Sat Apr 30 12:40:50 2011 Child 6 will create 2 symlinks.
2438 Sat Apr 30 12:41:00 2011 Child 6 finished creating 2 symlinks.
2439 Sat Apr 30 12:41:14 2011 Child 7 verified 16118 files, created 1517
2440 Sat Apr 30 12:41:14 2011 Child 7 will create 2 symlinks.
2441 Sat Apr 30 12:41:14 2011 Child 7 finished creating 2 symlinks.
2442 Sat Apr 30 12:41:15 2011 Child 1 verified 16118 files, created 1517
2443 Sat Apr 30 12:41:15 2011 Child 1 will create 2 symlinks.
2444 Sat Apr 30 12:41:15 2011 Child 1 finished creating 2 symlinks.
2445 Sat Apr 30 12:41:23 2011 Child 8 verified 16118 files, created 1517
2446 Sat Apr 30 12:41:23 2011 Child 8 will create 2 symlinks.
2447 Sat Apr 30 12:41:23 2011 Child 8 finished creating 2 symlinks.
2448 Sat Apr 30 12:41:26 2011 Child 12 verified 16118 files, created 1517
2449 Sat Apr 30 12:41:26 2011 Child 12 will create 2 symlinks.
2450 Sat Apr 30 12:41:29 2011 Child 12 finished creating 2 symlinks.
2451 Sat Apr 30 12:41:49 2011 Child 11 verified 16118 files, created 1517
2452 Sat Apr 30 12:41:49 2011 Child 11 will create 2 symlinks.
2453 Sat Apr 30 12:41:49 2011 Child 11 finished creating 2 symlinks.
2454 Sat Apr 30 12:41:49 2011 Child 15 verified 16118 files, created 1517
2455 Sat Apr 30 12:41:49 2011 Child 15 will create 2 symlinks.
2456 Sat Apr 30 12:41:49 2011 Child 15 finished creating 2 symlinks.
2457 Sat Apr 30 12:41:49 2011 Child 13 verified 16118 files, created 1517
2458 Sat Apr 30 12:41:49 2011 Child 13 will create 2 symlinks.
2459 Sat Apr 30 12:41:49 2011 Child 13 finished creating 2 symlinks.
2460 Sat Apr 30 12:41:51 2011 Child 0 verified 16118 files, created 1517
2461 Sat Apr 30 12:41:51 2011 Child 0 will create 2 symlinks.
2462 Sat Apr 30 12:41:51 2011 Child 0 finished creating 2 symlinks.
2463 Sat Apr 30 12:41:51 2011 Child 9 verified 16118 files, created 1517
2464 Sat Apr 30 12:41:51 2011 Child 9 will create 2 symlinks.
2465 Sat Apr 30 12:41:51 2011 Child 9 finished creating 2 symlinks.
2466 Sat Apr 30 12:41:51 2011 Child 5 verified 16118 files, created 1517
2467 Sat Apr 30 12:41:51 2011 Child 5 will create 2 symlinks.
2468 Sat Apr 30 12:41:52 2011 Child 5 finished creating 2 symlinks.
2469 Sat Apr 30 12:41:53 2011 Child 10 verified 16118 files, created 1517
2470 Sat Apr 30 12:41:53 2011 Child 10 will create 2 symlinks.
2471 Sat Apr 30 12:41:53 2011 Child 10 finished creating 2 symlinks.
2472 Sat Apr 30 12:41:53 2011 Child 3 verified 16118 files, created 1517
2473 Sat Apr 30 12:41:53 2011 Child 3 will create 2 symlinks.
2474 Sat Apr 30 12:41:53 2011 Child 3 finished creating 2 symlinks.
2475 Sat Apr 30 12:41:54 2011 Completed.
2476 Sat Apr 30 12:41:54 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2477 Sat Apr 30 12:41:54 2011 Completed.
2478 Sat Apr 30 12:41:54 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2479 Sat Apr 30 12:41:54 2011 Received.
2480 Sat Apr 30 12:41:54 2011 Performing 300 seconds pretest warmup.
2481 Sat Apr 30 12:46:54 2011 Completed.
2482 Sat Apr 30 12:46:54 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2483 Sat Apr 30 12:46:54 2011 Completed.
2484 Sat Apr 30 12:46:54 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2485 Sat Apr 30 12:46:55 2011 Received.
2486 Sat Apr 30 12:46:55 2011 Starting 300 seconds test run.
2487 SFS Single Client (c09-44) Results, Sat Apr 30 12:52:06 2011
2488 --------------------------------------------------------------------------------------
2489 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2490 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2491 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2492            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2493 --------------------------------------------------------------------------------------
2494 getattr     26.0%    26.3%    12283    12283      0     13.38    65.79      0.14  3.0%
2495 setattr      4.0%     4.1%     1919     1919      0    455.70   671.65      1.16 15.7%
2496 lookup      24.0%    24.0%    11203    11203      0     13.61    60.63      0.14  2.7%
2497 readlink     1.0%     1.0%      448      448      0     13.36    34.68      0.55  0.1%
2498 read        18.0%    17.7%     8272    10116      0    246.55   815.38      0.62 36.7%
2499 write       10.0%    10.0%     4667     5681      0    183.64   414.58      0.58 15.4%
2500 create       1.0%     1.0%      450      450      0    871.77   969.42      2.88  7.1%
2501 remove       1.0%     1.0%      471      471      0    489.10   640.35      2.28  4.1%
2502 readdir      1.0%     0.9%      444      444      0     12.01    33.04      0.53  0.1%
2503 fsstat       1.0%     1.0%      451      451      0     13.80    37.31      0.56  0.1%
2504 access      11.0%    11.2%     5223     5223      0     12.68    40.10      0.17  1.2%
2505 commit       0.0%     0.0%        0     1603      0      0.00     0.00      0.00 13.6%
2506 readdirplus  2.0%     2.0%      941      941      0     11.71    33.80      0.37  0.2%
2507 --------------------------------------------------------------------------------------
2508
2509       --------------------------------------------------
2510       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2511       --------------------------+-----------------------
2512 SFS NFS V3 THROUGHPUT:  156.42 Ops/Sec   AVG. RESPONSE TIME:  118.94 Msec/Op
2513 TCP PROTOCOL
2514 FAST CALLS: 0
2515 NFS V3 MIXFILE: [ SFS Default ]
2516 CLIENT REQUESTED LOAD: 1100 Ops/Sec
2517 TOTAL LOGICAL NFS V3 OPERATIONS:  46772          TEST TIME: 299 Sec
2518 TOTAL PHYSICAL NFS V3 OPERATIONS: 51233
2519 PHYSICAL NFS V3 THROUGHPUT:  171.34 Ops/Sec
2520 FILE SET SIZE CREATED: 134245296.0 KB
2521 FILE SET SIZE ACCESSED: 40279536.0 - 40374440.0 KB  (100.00% to 100.24% of Base)
2522
2523 ------------------------------------------------------------------------
2524
2525 Sat Apr 30 12:52:06 2011 Completed.
2526
2527 Client Test Parameters:
2528         Number of processes = 16
2529         Requested Load (NFS V3 operations/second) = 1100
2530         Maximum number of outstanding biod writes = 8
2531         Maximum number of outstanding biod reads = 8
2532         Warm-up time (seconds) = 300
2533         Run time (seconds) = 300
2534         File Set = 252816 Files created for I/O operations
2535                    75840 Files accessed for I/O operations
2536                    5072 Files for non-I/O operations
2537                      32 Symlinks
2538                    8416 Directories
2539                         Additional non-I/O files created as necessary
2540
2541
2542 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2543 Sat Apr 30 12:52:06 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2544 Sat Apr 30 12:52:06 2011 Completed.
2545 Sat Apr 30 12:52:06 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2546
2547 ************************************************************************
2548 Sat Apr 30 12:52:06 2011 Received.
2549 Sat Apr 30 12:52:06 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2550 Sat Apr 30 12:52:06 2011 Completed.
2551 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
2552 NFS V3 Protocol Version
2553
2554 ************************************************************************
2555 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2556 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2557
2558 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2559 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2560
2561 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 12:52:08 2011
2562         Client hostname = c09-44
2563         Prime Client hostname = c09-44.sysnet.ucsd.edu
2564
2565 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2566 NFS V3 Protocol
2567 Sat Apr 30 12:52:08 2011 Mounting 16 remote test directories.
2568 Child[000]: NFS negotiated block size 32768
2569 Child[001]: NFS negotiated block size 32768
2570 Child[002]: NFS negotiated block size 32768
2571 Child[003]: NFS negotiated block size 32768
2572 Child[004]: NFS negotiated block size 32768
2573 Child[005]: NFS negotiated block size 32768
2574 Child[006]: NFS negotiated block size 32768
2575 Child[007]: NFS negotiated block size 32768
2576 Child[008]: NFS negotiated block size 32768
2577 Child[009]: NFS negotiated block size 32768
2578 Child[010]: NFS negotiated block size 32768
2579 Child[011]: NFS negotiated block size 32768
2580 Child[012]: NFS negotiated block size 32768
2581 Child[013]: NFS negotiated block size 32768
2582 Child[014]: NFS negotiated block size 32768
2583 Child[015]: NFS negotiated block size 32768
2584 Sat Apr 30 12:52:22 2011 Completed.
2585 Sat Apr 30 12:52:22 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2586 Sat Apr 30 12:52:22 2011 Completed.
2587 Sat Apr 30 12:52:22 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2588 Sat Apr 30 12:52:24 2011 Received.
2589
2590 Sat Apr 30 12:52:24 2011 Initializing test directories.
2591 Sat Apr 30 12:52:24 2011 Child 10 will create 577 directories.
2592 Sat Apr 30 12:52:24 2011 Child 5 will create 577 directories.
2593 Sat Apr 30 12:52:24 2011 Child 4 will create 577 directories.
2594 Sat Apr 30 12:52:24 2011 Child 0 will create 577 directories.
2595 Sat Apr 30 12:52:24 2011 Child 9 will create 577 directories.
2596 Sat Apr 30 12:52:24 2011 Child 1 will create 577 directories.
2597 Sat Apr 30 12:52:24 2011 Child 2 will create 577 directories.
2598 Sat Apr 30 12:52:24 2011 Child 7 will create 577 directories.
2599 Sat Apr 30 12:52:24 2011 Child 8 will create 577 directories.
2600 Sat Apr 30 12:52:24 2011 Child 13 will create 577 directories.
2601 Sat Apr 30 12:52:24 2011 Child 3 will create 577 directories.
2602 Sat Apr 30 12:52:24 2011 Child 11 will create 577 directories.
2603 Sat Apr 30 12:52:24 2011 Child 14 will create 577 directories.
2604 Sat Apr 30 12:52:24 2011 Child 6 will create 577 directories.
2605 Sat Apr 30 12:52:24 2011 Child 12 will create 577 directories.
2606 Sat Apr 30 12:52:24 2011 Child 15 will create 577 directories.
2607 Sat Apr 30 12:52:25 2011 Child 6 finished creating 577 directories.
2608 Sat Apr 30 12:52:25 2011 Child 6 will create 17628 files.
2609 Sat Apr 30 12:52:29 2011 Child 9 finished creating 577 directories.
2610 Sat Apr 30 12:52:29 2011 Child 9 will create 17628 files.
2611 Sat Apr 30 12:52:31 2011 Child 10 finished creating 577 directories.
2612 Sat Apr 30 12:52:31 2011 Child 10 will create 17628 files.
2613 Sat Apr 30 12:52:34 2011 Child 13 finished creating 577 directories.
2614 Sat Apr 30 12:52:34 2011 Child 13 will create 17628 files.
2615 Sat Apr 30 12:52:35 2011 Child 0 finished creating 577 directories.
2616 Sat Apr 30 12:52:35 2011 Child 0 will create 17628 files.
2617 Sat Apr 30 12:52:37 2011 Child 14 finished creating 577 directories.
2618 Sat Apr 30 12:52:37 2011 Child 14 will create 17628 files.
2619 Sat Apr 30 12:52:40 2011 Child 1 finished creating 577 directories.
2620 Sat Apr 30 12:52:40 2011 Child 1 will create 17628 files.
2621 Sat Apr 30 12:52:48 2011 Child 3 finished creating 577 directories.
2622 Sat Apr 30 12:52:48 2011 Child 3 will create 17628 files.
2623 Sat Apr 30 12:52:52 2011 Child 4 finished creating 577 directories.
2624 Sat Apr 30 12:52:52 2011 Child 4 will create 17628 files.
2625 Sat Apr 30 12:52:54 2011 Child 7 finished creating 577 directories.
2626 Sat Apr 30 12:52:54 2011 Child 7 will create 17628 files.
2627 Sat Apr 30 12:52:57 2011 Child 8 finished creating 577 directories.
2628 Sat Apr 30 12:52:57 2011 Child 8 will create 17628 files.
2629 Sat Apr 30 12:53:00 2011 Child 11 finished creating 577 directories.
2630 Sat Apr 30 12:53:00 2011 Child 11 will create 17628 files.
2631 Sat Apr 30 12:53:01 2011 Child 2 finished creating 577 directories.
2632 Sat Apr 30 12:53:01 2011 Child 2 will create 17628 files.
2633 Sat Apr 30 12:53:02 2011 Child 12 finished creating 577 directories.
2634 Sat Apr 30 12:53:02 2011 Child 12 will create 17628 files.
2635 Sat Apr 30 12:53:08 2011 Child 15 finished creating 577 directories.
2636 Sat Apr 30 12:53:08 2011 Child 15 will create 17628 files.
2637 Sat Apr 30 12:53:19 2011 Child 5 finished creating 577 directories.
2638 Sat Apr 30 12:53:19 2011 Child 5 will create 17628 files.
2639 Sat Apr 30 12:56:02 2011 Child 6 verified 17628 files, created 1655
2640 Sat Apr 30 12:56:02 2011 Child 6 will create 2 symlinks.
2641 Sat Apr 30 12:56:02 2011 Child 6 finished creating 2 symlinks.
2642 Sat Apr 30 12:56:16 2011 Child 9 verified 17628 files, created 1655
2643 Sat Apr 30 12:56:16 2011 Child 9 will create 2 symlinks.
2644 Sat Apr 30 12:56:25 2011 Child 9 finished creating 2 symlinks.
2645 Sat Apr 30 12:56:59 2011 Child 10 verified 17628 files, created 1655
2646 Sat Apr 30 12:56:59 2011 Child 10 will create 2 symlinks.
2647 Sat Apr 30 12:56:59 2011 Child 10 finished creating 2 symlinks.
2648 Sat Apr 30 12:56:59 2011 Child 0 verified 17628 files, created 1655
2649 Sat Apr 30 12:56:59 2011 Child 0 will create 2 symlinks.
2650 Sat Apr 30 12:56:59 2011 Child 0 finished creating 2 symlinks.
2651 Sat Apr 30 12:57:03 2011 Child 14 verified 17628 files, created 1655
2652 Sat Apr 30 12:57:03 2011 Child 14 will create 2 symlinks.
2653 Sat Apr 30 12:57:03 2011 Child 13 verified 17628 files, created 1655
2654 Sat Apr 30 12:57:03 2011 Child 13 will create 2 symlinks.
2655 Sat Apr 30 12:57:10 2011 Child 13 finished creating 2 symlinks.
2656 Sat Apr 30 12:57:10 2011 Child 14 finished creating 2 symlinks.
2657 Sat Apr 30 12:57:32 2011 Child 1 verified 17628 files, created 1655
2658 Sat Apr 30 12:57:32 2011 Child 1 will create 2 symlinks.
2659 Sat Apr 30 12:57:32 2011 Child 1 finished creating 2 symlinks.
2660 Sat Apr 30 12:57:39 2011 Child 4 verified 17628 files, created 1655
2661 Sat Apr 30 12:57:39 2011 Child 4 will create 2 symlinks.
2662 Sat Apr 30 12:57:39 2011 Child 4 finished creating 2 symlinks.
2663 Sat Apr 30 12:57:43 2011 Child 7 verified 17628 files, created 1655
2664 Sat Apr 30 12:57:43 2011 Child 7 will create 2 symlinks.
2665 Sat Apr 30 12:57:43 2011 Child 7 finished creating 2 symlinks.
2666 Sat Apr 30 12:57:51 2011 Child 15 verified 17628 files, created 1655
2667 Sat Apr 30 12:57:51 2011 Child 15 will create 2 symlinks.
2668 Sat Apr 30 12:57:51 2011 Child 15 finished creating 2 symlinks.
2669 Sat Apr 30 12:57:51 2011 Child 11 verified 17628 files, created 1655
2670 Sat Apr 30 12:57:51 2011 Child 11 will create 2 symlinks.
2671 Sat Apr 30 12:57:51 2011 Child 11 finished creating 2 symlinks.
2672 Sat Apr 30 12:57:52 2011 Child 5 verified 17628 files, created 1655
2673 Sat Apr 30 12:57:52 2011 Child 5 will create 2 symlinks.
2674 Sat Apr 30 12:57:52 2011 Child 5 finished creating 2 symlinks.
2675 Sat Apr 30 12:57:53 2011 Child 3 verified 17628 files, created 1655
2676 Sat Apr 30 12:57:53 2011 Child 3 will create 2 symlinks.
2677 Sat Apr 30 12:57:53 2011 Child 3 finished creating 2 symlinks.
2678 Sat Apr 30 12:57:54 2011 Child 2 verified 17628 files, created 1655
2679 Sat Apr 30 12:57:54 2011 Child 2 will create 2 symlinks.
2680 Sat Apr 30 12:57:54 2011 Child 2 finished creating 2 symlinks.
2681 Sat Apr 30 12:57:54 2011 Child 8 verified 17628 files, created 1655
2682 Sat Apr 30 12:57:54 2011 Child 8 will create 2 symlinks.
2683 Sat Apr 30 12:57:54 2011 Child 8 finished creating 2 symlinks.
2684 Sat Apr 30 12:57:56 2011 Child 12 verified 17628 files, created 1655
2685 Sat Apr 30 12:57:56 2011 Child 12 will create 2 symlinks.
2686 Sat Apr 30 12:57:56 2011 Child 12 finished creating 2 symlinks.
2687 Sat Apr 30 12:57:57 2011 Completed.
2688 Sat Apr 30 12:57:57 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2689 Sat Apr 30 12:57:57 2011 Completed.
2690 Sat Apr 30 12:57:57 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2691 Sat Apr 30 12:57:57 2011 Received.
2692 Sat Apr 30 12:57:57 2011 Performing 300 seconds pretest warmup.
2693 Sat Apr 30 13:02:57 2011 Completed.
2694 Sat Apr 30 13:02:57 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2695 Sat Apr 30 13:02:57 2011 Completed.
2696 Sat Apr 30 13:02:57 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2697 Sat Apr 30 13:02:58 2011 Received.
2698 Sat Apr 30 13:02:58 2011 Starting 300 seconds test run.
2699 SFS Single Client (c09-44) Results, Sat Apr 30 13:08:09 2011
2700 --------------------------------------------------------------------------------------
2701 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2702 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2703 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2704            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2705 --------------------------------------------------------------------------------------
2706 getattr     26.0%    26.0%    11297    11297      0     13.18   205.16      0.26  2.6%
2707 setattr      4.0%     4.1%     1781     1781      0    465.52   721.59      1.25 14.7%
2708 lookup      24.0%    23.9%    10377    10377      0     13.73   239.90      0.30  2.5%
2709 readlink     1.0%     1.1%      457      457      0     10.86    33.73      0.53  0.1%
2710 read        18.0%    17.8%     7729     9579      0    264.34   835.52      0.64 36.2%
2711 write       10.0%    10.0%     4338     5353      0    217.47   840.28      0.86 16.7%
2712 create       1.0%     1.0%      437      437      0    983.82  1451.17      3.57  7.6%
2713 remove       1.0%     1.0%      445      445      0    490.22   725.45      2.50  3.9%
2714 readdir      1.0%     1.0%      428      428      0     10.01    30.86      0.53  0.1%
2715 fsstat       1.0%     1.0%      426      426      0     10.24    28.28      0.50  0.1%
2716 access      11.0%    11.1%     4840     4840      0     10.46    30.95      0.16  0.9%
2717 commit       0.0%     0.0%        0     1488      0      0.00     0.00      0.00 14.4%
2718 readdirplus  2.0%     2.1%      894      894      0     10.58    32.34      0.37  0.2%
2719 --------------------------------------------------------------------------------------
2720
2721       --------------------------------------------------
2722       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2723       --------------------------+-----------------------
2724 SFS NFS V3 THROUGHPUT:  145.31 Ops/Sec   AVG. RESPONSE TIME:  129.88 Msec/Op
2725 TCP PROTOCOL
2726 FAST CALLS: 0
2727 NFS V3 MIXFILE: [ SFS Default ]
2728 CLIENT REQUESTED LOAD: 1200 Ops/Sec
2729 TOTAL LOGICAL NFS V3 OPERATIONS:  43449          TEST TIME: 299 Sec
2730 TOTAL PHYSICAL NFS V3 OPERATIONS: 47802
2731 PHYSICAL NFS V3 THROUGHPUT:  159.87 Ops/Sec
2732 FILE SET SIZE CREATED: 146827872.0 KB
2733 FILE SET SIZE ACCESSED: 44051760.0 - 44145620.0 KB  (100.00% to 100.21% of Base)
2734
2735 ------------------------------------------------------------------------
2736
2737 Sat Apr 30 13:08:09 2011 Completed.
2738
2739 Client Test Parameters:
2740         Number of processes = 16
2741         Requested Load (NFS V3 operations/second) = 1200
2742         Maximum number of outstanding biod writes = 8
2743         Maximum number of outstanding biod reads = 8
2744         Warm-up time (seconds) = 300
2745         Run time (seconds) = 300
2746         File Set = 276512 Files created for I/O operations
2747                    82944 Files accessed for I/O operations
2748                    5536 Files for non-I/O operations
2749                      32 Symlinks
2750                    9216 Directories
2751                         Additional non-I/O files created as necessary
2752
2753
2754 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2755 Sat Apr 30 13:08:09 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2756 Sat Apr 30 13:08:09 2011 Completed.
2757 Sat Apr 30 13:08:09 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2758
2759 ************************************************************************
2760 Sat Apr 30 13:08:09 2011 Received.
2761 Sat Apr 30 13:08:09 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2762 Sat Apr 30 13:08:09 2011 Completed.
2763 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
2764 NFS V3 Protocol Version
2765
2766 ************************************************************************
2767 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2768 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2769
2770 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2771 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2772
2773 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 13:08:11 2011
2774         Client hostname = c09-44
2775         Prime Client hostname = c09-44.sysnet.ucsd.edu
2776
2777 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2778 NFS V3 Protocol
2779 Sat Apr 30 13:08:11 2011 Mounting 16 remote test directories.
2780 Child[000]: NFS negotiated block size 32768
2781 Child[001]: NFS negotiated block size 32768
2782 Child[002]: NFS negotiated block size 32768
2783 Child[003]: NFS negotiated block size 32768
2784 Child[004]: NFS negotiated block size 32768
2785 Child[005]: NFS negotiated block size 32768
2786 Child[006]: NFS negotiated block size 32768
2787 Child[007]: NFS negotiated block size 32768
2788 Child[008]: NFS negotiated block size 32768
2789 Child[009]: NFS negotiated block size 32768
2790 Child[010]: NFS negotiated block size 32768
2791 Child[011]: NFS negotiated block size 32768
2792 Child[012]: NFS negotiated block size 32768
2793 Child[013]: NFS negotiated block size 32768
2794 Child[014]: NFS negotiated block size 32768
2795 Child[015]: NFS negotiated block size 32768
2796 Sat Apr 30 13:08:25 2011 Completed.
2797 Sat Apr 30 13:08:25 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
2798 Sat Apr 30 13:08:25 2011 Completed.
2799 Sat Apr 30 13:08:25 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
2800 Sat Apr 30 13:08:27 2011 Received.
2801
2802 Sat Apr 30 13:08:27 2011 Initializing test directories.
2803 Sat Apr 30 13:08:27 2011 Child 14 will create 626 directories.
2804 Sat Apr 30 13:08:27 2011 Child 15 will create 626 directories.
2805 Sat Apr 30 13:08:27 2011 Child 13 will create 626 directories.
2806 Sat Apr 30 13:08:27 2011 Child 1 will create 626 directories.
2807 Sat Apr 30 13:08:27 2011 Child 7 will create 626 directories.
2808 Sat Apr 30 13:08:27 2011 Child 0 will create 626 directories.
2809 Sat Apr 30 13:08:27 2011 Child 8 will create 626 directories.
2810 Sat Apr 30 13:08:27 2011 Child 3 will create 626 directories.
2811 Sat Apr 30 13:08:27 2011 Child 6 will create 626 directories.
2812 Sat Apr 30 13:08:27 2011 Child 5 will create 626 directories.
2813 Sat Apr 30 13:08:27 2011 Child 9 will create 626 directories.
2814 Sat Apr 30 13:08:27 2011 Child 11 will create 626 directories.
2815 Sat Apr 30 13:08:27 2011 Child 4 will create 626 directories.
2816 Sat Apr 30 13:08:27 2011 Child 2 will create 626 directories.
2817 Sat Apr 30 13:08:27 2011 Child 10 will create 626 directories.
2818 Sat Apr 30 13:08:27 2011 Child 12 will create 626 directories.
2819 Sat Apr 30 13:08:29 2011 Child 12 finished creating 626 directories.
2820 Sat Apr 30 13:08:29 2011 Child 12 will create 19139 files.
2821 Sat Apr 30 13:08:30 2011 Child 5 finished creating 626 directories.
2822 Sat Apr 30 13:08:30 2011 Child 5 will create 19139 files.
2823 Sat Apr 30 13:08:33 2011 Child 13 finished creating 626 directories.
2824 Sat Apr 30 13:08:33 2011 Child 13 will create 19139 files.
2825 Sat Apr 30 13:08:35 2011 Child 7 finished creating 626 directories.
2826 Sat Apr 30 13:08:35 2011 Child 7 will create 19139 files.
2827 Sat Apr 30 13:08:35 2011 Child 4 finished creating 626 directories.
2828 Sat Apr 30 13:08:35 2011 Child 4 will create 19139 files.
2829 Sat Apr 30 13:08:37 2011 Child 1 finished creating 626 directories.
2830 Sat Apr 30 13:08:37 2011 Child 1 will create 19139 files.
2831 Sat Apr 30 13:09:01 2011 Child 10 finished creating 626 directories.
2832 Sat Apr 30 13:09:01 2011 Child 10 will create 19139 files.
2833 Sat Apr 30 13:09:05 2011 Child 6 finished creating 626 directories.
2834 Sat Apr 30 13:09:05 2011 Child 6 will create 19139 files.
2835 Sat Apr 30 13:09:05 2011 Child 14 finished creating 626 directories.
2836 Sat Apr 30 13:09:05 2011 Child 14 will create 19139 files.
2837 Sat Apr 30 13:09:08 2011 Child 0 finished creating 626 directories.
2838 Sat Apr 30 13:09:08 2011 Child 0 will create 19139 files.
2839 Sat Apr 30 13:09:10 2011 Child 3 finished creating 626 directories.
2840 Sat Apr 30 13:09:10 2011 Child 3 will create 19139 files.
2841 Sat Apr 30 13:09:18 2011 Child 8 finished creating 626 directories.
2842 Sat Apr 30 13:09:18 2011 Child 8 will create 19139 files.
2843 Sat Apr 30 13:09:18 2011 Child 11 finished creating 626 directories.
2844 Sat Apr 30 13:09:18 2011 Child 11 will create 19139 files.
2845 Sat Apr 30 13:09:27 2011 Child 2 finished creating 626 directories.
2846 Sat Apr 30 13:09:27 2011 Child 2 will create 19139 files.
2847 Sat Apr 30 13:09:27 2011 Child 15 finished creating 626 directories.
2848 Sat Apr 30 13:09:27 2011 Child 15 will create 19139 files.
2849 Sat Apr 30 13:09:29 2011 Child 9 finished creating 626 directories.
2850 Sat Apr 30 13:09:29 2011 Child 9 will create 19139 files.
2851 Sat Apr 30 13:12:05 2011 Child 12 verified 19139 files, created 1670
2852 Sat Apr 30 13:12:05 2011 Child 12 will create 2 symlinks.
2853 Sat Apr 30 13:12:06 2011 Child 12 finished creating 2 symlinks.
2854 Sat Apr 30 13:12:09 2011 Child 5 verified 19139 files, created 1670
2855 Sat Apr 30 13:12:09 2011 Child 5 will create 2 symlinks.
2856 Sat Apr 30 13:12:09 2011 Child 5 finished creating 2 symlinks.
2857 Sat Apr 30 13:13:08 2011 Child 13 verified 19139 files, created 1670
2858 Sat Apr 30 13:13:08 2011 Child 13 will create 2 symlinks.
2859 Sat Apr 30 13:13:08 2011 Child 13 finished creating 2 symlinks.
2860 Sat Apr 30 13:13:40 2011 Child 4 verified 18558 files (1276 creates).
2861 Sat Apr 30 13:13:52 2011 Child 1 verified 18558 files (1276 creates).
2862 Sat Apr 30 13:13:52 2011 Child 7 verified 18698 files (1416 creates).
2863 Sat Apr 30 13:13:56 2011 Child 7 verified 19139 files, created 1670
2864 Sat Apr 30 13:13:56 2011 Child 7 will create 2 symlinks.
2865 Sat Apr 30 13:13:56 2011 Child 7 finished creating 2 symlinks.
2866 Sat Apr 30 13:14:14 2011 Child 0 verified 18001 files (719 creates).
2867 Sat Apr 30 13:14:18 2011 Child 14 verified 17991 files (709 creates).
2868 Sat Apr 30 13:14:18 2011 Child 6 verified 17991 files (709 creates).
2869 Sat Apr 30 13:14:18 2011 Child 10 verified 17991 files (709 creates).
2870 Sat Apr 30 13:14:18 2011 Child 3 verified 17991 files (709 creates).
2871 Sat Apr 30 13:14:19 2011 Child 8 verified 18002 files (720 creates).
2872 Sat Apr 30 13:14:19 2011 Child 11 verified 18046 files (764 creates).
2873 Sat Apr 30 13:14:30 2011 Child 15 verified 18039 files (757 creates).
2874 Sat Apr 30 13:14:31 2011 Child 9 verified 17979 files (697 creates).
2875 Sat Apr 30 13:14:32 2011 Child 2 verified 18035 files (753 creates).
2876 Sat Apr 30 13:14:53 2011 Child 1 verified 19139 files, created 1670
2877 Sat Apr 30 13:14:53 2011 Child 1 will create 2 symlinks.
2878 Sat Apr 30 13:14:55 2011 Child 4 verified 19139 files, created 1670
2879 Sat Apr 30 13:14:55 2011 Child 4 will create 2 symlinks.
2880 Sat Apr 30 13:14:55 2011 Child 4 finished creating 2 symlinks.
2881 Sat Apr 30 13:14:56 2011 Child 1 finished creating 2 symlinks.
2882 Sat Apr 30 13:16:35 2011 Child 11 verified 19139 files, created 1670
2883 Sat Apr 30 13:16:35 2011 Child 11 will create 2 symlinks.
2884 Sat Apr 30 13:16:35 2011 Child 11 finished creating 2 symlinks.
2885 Sat Apr 30 13:16:35 2011 Child 0 verified 19139 files, created 1670
2886 Sat Apr 30 13:16:35 2011 Child 0 will create 2 symlinks.
2887 Sat Apr 30 13:16:37 2011 Child 2 verified 19139 files, created 1670
2888 Sat Apr 30 13:16:37 2011 Child 2 will create 2 symlinks.
2889 Sat Apr 30 13:16:37 2011 Child 2 finished creating 2 symlinks.
2890 Sat Apr 30 13:16:38 2011 Child 0 finished creating 2 symlinks.
2891 Sat Apr 30 13:16:40 2011 Child 10 verified 19139 files, created 1670
2892 Sat Apr 30 13:16:40 2011 Child 10 will create 2 symlinks.
2893 Sat Apr 30 13:16:40 2011 Child 10 finished creating 2 symlinks.
2894 Sat Apr 30 13:16:40 2011 Child 15 verified 19139 files, created 1670
2895 Sat Apr 30 13:16:40 2011 Child 15 will create 2 symlinks.
2896 Sat Apr 30 13:16:40 2011 Child 15 finished creating 2 symlinks.
2897 Sat Apr 30 13:16:40 2011 Child 6 verified 19139 files, created 1670
2898 Sat Apr 30 13:16:40 2011 Child 6 will create 2 symlinks.
2899 Sat Apr 30 13:16:40 2011 Child 6 finished creating 2 symlinks.
2900 Sat Apr 30 13:16:40 2011 Child 8 verified 19139 files, created 1670
2901 Sat Apr 30 13:16:40 2011 Child 8 will create 2 symlinks.
2902 Sat Apr 30 13:16:40 2011 Child 8 finished creating 2 symlinks.
2903 Sat Apr 30 13:16:40 2011 Child 3 verified 19139 files, created 1670
2904 Sat Apr 30 13:16:40 2011 Child 3 will create 2 symlinks.
2905 Sat Apr 30 13:16:40 2011 Child 3 finished creating 2 symlinks.
2906 Sat Apr 30 13:16:40 2011 Child 14 verified 19139 files, created 1670
2907 Sat Apr 30 13:16:40 2011 Child 14 will create 2 symlinks.
2908 Sat Apr 30 13:16:40 2011 Child 14 finished creating 2 symlinks.
2909 Sat Apr 30 13:16:40 2011 Child 9 verified 19139 files, created 1670
2910 Sat Apr 30 13:16:40 2011 Child 9 will create 2 symlinks.
2911 Sat Apr 30 13:16:40 2011 Child 9 finished creating 2 symlinks.
2912 Sat Apr 30 13:16:41 2011 Completed.
2913 Sat Apr 30 13:16:41 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
2914 Sat Apr 30 13:16:41 2011 Completed.
2915 Sat Apr 30 13:16:41 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
2916 Sat Apr 30 13:16:42 2011 Received.
2917 Sat Apr 30 13:16:42 2011 Performing 300 seconds pretest warmup.
2918 Sat Apr 30 13:21:42 2011 Completed.
2919 Sat Apr 30 13:21:42 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
2920 Sat Apr 30 13:21:42 2011 Completed.
2921 Sat Apr 30 13:21:42 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
2922 Sat Apr 30 13:21:43 2011 Received.
2923 Sat Apr 30 13:21:43 2011 Starting 300 seconds test run.
2924 SFS Single Client (c09-44) Results, Sat Apr 30 13:27:04 2011
2925 --------------------------------------------------------------------------------------
2926 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
2927 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
2928 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
2929            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
2930 --------------------------------------------------------------------------------------
2931 getattr     26.0%    25.7%    10063    10063      0     12.92    38.25      0.12  2.4%
2932 setattr      4.0%     4.1%     1590     1590      0    583.92  1394.41      1.84 17.1%
2933 lookup      24.0%    23.9%     9373     9373      0     12.27    37.83      0.12  2.1%
2934 readlink     1.0%     1.0%      399      399      0     11.25    29.56      0.53  0.1%
2935 read        18.0%    17.9%     7008     8656      0    273.39   581.43      0.56 35.3%
2936 write       10.0%    10.0%     3928     4824      0    206.66   465.65      0.67 15.0%
2937 create       1.0%     1.1%      425      425      0   1022.92  1166.16      3.25  8.0%
2938 remove       1.0%     1.1%      412      412      0    519.39   356.70      1.82  3.9%
2939 readdir      1.0%     1.1%      417      417      0     11.64    32.11      0.54  0.1%
2940 fsstat       1.0%     1.0%      398      398      0     11.55    34.02      0.57  0.1%
2941 access      11.0%    11.2%     4396     4396      0     12.10    34.34      0.17  1.0%
2942 commit       0.0%     0.0%        0     1355      0      0.00     0.00      0.00 14.7%
2943 readdirplus  2.0%     2.0%      798      798      0     12.51    30.63      0.38  0.2%
2944 --------------------------------------------------------------------------------------
2945
2946       --------------------------------------------------
2947       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
2948       --------------------------+-----------------------
2949 SFS NFS V3 THROUGHPUT:  130.69 Ops/Sec   AVG. RESPONSE TIME:  138.29 Msec/Op
2950 TCP PROTOCOL
2951 FAST CALLS: 0
2952 NFS V3 MIXFILE: [ SFS Default ]
2953 CLIENT REQUESTED LOAD: 1300 Ops/Sec
2954 TOTAL LOGICAL NFS V3 OPERATIONS:  39207          TEST TIME: 300 Sec
2955 TOTAL PHYSICAL NFS V3 OPERATIONS: 43106
2956 PHYSICAL NFS V3 THROUGHPUT:  143.68 Ops/Sec
2957 FILE SET SIZE CREATED: 159410448.0 KB
2958 FILE SET SIZE ACCESSED: 47823984.0 - 47907824.0 KB  (100.00% to 100.18% of Base)
2959
2960 ------------------------------------------------------------------------
2961
2962 Sat Apr 30 13:27:04 2011 Completed.
2963
2964 Client Test Parameters:
2965         Number of processes = 16
2966         Requested Load (NFS V3 operations/second) = 1300
2967         Maximum number of outstanding biod writes = 8
2968         Maximum number of outstanding biod reads = 8
2969         Warm-up time (seconds) = 300
2970         Run time (seconds) = 300
2971         File Set = 300208 Files created for I/O operations
2972                    90048 Files accessed for I/O operations
2973                    6016 Files for non-I/O operations
2974                      32 Symlinks
2975                    10000 Directories
2976                         Additional non-I/O files created as necessary
2977
2978
2979 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
2980 Sat Apr 30 13:27:04 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
2981 Sat Apr 30 13:27:04 2011 Completed.
2982 Sat Apr 30 13:27:04 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
2983
2984 ************************************************************************
2985 Sat Apr 30 13:27:05 2011 Received.
2986 Sat Apr 30 13:27:05 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
2987 Sat Apr 30 13:27:05 2011 Completed.
2988 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
2989 NFS V3 Protocol Version
2990
2991 ************************************************************************
2992 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
2993 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
2994
2995 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
2996 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
2997
2998 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 13:27:07 2011
2999         Client hostname = c09-44
3000         Prime Client hostname = c09-44.sysnet.ucsd.edu
3001
3002 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3003 NFS V3 Protocol
3004 Sat Apr 30 13:27:07 2011 Mounting 16 remote test directories.
3005 Child[000]: NFS negotiated block size 32768
3006 Child[001]: NFS negotiated block size 32768
3007 Child[002]: NFS negotiated block size 32768
3008 Child[003]: NFS negotiated block size 32768
3009 Child[004]: NFS negotiated block size 32768
3010 Child[005]: NFS negotiated block size 32768
3011 Child[006]: NFS negotiated block size 32768
3012 Child[007]: NFS negotiated block size 32768
3013 Child[008]: NFS negotiated block size 32768
3014 Child[009]: NFS negotiated block size 32768
3015 Child[010]: NFS negotiated block size 32768
3016 Child[011]: NFS negotiated block size 32768
3017 Child[012]: NFS negotiated block size 32768
3018 Child[013]: NFS negotiated block size 32768
3019 Child[014]: NFS negotiated block size 32768
3020 Child[015]: NFS negotiated block size 32768
3021 Sat Apr 30 13:27:21 2011 Completed.
3022 Sat Apr 30 13:27:21 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
3023 Sat Apr 30 13:27:21 2011 Completed.
3024 Sat Apr 30 13:27:21 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
3025 Sat Apr 30 13:27:23 2011 Received.
3026
3027 Sat Apr 30 13:27:23 2011 Initializing test directories.
3028 Sat Apr 30 13:27:23 2011 Child 15 will create 675 directories.
3029 Sat Apr 30 13:27:23 2011 Child 9 will create 675 directories.
3030 Sat Apr 30 13:27:23 2011 Child 1 will create 675 directories.
3031 Sat Apr 30 13:27:23 2011 Child 14 will create 675 directories.
3032 Sat Apr 30 13:27:23 2011 Child 0 will create 675 directories.
3033 Sat Apr 30 13:27:23 2011 Child 2 will create 675 directories.
3034 Sat Apr 30 13:27:23 2011 Child 13 will create 675 directories.
3035 Sat Apr 30 13:27:23 2011 Child 10 will create 675 directories.
3036 Sat Apr 30 13:27:23 2011 Child 3 will create 675 directories.
3037 Sat Apr 30 13:27:23 2011 Child 4 will create 675 directories.
3038 Sat Apr 30 13:27:23 2011 Child 12 will create 675 directories.
3039 Sat Apr 30 13:27:23 2011 Child 6 will create 675 directories.
3040 Sat Apr 30 13:27:23 2011 Child 5 will create 675 directories.
3041 Sat Apr 30 13:27:23 2011 Child 11 will create 675 directories.
3042 Sat Apr 30 13:27:23 2011 Child 7 will create 675 directories.
3043 Sat Apr 30 13:27:23 2011 Child 8 will create 675 directories.
3044 Sat Apr 30 13:27:24 2011 Child 0 finished creating 675 directories.
3045 Sat Apr 30 13:27:24 2011 Child 0 will create 20649 files.
3046 Sat Apr 30 13:27:27 2011 Child 13 finished creating 675 directories.
3047 Sat Apr 30 13:27:27 2011 Child 13 will create 20649 files.
3048 Sat Apr 30 13:27:30 2011 Child 14 finished creating 675 directories.
3049 Sat Apr 30 13:27:30 2011 Child 14 will create 20649 files.
3050 Sat Apr 30 13:27:32 2011 Child 10 finished creating 675 directories.
3051 Sat Apr 30 13:27:32 2011 Child 10 will create 20649 files.
3052 Sat Apr 30 13:27:38 2011 Child 12 finished creating 675 directories.
3053 Sat Apr 30 13:27:38 2011 Child 12 will create 20649 files.
3054 Sat Apr 30 13:27:38 2011 Child 4 finished creating 675 directories.
3055 Sat Apr 30 13:27:38 2011 Child 4 will create 20649 files.
3056 Sat Apr 30 13:27:38 2011 Child 8 finished creating 675 directories.
3057 Sat Apr 30 13:27:38 2011 Child 8 will create 20649 files.
3058 Sat Apr 30 13:27:41 2011 Child 7 finished creating 675 directories.
3059 Sat Apr 30 13:27:41 2011 Child 7 will create 20649 files.
3060 Sat Apr 30 13:27:48 2011 Child 11 finished creating 675 directories.
3061 Sat Apr 30 13:27:48 2011 Child 11 will create 20649 files.
3062 Sat Apr 30 13:27:49 2011 Child 1 finished creating 675 directories.
3063 Sat Apr 30 13:27:49 2011 Child 1 will create 20649 files.
3064 Sat Apr 30 13:27:55 2011 Child 2 finished creating 675 directories.
3065 Sat Apr 30 13:27:55 2011 Child 2 will create 20649 files.
3066 Sat Apr 30 13:27:59 2011 Child 15 finished creating 675 directories.
3067 Sat Apr 30 13:27:59 2011 Child 15 will create 20649 files.
3068 Sat Apr 30 13:28:21 2011 Child 5 finished creating 675 directories.
3069 Sat Apr 30 13:28:21 2011 Child 5 will create 20649 files.
3070 Sat Apr 30 13:28:26 2011 Child 3 finished creating 675 directories.
3071 Sat Apr 30 13:28:26 2011 Child 3 will create 20649 files.
3072 Sat Apr 30 13:28:28 2011 Child 9 finished creating 675 directories.
3073 Sat Apr 30 13:28:28 2011 Child 9 will create 20649 files.
3074 Sat Apr 30 13:28:28 2011 Child 6 finished creating 675 directories.
3075 Sat Apr 30 13:28:28 2011 Child 6 will create 20649 files.
3076 Sat Apr 30 13:29:53 2011 Child 0 verified 20649 files, created 1684
3077 Sat Apr 30 13:29:53 2011 Child 0 will create 2 symlinks.
3078 Sat Apr 30 13:29:53 2011 Child 0 finished creating 2 symlinks.
3079 Sat Apr 30 13:30:41 2011 Child 13 verified 20649 files, created 1684
3080 Sat Apr 30 13:30:41 2011 Child 13 will create 2 symlinks.
3081 Sat Apr 30 13:30:41 2011 Child 13 finished creating 2 symlinks.
3082 Sat Apr 30 13:31:04 2011 Child 14 verified 20649 files, created 1684
3083 Sat Apr 30 13:31:04 2011 Child 14 will create 2 symlinks.
3084 Sat Apr 30 13:31:04 2011 Child 14 finished creating 2 symlinks.
3085 Sat Apr 30 13:31:12 2011 Child 10 verified 20649 files, created 1684
3086 Sat Apr 30 13:31:12 2011 Child 10 will create 2 symlinks.
3087 Sat Apr 30 13:31:12 2011 Child 10 finished creating 2 symlinks.
3088 Sat Apr 30 13:31:53 2011 Child 12 verified 20649 files, created 1684
3089 Sat Apr 30 13:31:53 2011 Child 12 will create 2 symlinks.
3090 Sat Apr 30 13:31:54 2011 Child 12 finished creating 2 symlinks.
3091 Sat Apr 30 13:31:54 2011 Child 4 verified 20649 files, created 1684
3092 Sat Apr 30 13:31:54 2011 Child 4 will create 2 symlinks.
3093 Sat Apr 30 13:31:56 2011 Child 8 verified 20649 files, created 1684
3094 Sat Apr 30 13:31:56 2011 Child 8 will create 2 symlinks.
3095 Sat Apr 30 13:31:56 2011 Child 8 finished creating 2 symlinks.
3096 Sat Apr 30 13:31:56 2011 Child 7 verified 20649 files, created 1684
3097 Sat Apr 30 13:31:56 2011 Child 7 will create 2 symlinks.
3098 Sat Apr 30 13:31:57 2011 Child 7 finished creating 2 symlinks.
3099 Sat Apr 30 13:31:57 2011 Child 4 finished creating 2 symlinks.
3100 Sat Apr 30 13:32:03 2011 Child 11 verified 20649 files, created 1684
3101 Sat Apr 30 13:32:03 2011 Child 11 will create 2 symlinks.
3102 Sat Apr 30 13:32:03 2011 Child 11 finished creating 2 symlinks.
3103 Sat Apr 30 13:32:03 2011 Child 1 verified 20649 files, created 1684
3104 Sat Apr 30 13:32:03 2011 Child 1 will create 2 symlinks.
3105 Sat Apr 30 13:32:04 2011 Child 1 finished creating 2 symlinks.
3106 Sat Apr 30 13:32:05 2011 Child 15 verified 20649 files, created 1684
3107 Sat Apr 30 13:32:05 2011 Child 15 will create 2 symlinks.
3108 Sat Apr 30 13:32:05 2011 Child 15 finished creating 2 symlinks.
3109 Sat Apr 30 13:32:07 2011 Child 2 verified 20649 files, created 1684
3110 Sat Apr 30 13:32:07 2011 Child 2 will create 2 symlinks.
3111 Sat Apr 30 13:32:12 2011 Child 2 finished creating 2 symlinks.
3112 Sat Apr 30 13:32:15 2011 Child 9 verified 20649 files, created 1684
3113 Sat Apr 30 13:32:15 2011 Child 9 will create 2 symlinks.
3114 Sat Apr 30 13:32:15 2011 Child 9 finished creating 2 symlinks.
3115 Sat Apr 30 13:32:18 2011 Child 6 verified 20649 files, created 1684
3116 Sat Apr 30 13:32:18 2011 Child 6 will create 2 symlinks.
3117 Sat Apr 30 13:32:18 2011 Child 6 finished creating 2 symlinks.
3118 Sat Apr 30 13:32:18 2011 Child 3 verified 20649 files, created 1684
3119 Sat Apr 30 13:32:18 2011 Child 3 will create 2 symlinks.
3120 Sat Apr 30 13:32:18 2011 Child 3 finished creating 2 symlinks.
3121 Sat Apr 30 13:32:18 2011 Child 5 verified 20649 files, created 1684
3122 Sat Apr 30 13:32:18 2011 Child 5 will create 2 symlinks.
3123 Sat Apr 30 13:32:18 2011 Child 5 finished creating 2 symlinks.
3124 Sat Apr 30 13:32:19 2011 Completed.
3125 Sat Apr 30 13:32:19 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
3126 Sat Apr 30 13:32:19 2011 Completed.
3127 Sat Apr 30 13:32:19 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
3128 Sat Apr 30 13:32:19 2011 Received.
3129 Sat Apr 30 13:32:19 2011 Performing 300 seconds pretest warmup.
3130 Sat Apr 30 13:37:19 2011 Completed.
3131 Sat Apr 30 13:37:19 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
3132 Sat Apr 30 13:37:19 2011 Completed.
3133 Sat Apr 30 13:37:19 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
3134 Sat Apr 30 13:37:20 2011 Received.
3135 Sat Apr 30 13:37:20 2011 Starting 300 seconds test run.
3136 SFS Single Client (c09-44) Results, Sat Apr 30 13:42:49 2011
3137 --------------------------------------------------------------------------------------
3138 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
3139 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
3140 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
3141            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
3142 --------------------------------------------------------------------------------------
3143 getattr     26.0%    25.7%    10597    10597      0     14.68   289.37      0.32  2.7%
3144 setattr      4.0%     4.0%     1668     1668      0    550.94   984.90      1.51 15.7%
3145 lookup      24.0%    23.9%     9852     9852      0     12.30   138.61      0.23  2.1%
3146 readlink     1.0%     0.9%      390      390      0      9.65    28.56      0.53  0.1%
3147 read        18.0%    17.9%     7376     8999      0    266.23   798.40      0.64 33.5%
3148 write       10.0%     9.9%     4093     5044      0    249.64  1008.21      0.97 17.4%
3149 create       1.0%     1.0%      409      409      0   1106.80  1323.88      3.53  7.7%
3150 remove       1.0%     1.1%      453      453      0    631.77  1430.05      3.48  4.9%
3151 readdir      1.0%     1.2%      482      482      0     11.06    34.84      0.53  0.1%
3152 fsstat       1.0%     1.0%      425      425      0     11.92    43.08      0.62  0.1%
3153 access      11.0%    11.3%     4640     4640      0     15.32   309.19      0.51  1.2%
3154 commit       0.0%     0.0%        0     1401      0      0.00     0.00      0.00 14.1%
3155 readdirplus  2.0%     2.1%      859      859      0     35.98   714.45      1.79  0.5%
3156 --------------------------------------------------------------------------------------
3157
3158       --------------------------------------------------
3159       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
3160       --------------------------+-----------------------
3161 SFS NFS V3 THROUGHPUT:  129.29 Ops/Sec   AVG. RESPONSE TIME:  142.16 Msec/Op
3162 TCP PROTOCOL
3163 FAST CALLS: 0
3164 NFS V3 MIXFILE: [ SFS Default ]
3165 CLIENT REQUESTED LOAD: 1400 Ops/Sec
3166 TOTAL LOGICAL NFS V3 OPERATIONS:  41244          TEST TIME: 319 Sec
3167 TOTAL PHYSICAL NFS V3 OPERATIONS: 45219
3168 PHYSICAL NFS V3 THROUGHPUT:  141.75 Ops/Sec
3169 FILE SET SIZE CREATED: 171993024.0 KB
3170 FILE SET SIZE ACCESSED: 51604704.0 - 51693344.0 KB  (100.00% to 100.17% of Base)
3171
3172 ------------------------------------------------------------------------
3173
3174 Sat Apr 30 13:42:49 2011 Completed.
3175
3176 Client Test Parameters:
3177         Number of processes = 16
3178         Requested Load (NFS V3 operations/second) = 1400
3179         Maximum number of outstanding biod writes = 8
3180         Maximum number of outstanding biod reads = 8
3181         Warm-up time (seconds) = 300
3182         Run time (seconds) = 300
3183         File Set = 323904 Files created for I/O operations
3184                    97168 Files accessed for I/O operations
3185                    6480 Files for non-I/O operations
3186                      32 Symlinks
3187                    10784 Directories
3188                         Additional non-I/O files created as necessary
3189
3190
3191 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
3192 Sat Apr 30 13:42:49 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
3193 Sat Apr 30 13:42:49 2011 Completed.
3194 Sat Apr 30 13:42:49 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
3195
3196 ************************************************************************
3197 Sat Apr 30 13:42:50 2011 Received.
3198 Sat Apr 30 13:42:50 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
3199 Sat Apr 30 13:42:50 2011 Completed.
3200 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
3201 NFS V3 Protocol Version
3202
3203 ************************************************************************
3204 ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client
3205 ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client
3206
3207 c09-44: Good clock resolution [ 1.000000 ] Micro seconds.
3208 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3209
3210 SFS NFS V3 Benchmark Client Logfile, Sat Apr 30 13:42:52 2011
3211         Client hostname = c09-44
3212         Prime Client hostname = c09-44.sysnet.ucsd.edu
3213
3214 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
3215 NFS V3 Protocol
3216 Sat Apr 30 13:42:52 2011 Mounting 16 remote test directories.
3217 Child[000]: NFS negotiated block size 32768
3218 Child[001]: NFS negotiated block size 32768
3219 Child[002]: NFS negotiated block size 32768
3220 Child[003]: NFS negotiated block size 32768
3221 Child[004]: NFS negotiated block size 32768
3222 Child[005]: NFS negotiated block size 32768
3223 Child[006]: NFS negotiated block size 32768
3224 Child[007]: NFS negotiated block size 32768
3225 Child[008]: NFS negotiated block size 32768
3226 Child[009]: NFS negotiated block size 32768
3227 Child[010]: NFS negotiated block size 32768
3228 Child[011]: NFS negotiated block size 32768
3229 Child[012]: NFS negotiated block size 32768
3230 Child[013]: NFS negotiated block size 32768
3231 Child[014]: NFS negotiated block size 32768
3232 Child[015]: NFS negotiated block size 32768
3233 Sat Apr 30 13:43:06 2011 Completed.
3234 Sat Apr 30 13:43:06 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu).
3235 Sat Apr 30 13:43:06 2011 Completed.
3236 Sat Apr 30 13:43:06 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu).
3237 Sat Apr 30 13:43:08 2011 Received.
3238
3239 Sat Apr 30 13:43:08 2011 Initializing test directories.
3240 Sat Apr 30 13:43:08 2011 Child 14 will create 721 directories.
3241 Sat Apr 30 13:43:08 2011 Child 13 will create 721 directories.
3242 Sat Apr 30 13:43:08 2011 Child 2 will create 721 directories.
3243 Sat Apr 30 13:43:08 2011 Child 12 will create 721 directories.
3244 Sat Apr 30 13:43:08 2011 Child 0 will create 721 directories.
3245 Sat Apr 30 13:43:08 2011 Child 3 will create 721 directories.
3246 Sat Apr 30 13:43:08 2011 Child 11 will create 721 directories.
3247 Sat Apr 30 13:43:08 2011 Child 10 will create 721 directories.
3248 Sat Apr 30 13:43:08 2011 Child 4 will create 721 directories.
3249 Sat Apr 30 13:43:08 2011 Child 9 will create 721 directories.
3250 Sat Apr 30 13:43:08 2011 Child 1 will create 721 directories.
3251 Sat Apr 30 13:43:08 2011 Child 5 will create 721 directories.
3252 Sat Apr 30 13:43:08 2011 Child 15 will create 721 directories.
3253 Sat Apr 30 13:43:08 2011 Child 6 will create 721 directories.
3254 Sat Apr 30 13:43:08 2011 Child 8 will create 721 directories.
3255 Sat Apr 30 13:43:08 2011 Child 7 will create 721 directories.
3256 Sat Apr 30 13:43:08 2011 Child 9 finished creating 721 directories.
3257 Sat Apr 30 13:43:08 2011 Child 9 will create 22035 files.
3258 Sat Apr 30 13:43:26 2011 Child 11 finished creating 721 directories.
3259 Sat Apr 30 13:43:26 2011 Child 11 will create 22035 files.
3260 Sat Apr 30 13:43:26 2011 Child 5 finished creating 721 directories.
3261 Sat Apr 30 13:43:26 2011 Child 5 will create 22035 files.
3262 Sat Apr 30 13:43:26 2011 Child 15 finished creating 721 directories.
3263 Sat Apr 30 13:43:26 2011 Child 15 will create 22035 files.
3264 Sat Apr 30 13:43:28 2011 Child 1 finished creating 721 directories.
3265 Sat Apr 30 13:43:28 2011 Child 1 will create 22035 files.
3266 Sat Apr 30 13:43:33 2011 Child 3 finished creating 721 directories.
3267 Sat Apr 30 13:43:33 2011 Child 3 will create 22035 files.
3268 Sat Apr 30 13:43:35 2011 Child 8 finished creating 721 directories.
3269 Sat Apr 30 13:43:35 2011 Child 8 will create 22035 files.
3270 Sat Apr 30 13:43:36 2011 Child 0 finished creating 721 directories.
3271 Sat Apr 30 13:43:36 2011 Child 0 will create 22035 files.
3272 Sat Apr 30 13:43:37 2011 Child 4 finished creating 721 directories.
3273 Sat Apr 30 13:43:37 2011 Child 4 will create 22035 files.
3274 Sat Apr 30 13:43:39 2011 Child 7 finished creating 721 directories.
3275 Sat Apr 30 13:43:39 2011 Child 7 will create 22035 files.
3276 Sat Apr 30 13:43:40 2011 Child 10 finished creating 721 directories.
3277 Sat Apr 30 13:43:40 2011 Child 10 will create 22035 files.
3278 Sat Apr 30 13:43:43 2011 Child 6 finished creating 721 directories.
3279 Sat Apr 30 13:43:43 2011 Child 6 will create 22035 files.
3280 Sat Apr 30 13:43:50 2011 Child 13 finished creating 721 directories.
3281 Sat Apr 30 13:43:50 2011 Child 13 will create 22035 files.
3282 Sat Apr 30 13:44:03 2011 Child 14 finished creating 721 directories.
3283 Sat Apr 30 13:44:03 2011 Child 14 will create 22035 files.
3284 Sat Apr 30 13:44:15 2011 Child 12 finished creating 721 directories.
3285 Sat Apr 30 13:44:15 2011 Child 12 will create 22035 files.
3286 Sat Apr 30 13:44:16 2011 Child 2 finished creating 721 directories.
3287 Sat Apr 30 13:44:16 2011 Child 2 will create 22035 files.
3288 Sat Apr 30 13:44:57 2011 Child 9 verified 22035 files, created 1575
3289 Sat Apr 30 13:44:57 2011 Child 9 will create 2 symlinks.
3290 Sat Apr 30 13:44:57 2011 Child 9 finished creating 2 symlinks.
3291 Sat Apr 30 13:47:41 2011 Child 11 verified 22035 files, created 1575
3292 Sat Apr 30 13:47:41 2011 Child 11 will create 2 symlinks.
3293 Sat Apr 30 13:47:42 2011 Child 11 finished creating 2 symlinks.
3294 Sat Apr 30 13:48:03 2011 Child 5 verified 22035 files, created 1575
3295 Sat Apr 30 13:48:03 2011 Child 5 will create 2 symlinks.
3296 Sat Apr 30 13:48:03 2011 Child 1 verified 22035 files, created 1575
3297 Sat Apr 30 13:48:03 2011 Child 1 will create 2 symlinks.
3298 Sat Apr 30 13:48:04 2011 Child 1 finished creating 2 symlinks.
3299 Sat Apr 30 13:48:04 2011 Child 5 finished creating 2 symlinks.
3300 Sat Apr 30 13:48:08 2011 Child 0 verified 22035 files, created 1575
3301 Sat Apr 30 13:48:08 2011 Child 0 will create 2 symlinks.
3302 Sat Apr 30 13:48:08 2011 Child 15 verified 22035 files, created 1575
3303 Sat Apr 30 13:48:08 2011 Child 15 will create 2 symlinks.
3304 Sat Apr 30 13:48:08 2011 Child 0 finished creating 2 symlinks.
3305 Sat Apr 30 13:48:08 2011 Child 15 finished creating 2 symlinks.
3306 Sat Apr 30 13:48:11 2011 Child 8 verified 22035 files, created 1575
3307 Sat Apr 30 13:48:11 2011 Child 8 will create 2 symlinks.
3308 Sat Apr 30 13:48:11 2011 Child 8 finished creating 2 symlinks.
3309 Sat Apr 30 13:48:13 2011 Child 3 verified 22035 files, created 1575
3310 Sat Apr 30 13:48:13 2011 Child 3 will create 2 symlinks.
3311 Sat Apr 30 13:48:13 2011 Child 3 finished creating 2 symlinks.
3312 Sat Apr 30 13:48:19 2011 Child 4 verified 22035 files, created 1575
3313 Sat Apr 30 13:48:19 2011 Child 4 will create 2 symlinks.
3314 Sat Apr 30 13:48:19 2011 Child 4 finished creating 2 symlinks.
3315 Sat Apr 30 13:48:20 2011 Child 7 verified 22035 files, created 1575
3316 Sat Apr 30 13:48:20 2011 Child 7 will create 2 symlinks.
3317 Sat Apr 30 13:48:20 2011 Child 7 finished creating 2 symlinks.
3318 Sat Apr 30 13:48:23 2011 Child 10 verified 22035 files, created 1575
3319 Sat Apr 30 13:48:23 2011 Child 10 will create 2 symlinks.
3320 Sat Apr 30 13:48:23 2011 Child 10 finished creating 2 symlinks.
3321 Sat Apr 30 13:48:24 2011 Child 6 verified 22035 files, created 1575
3322 Sat Apr 30 13:48:24 2011 Child 6 will create 2 symlinks.
3323 Sat Apr 30 13:48:24 2011 Child 6 finished creating 2 symlinks.
3324 Sat Apr 30 13:48:28 2011 Child 13 verified 22035 files, created 1575
3325 Sat Apr 30 13:48:28 2011 Child 13 will create 2 symlinks.
3326 Sat Apr 30 13:48:28 2011 Child 13 finished creating 2 symlinks.
3327 Sat Apr 30 13:48:32 2011 Child 14 verified 22035 files, created 1575
3328 Sat Apr 30 13:48:32 2011 Child 14 will create 2 symlinks.
3329 Sat Apr 30 13:48:32 2011 Child 14 finished creating 2 symlinks.
3330 Sat Apr 30 13:48:33 2011 Child 12 verified 22035 files, created 1575
3331 Sat Apr 30 13:48:33 2011 Child 12 will create 2 symlinks.
3332 Sat Apr 30 13:48:33 2011 Child 12 finished creating 2 symlinks.
3333 Sat Apr 30 13:48:34 2011 Child 2 verified 22035 files, created 1575
3334 Sat Apr 30 13:48:34 2011 Child 2 will create 2 symlinks.
3335 Sat Apr 30 13:48:34 2011 Child 2 finished creating 2 symlinks.
3336 Sat Apr 30 13:48:35 2011 Completed.
3337 Sat Apr 30 13:48:35 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu).
3338 Sat Apr 30 13:48:35 2011 Completed.
3339 Sat Apr 30 13:48:35 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu).
3340 Sat Apr 30 13:48:36 2011 Received.
3341 Sat Apr 30 13:48:36 2011 Performing 300 seconds pretest warmup.
3342 Sat Apr 30 13:53:36 2011 Completed.
3343 Sat Apr 30 13:53:36 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu).
3344 Sat Apr 30 13:53:36 2011 Completed.
3345 Sat Apr 30 13:53:36 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu).
3346 Sat Apr 30 13:53:37 2011 Received.
3347 Sat Apr 30 13:53:37 2011 Starting 300 seconds test run.
3348 SFS Single Client (c09-44) Results, Sat Apr 30 13:58:47 2011
3349 --------------------------------------------------------------------------------------
3350 NFS V3     Target   Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
3351 Op         Logical  Logical Logical Physical  Op     Response Response of Mean,  of
3352 Type       Mix      Mix     Success Success   Error  Time     Time     95% Conf  Total
3353            Pcnt     Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
3354 --------------------------------------------------------------------------------------
3355 getattr     26.0%    25.9%     8345     8345      0     25.28   373.19      0.41  3.7%
3356 setattr      4.0%     3.9%     1266     1266      0    474.26  1650.33      2.24 10.7%
3357 lookup      24.0%    23.6%     7600     7600      0     29.41   396.43      0.45  4.0%
3358 readlink     1.0%     1.0%      334      334      0     26.09    75.13      0.93  0.2%
3359 read        18.0%    18.2%     5847     7190      0    418.29  1519.20      1.00 43.4%
3360 write       10.0%     9.9%     3189     3819      0    312.26  1183.21      1.19 17.7%
3361 create       1.0%     1.1%      340      340      0    936.29  2586.33      5.40  5.6%
3362 remove       1.0%     1.1%      344      344      0    486.76   468.06      2.29  3.0%
3363 readdir      1.0%     1.0%      337      337      0     21.29    57.19      0.81  0.1%
3364 fsstat       1.0%     1.0%      334      334      0     21.66    57.61      0.81  0.1%
3365 access      11.0%    11.1%     3581     3581      0     20.04    61.86      0.26  1.3%
3366 commit       0.0%     0.0%        0     1085      0      0.00     0.00      0.00  9.8%
3367 readdirplus  2.0%     2.0%      655      655      0     37.10   316.43      1.36  0.4%
3368 --------------------------------------------------------------------------------------
3369
3370       --------------------------------------------------
3371       |   SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY  |
3372       --------------------------+-----------------------
3373 SFS NFS V3 THROUGHPUT:  107.59 Ops/Sec   AVG. RESPONSE TIME:  175.14 Msec/Op
3374 TCP PROTOCOL
3375 FAST CALLS: 0
3376 NFS V3 MIXFILE: [ SFS Default ]
3377 CLIENT REQUESTED LOAD: 1500 Ops/Sec
3378 TOTAL LOGICAL NFS V3 OPERATIONS:  32172          TEST TIME: 299 Sec
3379 TOTAL PHYSICAL NFS V3 OPERATIONS: 35230
3380 PHYSICAL NFS V3 THROUGHPUT:  117.82 Ops/Sec
3381 FILE SET SIZE CREATED: 183530592.0 KB
3382 FILE SET SIZE ACCESSED: 55062576.0 - 55117064.0 KB  (100.00% to 100.10% of Base)
3383
3384 ------------------------------------------------------------------------
3385
3386 Sat Apr 30 13:58:47 2011 Completed.
3387
3388 Client Test Parameters:
3389         Number of processes = 16
3390         Requested Load (NFS V3 operations/second) = 1500
3391         Maximum number of outstanding biod writes = 8
3392         Maximum number of outstanding biod reads = 8
3393         Warm-up time (seconds) = 300
3394         Run time (seconds) = 300
3395         File Set = 345632 Files created for I/O operations
3396                    103680 Files accessed for I/O operations
3397                    6928 Files for non-I/O operations
3398                      32 Symlinks
3399                    11520 Directories
3400                         Additional non-I/O files created as necessary
3401
3402
3403 SPEC SFS 2008 Benchmark, Creation - 1 February 2008
3404 Sat Apr 30 13:58:47 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu).
3405 Sat Apr 30 13:58:47 2011 Completed.
3406 Sat Apr 30 13:58:47 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu).
3407
3408 ************************************************************************
3409 Sat Apr 30 13:58:48 2011 Received.
3410 Sat Apr 30 13:58:48 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu)
3411 Sat Apr 30 13:58:48 2011 Completed.