results/20110409/sfslog.20110411-azure-hi16

   1 Mon Apr 11 13:16:16 PDT 2011 Configuration parameters passed validation
   2
   3 Mon Apr 11 13:16:16 PDT 2011 ========================================================================
   4 SFS Benchmark Prime Client Logfile.
   5         Creation Date: Mon Apr 11 13:16:16 PDT 2011
   6         Prime Client hostname: c09-44.sysnet.ucsd.edu
   7 Mon Apr 11 13:16:21 PDT 2011         Number of Clients: 1
   8         Client hostname(s): vrable1.sysnet.ucsd.edu
   9
  10 Mon Apr 11 13:16:21 PDT 2011 SfsManager: Started sfs_syncd on vrable1.sysnet.ucsd.edu
  11 Mon Apr 11 13:16:31 PDT 2011
  12 ************************************************************************
  13 Mon Apr 11 13:16:31 PDT 2011 Test Run 1 of 15
  14
  15 Mon Apr 11 13:16:31 PDT 2011 Mon Apr 11 13:16:31 PDT 2011 vrable1.sysnet.ucsd.edu start:
  16 Mon Apr 11 13:16:31 PDT 2011         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
  17
  18 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
  19 Executing SFS Benchmark on 1 Client(s).
  20 Mon Apr 11 13:16:46 2011 Waiting on DONE-MOUNT message from 1 client(s).
  21 Mon Apr 11 13:16:50 2011 Received.
  22 Mon Apr 11 13:16:50 2011 Sending DO-INIT message to 1 client(s).
  23 Mon Apr 11 13:16:50 2011 Completed.
  24 Mon Apr 11 13:16:50 2011 Waiting on DONE-INIT message from 1 client(s) (timeout is 8000 seconds).
  25 Mon Apr 11 13:24:11 2011 Received.
  26 Mon Apr 11 13:24:11 2011 Sending DO-WARMUP message to 1 client(s).
  27 Mon Apr 11 13:24:11 2011 Completed.
  28 Mon Apr 11 13:24:11 2011 Waiting on READY message from 1 client(s).
  29 Mon Apr 11 13:29:12 2011 Received.
  30 Mon Apr 11 13:29:12 2011 Sending START message to 1 client(s).
  31 Mon Apr 11 13:29:12 2011 Completed.
  32 Mon Apr 11 13:34:12 2011 Sending STOP message to 1 client(s).
  33 Mon Apr 11 13:34:12 2011 Completed.
  34 Mon Apr 11 13:34:12 2011 Waiting on DONE-TEST message from 1 client(s).
  35 Mon Apr 11 13:34:23 2011 Received.
  36 Mon Apr 11 13:34:23 2011 Sending MOVE-DATA message to 1 client(s).
  37 Mon Apr 11 13:34:23 2011 Completed.
  38 Mon Apr 11 13:34:23 2011 Waiting on SEND-DATA message from 1 client(s).
  39 Mon Apr 11 13:34:24 2011 Received.
  40 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
  41 Started on client (c09-44): ../binaries/linux-x86/sfs_prime -l 100 -C ../result/sfssum.20110411-azure-hi16 -t 300 -a 30 -A 70 -R 8 -W 8 -Q -p 16 -w 300 -X 8000 vrable1.sysnet.ucsd.edu
  42
  43 Aggregate Test Parameters:
  44     Number of processes = 16
  45     Requested Load (NFS operations/second) = 100
  46     Maximum number of outstanding biod writes = 8
  47     Maximum number of outstanding biod reads = 8
  48     Warm-up time (seconds) = 300
  49     Run time (seconds) = 300
  50     File Set =  21728 Files created for I/O operations
  51                  6512 Files accessed for I/O operations
  52                   448 Files for non-I/O operations
  53                    32 Symlinks
  54                   720 Directories
  55                       Additional non-I/O files created as necessary
  56
  57 SFS Aggregate Results for 1 Client(s), Mon Apr 11 13:34:24 2011
  58 NFS Protocol Version 3
  59
  60 -------------------------------------------------------------------------------------
  61 NFS         Target Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
  62 Op          NFS    NFS     Logical Physical  Op     Response Response of Mean,  of
  63 Type        Mix    Mix     Success Success   Error  Time     Time     95% Conf  Total
  64             Pcnt   Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
  65 -------------------------------------------------------------------------------------
  66 getattr      26.0%  25.8%     7749     7749      0     0.19     0.03      0.00   5.5%
  67 setattr       4.0%   4.0%     1191     1191      0     0.94     0.98      0.06   4.2%
  68 lookup       24.0%  23.7%     7104     7104      0     0.19     0.03      0.00   5.2%
  69 readlink      1.0%   1.0%      302      302      0     0.19     0.01      0.01   0.2%
  70 read         18.0%  18.1%     5440     6644      0     1.21     2.58      0.04  24.7%
  71 write        10.0%  10.2%     3053     3706      0     3.58    10.97      0.12  41.3%
  72 create        1.0%   1.0%      303      303      0     1.89     0.91      0.11   2.2%
  73 remove        1.0%   1.0%      297      297      0     1.15     0.56      0.09   1.3%
  74 readdir       1.0%   1.0%      287      287      0     0.36     0.08      0.03   0.4%
  75 fsstat        1.0%   1.0%      293      293      0     0.19     0.03      0.02   0.2%
  76 access       11.0%  11.1%     3338     3338      0     0.19     0.03      0.01   2.4%
  77 commit        0.0%   0.0%        0     1343      0     0.00     0.00      0.00  11.2%
  78 readdirplus   2.0%   2.1%      627      627      0     0.55     0.04      0.02   1.3%
  79 -------------------------------------------------------------------------------------
  80
  81         ---------------------------------------------
  82         |  SPEC SFS 2008 AGGREGATE RESULTS SUMMARY  |
  83         ---------------------------------------------
  84 SFS NFS THROUGHPUT:     100 Ops/Sec   AVG. RESPONSE TIME:     0.9 Msec/Op
  85 TCP PROTOCOL (IPv4)
  86 NFS MIXFILE: [ SFS default ]
  87 AGGREGATE REQUESTED LOAD: 100 Ops/Sec
  88 TOTAL LOGICAL NFS OPERATIONS:  29984          TEST TIME: 300 Sec
  89 TOTAL PHYSICAL NFS OPERATIONS: 33184
  90 PHYSICAL NFS IO THROUGHPUT: 110 Ops/sec
  91 NUMBER OF SFS CLIENTS: 1
  92 TOTAL FILE SET SIZE CREATED: 11267.2 MB
  93 TOTAL FILE SET SIZE ACCESSED: 3385.1 - 3453.7 MB  (100.00% to 102.03% of Base)
  94
  95 ------------------------------------------------------------------------
  96
  97 Mon Apr 11 13:34:25 PDT 2011
  98 ************************************************************************
  99 Mon Apr 11 13:34:25 PDT 2011
 100 ************************************************************************
 101 Mon Apr 11 13:34:25 PDT 2011 Test Run 2 of 15
 102
 103 Mon Apr 11 13:34:25 PDT 2011 Mon Apr 11 13:34:25 PDT 2011 vrable1.sysnet.ucsd.edu start:
 104 Mon Apr 11 13:34:25 PDT 2011         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
 105
 106 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 107 Executing SFS Benchmark on 1 Client(s).
 108 Mon Apr 11 13:34:40 2011 Waiting on DONE-MOUNT message from 1 client(s).
 109 Mon Apr 11 13:34:41 2011 Received.
 110 Mon Apr 11 13:34:41 2011 Sending DO-INIT message to 1 client(s).
 111 Mon Apr 11 13:34:41 2011 Completed.
 112 Mon Apr 11 13:34:41 2011 Waiting on DONE-INIT message from 1 client(s) (timeout is 8000 seconds).
 113 Mon Apr 11 13:54:49 2011 Received.
 114 Mon Apr 11 13:54:49 2011 Sending DO-WARMUP message to 1 client(s).
 115 Mon Apr 11 13:54:49 2011 Completed.
 116 Mon Apr 11 13:54:49 2011 Waiting on READY message from 1 client(s).
 117 Mon Apr 11 13:59:50 2011 Received.
 118 Mon Apr 11 13:59:50 2011 Sending START message to 1 client(s).
 119 Mon Apr 11 13:59:50 2011 Completed.
 120 Mon Apr 11 14:04:50 2011 Sending STOP message to 1 client(s).
 121 Mon Apr 11 14:04:50 2011 Completed.
 122 Mon Apr 11 14:04:50 2011 Waiting on DONE-TEST message from 1 client(s).
 123 Mon Apr 11 14:05:01 2011 Received.
 124 Mon Apr 11 14:05:01 2011 Sending MOVE-DATA message to 1 client(s).
 125 Mon Apr 11 14:05:01 2011 Completed.
 126 Mon Apr 11 14:05:01 2011 Waiting on SEND-DATA message from 1 client(s).
 127 Mon Apr 11 14:05:02 2011 Received.
 128 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 129 Started on client (c09-44): ../binaries/linux-x86/sfs_prime -l 200 -C ../result/sfssum.20110411-azure-hi16 -t 300 -a 30 -A 70 -R 8 -W 8 -Q -p 16 -w 300 -X 8000 vrable1.sysnet.ucsd.edu
 130
 131 Aggregate Test Parameters:
 132     Number of processes = 16
 133     Requested Load (NFS operations/second) = 200
 134     Maximum number of outstanding biod writes = 8
 135     Maximum number of outstanding biod reads = 8
 136     Warm-up time (seconds) = 300
 137     Run time (seconds) = 300
 138     File Set =  45440 Files created for I/O operations
 139                 13632 Files accessed for I/O operations
 140                   912 Files for non-I/O operations
 141                    32 Symlinks
 142                  1504 Directories
 143                       Additional non-I/O files created as necessary
 144
 145 SFS Aggregate Results for 1 Client(s), Mon Apr 11 14:05:02 2011
 146 NFS Protocol Version 3
 147
 148 -------------------------------------------------------------------------------------
 149 NFS         Target Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 150 Op          NFS    NFS     Logical Physical  Op     Response Response of Mean,  of
 151 Type        Mix    Mix     Success Success   Error  Time     Time     95% Conf  Total
 152             Pcnt   Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 153 -------------------------------------------------------------------------------------
 154 getattr      26.0%  26.1%    15636    15636      0     0.19     0.05      0.00   4.4%
 155 setattr       4.0%   4.1%     2443     2443      0     1.54    10.17      0.13   5.5%
 156 lookup       24.0%  23.9%    14314    14314      0     0.20     0.06      0.00   4.2%
 157 readlink      1.0%   1.0%      623      623      0     0.19     0.07      0.02   0.2%
 158 read         18.0%  18.1%    10842    13425      0     2.01     4.42      0.04  31.9%
 159 write        10.0%   9.9%     5963     7271      0     3.42    12.67      0.09  29.8%
 160 create        1.0%   1.0%      599      599      0     5.02    38.61      0.50   4.4%
 161 remove        1.0%   1.0%      622      622      0     3.22    23.31      0.38   2.9%
 162 readdir       1.0%   1.0%      604      604      0     0.40     0.12      0.03   0.4%
 163 fsstat        1.0%   1.0%      616      616      0     0.19     0.05      0.02   0.2%
 164 access       11.0%  10.9%     6547     6547      0     0.19     0.04      0.01   1.8%
 165 commit        0.0%   0.0%        0     2598      0     0.00     0.00      0.00  13.4%
 166 readdirplus   2.0%   2.0%     1206     1206      0     0.65     0.07      0.01   1.1%
 167 -------------------------------------------------------------------------------------
 168
 169         ---------------------------------------------
 170         |  SPEC SFS 2008 AGGREGATE RESULTS SUMMARY  |
 171         ---------------------------------------------
 172 SFS NFS THROUGHPUT:     200 Ops/Sec   AVG. RESPONSE TIME:     1.1 Msec/Op
 173 TCP PROTOCOL (IPv4)
 174 NFS MIXFILE: [ SFS default ]
 175 AGGREGATE REQUESTED LOAD: 200 Ops/Sec
 176 TOTAL LOGICAL NFS OPERATIONS:  60015          TEST TIME: 300 Sec
 177 TOTAL PHYSICAL NFS OPERATIONS: 66504
 178 PHYSICAL NFS IO THROUGHPUT: 221 Ops/sec
 179 NUMBER OF SFS CLIENTS: 1
 180 TOTAL FILE SET SIZE CREATED: 23563.1 MB
 181 TOTAL FILE SET SIZE ACCESSED: 7077.2 - 7218.5 MB  (100.00% to 102.00% of Base)
 182
 183 ------------------------------------------------------------------------
 184
 185 Mon Apr 11 14:05:03 PDT 2011
 186 ************************************************************************
 187 Mon Apr 11 14:05:03 PDT 2011
 188 ************************************************************************
 189 Mon Apr 11 14:05:03 PDT 2011 Test Run 3 of 15
 190
 191 Mon Apr 11 14:05:03 PDT 2011 Mon Apr 11 14:05:03 PDT 2011 vrable1.sysnet.ucsd.edu start:
 192 Mon Apr 11 14:05:03 PDT 2011         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
 193
 194 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 195 Executing SFS Benchmark on 1 Client(s).
 196 Mon Apr 11 14:05:18 2011 Waiting on DONE-MOUNT message from 1 client(s).
 197 Mon Apr 11 14:05:19 2011 Received.
 198 Mon Apr 11 14:05:19 2011 Sending DO-INIT message to 1 client(s).
 199 Mon Apr 11 14:05:19 2011 Completed.
 200 Mon Apr 11 14:05:19 2011 Waiting on DONE-INIT message from 1 client(s) (timeout is 8000 seconds).
 201 Mon Apr 11 14:20:54 2011 Received.
 202 Mon Apr 11 14:20:54 2011 Sending DO-WARMUP message to 1 client(s).
 203 Mon Apr 11 14:20:54 2011 Completed.
 204 Mon Apr 11 14:20:54 2011 Waiting on READY message from 1 client(s).
 205 Mon Apr 11 14:25:55 2011 Received.
 206 Mon Apr 11 14:25:55 2011 Sending START message to 1 client(s).
 207 Mon Apr 11 14:25:55 2011 Completed.
 208 Mon Apr 11 14:30:55 2011 Sending STOP message to 1 client(s).
 209 Mon Apr 11 14:30:55 2011 Completed.
 210 Mon Apr 11 14:30:55 2011 Waiting on DONE-TEST message from 1 client(s).
 211 Mon Apr 11 14:31:06 2011 Received.
 212 Mon Apr 11 14:31:06 2011 Sending MOVE-DATA message to 1 client(s).
 213 Mon Apr 11 14:31:06 2011 Completed.
 214 Mon Apr 11 14:31:06 2011 Waiting on SEND-DATA message from 1 client(s).
 215 Mon Apr 11 14:31:07 2011 Received.
 216 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 217 Started on client (c09-44): ../binaries/linux-x86/sfs_prime -l 300 -C ../result/sfssum.20110411-azure-hi16 -t 300 -a 30 -A 70 -R 8 -W 8 -Q -p 16 -w 300 -X 8000 vrable1.sysnet.ucsd.edu
 218
 219 Aggregate Test Parameters:
 220     Number of processes = 16
 221     Requested Load (NFS operations/second) = 300
 222     Maximum number of outstanding biod writes = 8
 223     Maximum number of outstanding biod reads = 8
 224     Warm-up time (seconds) = 300
 225     Run time (seconds) = 300
 226     File Set =  69136 Files created for I/O operations
 227                 20736 Files accessed for I/O operations
 228                  1392 Files for non-I/O operations
 229                    32 Symlinks
 230                  2304 Directories
 231                       Additional non-I/O files created as necessary
 232
 233 SFS Aggregate Results for 1 Client(s), Mon Apr 11 14:31:07 2011
 234 NFS Protocol Version 3
 235
 236 -------------------------------------------------------------------------------------
 237 NFS         Target Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 238 Op          NFS    NFS     Logical Physical  Op     Response Response of Mean,  of
 239 Type        Mix    Mix     Success Success   Error  Time     Time     95% Conf  Total
 240             Pcnt   Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 241 -------------------------------------------------------------------------------------
 242 getattr      26.0%  26.0%    23377    23377      0     0.19     0.05      0.00   3.3%
 243 setattr       4.0%   4.0%     3598     3598      0     2.03    10.76      0.11   5.4%
 244 lookup       24.0%  24.0%    21650    21650      0     0.20     0.05      0.00   3.2%
 245 readlink      1.0%   1.0%      914      914      0     0.19     0.05      0.01   0.1%
 246 read         18.0%  17.9%    16126    19763      0     3.34    22.89      0.07  39.7%
 247 write        10.0%  10.1%     9132    11173      0     4.42    17.99      0.09  29.7%
 248 create        1.0%   1.0%      939      939      0     4.59    11.51      0.22   3.2%
 249 remove        1.0%   1.0%      872      872      0     3.72    19.37      0.29   2.4%
 250 readdir       1.0%   0.9%      855      855      0     0.40     0.10      0.02   0.3%
 251 fsstat        1.0%   1.0%      922      922      0     0.19     0.06      0.02   0.1%
 252 access       11.0%  11.0%     9872     9872      0     0.19     0.07      0.01   1.4%
 253 commit        0.0%   0.0%        0     4017      0     0.00     0.00      0.00  10.4%
 254 readdirplus   2.0%   2.0%     1777     1777      0     0.69     0.07      0.01   0.9%
 255 -------------------------------------------------------------------------------------
 256
 257         ---------------------------------------------
 258         |  SPEC SFS 2008 AGGREGATE RESULTS SUMMARY  |
 259         ---------------------------------------------
 260 SFS NFS THROUGHPUT:     300 Ops/Sec   AVG. RESPONSE TIME:     1.5 Msec/Op
 261 TCP PROTOCOL (IPv4)
 262 NFS MIXFILE: [ SFS default ]
 263 AGGREGATE REQUESTED LOAD: 300 Ops/Sec
 264 TOTAL LOGICAL NFS OPERATIONS:  90034          TEST TIME: 300 Sec
 265 TOTAL PHYSICAL NFS OPERATIONS: 99729
 266 PHYSICAL NFS IO THROUGHPUT: 332 Ops/sec
 267 NUMBER OF SFS CLIENTS: 1
 268 TOTAL FILE SET SIZE CREATED: 35850.8 MB
 269 TOTAL FILE SET SIZE ACCESSED: 10761.0 - 10973.8 MB  (100.00% to 101.98% of Base)
 270
 271 ------------------------------------------------------------------------
 272
 273 Mon Apr 11 14:31:08 PDT 2011
 274 ************************************************************************
 275 Mon Apr 11 14:31:08 PDT 2011
 276 ************************************************************************
 277 Mon Apr 11 14:31:08 PDT 2011 Test Run 4 of 15
 278
 279 Mon Apr 11 14:31:08 PDT 2011 Mon Apr 11 14:31:08 PDT 2011 vrable1.sysnet.ucsd.edu start:
 280 Mon Apr 11 14:31:08 PDT 2011         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
 281
 282 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 283 Executing SFS Benchmark on 1 Client(s).
 284 Mon Apr 11 14:31:23 2011 Waiting on DONE-MOUNT message from 1 client(s).
 285 Mon Apr 11 14:31:24 2011 Received.
 286 Mon Apr 11 14:31:24 2011 Sending DO-INIT message to 1 client(s).
 287 Mon Apr 11 14:31:24 2011 Completed.
 288 Mon Apr 11 14:31:24 2011 Waiting on DONE-INIT message from 1 client(s) (timeout is 8000 seconds).
 289 Mon Apr 11 14:42:43 2011 Received.
 290 Mon Apr 11 14:42:43 2011 Sending DO-WARMUP message to 1 client(s).
 291 Mon Apr 11 14:42:43 2011 Completed.
 292 Mon Apr 11 14:42:43 2011 Waiting on READY message from 1 client(s).
 293 Mon Apr 11 14:47:44 2011 Received.
 294 Mon Apr 11 14:47:44 2011 Sending START message to 1 client(s).
 295 Mon Apr 11 14:47:44 2011 Completed.
 296 Mon Apr 11 14:52:44 2011 Sending STOP message to 1 client(s).
 297 Mon Apr 11 14:52:44 2011 Completed.
 298 Mon Apr 11 14:52:44 2011 Waiting on DONE-TEST message from 1 client(s).
 299 Mon Apr 11 14:52:55 2011 Received.
 300 Mon Apr 11 14:52:55 2011 Sending MOVE-DATA message to 1 client(s).
 301 Mon Apr 11 14:52:55 2011 Completed.
 302 Mon Apr 11 14:52:55 2011 Waiting on SEND-DATA message from 1 client(s).
 303 Mon Apr 11 14:52:56 2011 Received.
 304 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 305 Started on client (c09-44): ../binaries/linux-x86/sfs_prime -l 400 -C ../result/sfssum.20110411-azure-hi16 -t 300 -a 30 -A 70 -R 8 -W 8 -Q -p 16 -w 300 -X 8000 vrable1.sysnet.ucsd.edu
 306
 307 Aggregate Test Parameters:
 308     Number of processes = 16
 309     Requested Load (NFS operations/second) = 400
 310     Maximum number of outstanding biod writes = 8
 311     Maximum number of outstanding biod reads = 8
 312     Warm-up time (seconds) = 300
 313     Run time (seconds) = 300
 314     File Set =  90864 Files created for I/O operations
 315                 27248 Files accessed for I/O operations
 316                  1824 Files for non-I/O operations
 317                    32 Symlinks
 318                  3024 Directories
 319                       Additional non-I/O files created as necessary
 320
 321 SFS Aggregate Results for 1 Client(s), Mon Apr 11 14:52:56 2011
 322 NFS Protocol Version 3
 323
 324 -------------------------------------------------------------------------------------
 325 NFS         Target Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 326 Op          NFS    NFS     Logical Physical  Op     Response Response of Mean,  of
 327 Type        Mix    Mix     Success Success   Error  Time     Time     95% Conf  Total
 328             Pcnt   Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 329 -------------------------------------------------------------------------------------
 330 getattr      26.0%  25.9%    31143    31143      0     0.21     1.28      0.01   2.4%
 331 setattr       4.0%   4.0%     4780     4780      0     2.54     6.36      0.07   4.6%
 332 lookup       24.0%  23.9%    28644    28644      0     0.22     1.40      0.01   2.3%
 333 readlink      1.0%   1.0%     1226     1226      0     0.20     0.08      0.02   0.1%
 334 read         18.0%  18.2%    21816    26838      0     4.65    32.88      0.08  38.3%
 335 write        10.0%  10.1%    12117    14882      0     8.18    24.56      0.09  37.5%
 336 create        1.0%   1.0%     1174     1174      0     5.40     7.05      0.15   2.4%
 337 remove        1.0%   1.0%     1211     1211      0     4.10     6.29      0.14   1.9%
 338 readdir       1.0%   1.0%     1179     1179      0     0.40     0.06      0.01   0.2%
 339 fsstat        1.0%   1.0%     1255     1255      0     0.19     0.04      0.01   0.1%
 340 access       11.0%  10.9%    13148    13148      0     0.22     1.90      0.02   1.1%
 341 commit        0.0%   0.0%        0     5331      0     0.00     0.00      0.00   8.5%
 342 readdirplus   2.0%   2.0%     2392     2392      0     0.70     0.08      0.01   0.6%
 343 -------------------------------------------------------------------------------------
 344
 345         ---------------------------------------------
 346         |  SPEC SFS 2008 AGGREGATE RESULTS SUMMARY  |
 347         ---------------------------------------------
 348 SFS NFS THROUGHPUT:     400 Ops/Sec   AVG. RESPONSE TIME:     2.2 Msec/Op
 349 TCP PROTOCOL (IPv4)
 350 NFS MIXFILE: [ SFS default ]
 351 AGGREGATE REQUESTED LOAD: 400 Ops/Sec
 352 TOTAL LOGICAL NFS OPERATIONS:  120085         TEST TIME: 300 Sec
 353 TOTAL PHYSICAL NFS OPERATIONS: 133203
 354 PHYSICAL NFS IO THROUGHPUT: 444 Ops/sec
 355 NUMBER OF SFS CLIENTS: 1
 356 TOTAL FILE SET SIZE CREATED: 47118.0 MB
 357 TOTAL FILE SET SIZE ACCESSED: 14137.9 - 14418.6 MB  (100.00% to 101.99% of Base)
 358
 359 ------------------------------------------------------------------------
 360
 361 Mon Apr 11 14:52:57 PDT 2011
 362 ************************************************************************
 363 Mon Apr 11 14:52:57 PDT 2011
 364 ************************************************************************
 365 Mon Apr 11 14:52:57 PDT 2011 Test Run 5 of 15
 366
 367 Mon Apr 11 14:52:57 PDT 2011 Mon Apr 11 14:52:57 PDT 2011 vrable1.sysnet.ucsd.edu start:
 368 Mon Apr 11 14:52:57 PDT 2011         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
 369
 370 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 371 Executing SFS Benchmark on 1 Client(s).
 372 Mon Apr 11 14:53:12 2011 Waiting on DONE-MOUNT message from 1 client(s).
 373 Mon Apr 11 14:53:13 2011 Received.
 374 Mon Apr 11 14:53:13 2011 Sending DO-INIT message to 1 client(s).
 375 Mon Apr 11 14:53:13 2011 Completed.
 376 Mon Apr 11 14:53:13 2011 Waiting on DONE-INIT message from 1 client(s) (timeout is 8000 seconds).
 377 Mon Apr 11 15:05:07 2011 Received.
 378 Mon Apr 11 15:05:07 2011 Sending DO-WARMUP message to 1 client(s).
 379 Mon Apr 11 15:05:07 2011 Completed.
 380 Mon Apr 11 15:05:07 2011 Waiting on READY message from 1 client(s).
 381 Mon Apr 11 15:10:08 2011 Received.
 382 Mon Apr 11 15:10:08 2011 Sending START message to 1 client(s).
 383 Mon Apr 11 15:10:08 2011 Completed.
 384 Mon Apr 11 15:15:08 2011 Sending STOP message to 1 client(s).
 385 Mon Apr 11 15:15:08 2011 Completed.
 386 Mon Apr 11 15:15:08 2011 Waiting on DONE-TEST message from 1 client(s).
 387 Mon Apr 11 15:15:19 2011 Received.
 388 Mon Apr 11 15:15:19 2011 Sending MOVE-DATA message to 1 client(s).
 389 Mon Apr 11 15:15:19 2011 Completed.
 390 Mon Apr 11 15:15:19 2011 Waiting on SEND-DATA message from 1 client(s).
 391 Mon Apr 11 15:15:20 2011 Received.
 392 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 393 Started on client (c09-44): ../binaries/linux-x86/sfs_prime -l 500 -C ../result/sfssum.20110411-azure-hi16 -t 300 -a 30 -A 70 -R 8 -W 8 -Q -p 16 -w 300 -X 8000 vrable1.sysnet.ucsd.edu
 394
 395 Aggregate Test Parameters:
 396     Number of processes = 16
 397     Requested Load (NFS operations/second) = 500
 398     Maximum number of outstanding biod writes = 8
 399     Maximum number of outstanding biod reads = 8
 400     Warm-up time (seconds) = 300
 401     Run time (seconds) = 300
 402     File Set = 114560 Files created for I/O operations
 403                 34368 Files accessed for I/O operations
 404                  2304 Files for non-I/O operations
 405                    32 Symlinks
 406                  3808 Directories
 407                       Additional non-I/O files created as necessary
 408
 409 SFS Aggregate Results for 1 Client(s), Mon Apr 11 15:15:20 2011
 410 NFS Protocol Version 3
 411
 412 -------------------------------------------------------------------------------------
 413 NFS         Target Actual  NFS Op  NFS Op    NFS    Mean     Std Dev  Std Error Pcnt
 414 Op          NFS    NFS     Logical Physical  Op     Response Response of Mean,  of
 415 Type        Mix    Mix     Success Success   Error  Time     Time     95% Conf  Total
 416             Pcnt   Pcnt    Count   Count     Count  Msec/Op  Msec/Op  +-Msec/Op Time
 417 -------------------------------------------------------------------------------------
 418 getattr      26.0%  26.0%    39100    39100      0     0.24     7.87      0.03   1.5%
 419 setattr       4.0%   4.0%     5949     5949      0    11.94   120.51      0.28  11.1%
 420 lookup       24.0%  23.8%    35799    35799      0     0.24     6.20      0.03   1.4%
 421 readlink      1.0%   1.0%     1479     1479      0     0.20     0.12      0.02   0.0%
 422 read         18.0%  18.1%    27284    33651      0     7.85    64.45      0.10  33.5%
 423 write        10.0%  10.1%    15135    18361      0    11.83    54.05      0.12  28.0%
 424 create        1.0%   1.0%     1521     1521      0    14.24   112.01      0.53   3.4%
 425 remove        1.0%   1.0%     1478     1478      0    15.64   135.21      0.59   3.6%
 426 readdir       1.0%   1.0%     1516     1516      0     0.42     0.13      0.02   0.1%
 427 fsstat        1.0%   1.0%     1485     1485      0     0.21     0.28      0.03   0.0%
 428 access       11.0%  11.0%    16529    16529      0     0.20     0.09      0.00   0.5%
 429 commit        0.0%   0.0%        0     6650      0     0.00     0.00      0.00  16.6%
 430 readdirplus   2.0%   2.0%     3056     3056      0     0.73     0.54      0.03   0.3%
 431 -------------------------------------------------------------------------------------
 432
 433         ---------------------------------------------
 434         |  SPEC SFS 2008 AGGREGATE RESULTS SUMMARY  |
 435         ---------------------------------------------
 436 SFS NFS THROUGHPUT:     503 Ops/Sec   AVG. RESPONSE TIME:     4.2 Msec/Op
 437 TCP PROTOCOL (IPv4)
 438 NFS MIXFILE: [ SFS default ]
 439 AGGREGATE REQUESTED LOAD: 500 Ops/Sec
 440 TOTAL LOGICAL NFS OPERATIONS:  150331         TEST TIME: 299 Sec
 441 TOTAL PHYSICAL NFS OPERATIONS: 166574
 442 PHYSICAL NFS IO THROUGHPUT: 557 Ops/sec
 443 NUMBER OF SFS CLIENTS: 1
 444 TOTAL FILE SET SIZE CREATED: 59405.6 MB
 445 TOTAL FILE SET SIZE ACCESSED: 17830.0 - 18176.1 MB  (100.00% to 101.94% of Base)
 446
 447 ------------------------------------------------------------------------
 448
 449 Mon Apr 11 15:15:21 PDT 2011
 450 ************************************************************************
 451 Mon Apr 11 15:15:21 PDT 2011
 452 ************************************************************************
 453 Mon Apr 11 15:15:21 PDT 2011 Test Run 6 of 15
 454
 455 Mon Apr 11 15:15:21 PDT 2011 Mon Apr 11 15:15:21 PDT 2011 vrable1.sysnet.ucsd.edu start:
 456 Mon Apr 11 15:15:21 PDT 2011         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
 457
 458 SPEC SFS Benchmark Version 2008, Creation - 1 February 2008
 459 Executing SFS Benchmark on 1 Client(s).
 460 Mon Apr 11 15:15:36 2011 Waiting on DONE-MOUNT message from 1 client(s).
 461 Mon Apr 11 15:15:37 2011 Received.
 462 Mon Apr 11 15:15:37 2011 Sending DO-INIT message to 1 client(s).
 463 Mon Apr 11 15:15:37 2011 Completed.
 464 Mon Apr 11 15:15:37 2011 Waiting on DONE-INIT message from 1 client(s) (timeout is 8000 seconds).
 465 Mon Apr 11 15:26:46 2011 Received.
 466 Mon Apr 11 15:26:46 2011 Sending DO-WARMUP message to 1 client(s).
 467 Mon Apr 11 15:26:46 2011 Completed.
 468 Mon Apr 11 15:26:46 2011 Waiting on READY message from 1 client(s).
 469 Mon Apr 11 15:31:47 2011 Received.
 470 Mon Apr 11 15:31:47 2011 Sending START message to 1 client(s).
 471 Mon Apr 11 15:31:47 2011 Completed.
 472 Mon Apr 11 15:36:47 2011 Sending STOP message to 1 client(s).
 473
 474 ../binaries/linux-x86/sfs_prime: Unable to perform remote procedure on vrable1.sysnet.ucsd.edu .
 475 Error calling signal_sfs_client. Exit(16)
 476 Mon Apr 11 15:36:48 PDT 2011 ERROR: SfsManager: sfs_prime returned error code: 16, exiting