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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 16:00:45 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 16:00:45 2012 Mounting 16 remote test directories. Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Sat Jan 21 16:01:03 2012 Completed. Sat Jan 21 16:01:03 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:01:03 2012 Completed. Sat Jan 21 16:01:03 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:01:03 2012 Received. Sat Jan 21 16:01:03 2012 Initializing test directories. Sat Jan 21 16:01:03 2012 Child 7 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 15 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 4 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 14 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 2 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 3 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 10 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 8 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 9 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 11 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 13 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 0 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 12 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 1 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 5 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 6 will create 46 directories. Sat Jan 21 16:01:03 2012 Child 13 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 13 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 8 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 8 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 12 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 12 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 9 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 9 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 11 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 11 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 6 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 6 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 5 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 5 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 1 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 1 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 4 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 4 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 3 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 3 will create 1386 files. Sat Jan 21 16:01:03 2012 Child 10 finished creating 46 directories. Sat Jan 21 16:01:03 2012 Child 10 will create 1386 files. Sat Jan 21 16:01:04 2012 Child 0 finished creating 46 directories. Sat Jan 21 16:01:04 2012 Child 0 will create 1386 files. Sat Jan 21 16:01:04 2012 Child 2 finished creating 46 directories. Sat Jan 21 16:01:04 2012 Child 2 will create 1386 files. Sat Jan 21 16:01:04 2012 Child 15 finished creating 46 directories. Sat Jan 21 16:01:04 2012 Child 15 will create 1386 files. Sat Jan 21 16:01:04 2012 Child 7 finished creating 46 directories. Sat Jan 21 16:01:04 2012 Child 7 will create 1386 files. Sat Jan 21 16:01:04 2012 Child 14 finished creating 46 directories. Sat Jan 21 16:01:04 2012 Child 14 will create 1386 files. Sat Jan 21 16:06:50 2012 Child 4 verified 561 files (561 creates). Sat Jan 21 16:07:25 2012 Child 11 verified 504 files (504 creates). Sat Jan 21 16:07:33 2012 Child 1 verified 504 files (504 creates). Sat Jan 21 16:07:33 2012 Child 5 verified 504 files (504 creates). Sat Jan 21 16:07:33 2012 Child 6 verified 504 files (504 creates). Sat Jan 21 16:07:33 2012 Child 0 verified 504 files (504 creates). Sat Jan 21 16:07:33 2012 Child 2 verified 504 files (504 creates). Sat Jan 21 16:07:33 2012 Child 14 verified 374 files (374 creates). Sat Jan 21 16:07:33 2012 Child 12 verified 374 files (374 creates). Sat Jan 21 16:07:33 2012 Child 3 verified 504 files (504 creates). Sat Jan 21 16:08:35 2012 Child 10 verified 504 files (504 creates). Sat Jan 21 16:08:35 2012 Child 8 verified 504 files (504 creates). Sat Jan 21 16:08:35 2012 Child 15 verified 504 files (504 creates). Sat Jan 21 16:08:35 2012 Child 13 verified 597 files (597 creates). Sat Jan 21 16:08:36 2012 Child 7 verified 374 files (374 creates). Sat Jan 21 16:08:39 2012 Child 9 verified 504 files (504 creates). Sat Jan 21 16:11:47 2012 Child 4 verified 1095 files (1095 creates). Sat Jan 21 16:14:26 2012 Child 11 verified 1059 files (1059 creates). Sat Jan 21 16:14:26 2012 Child 15 verified 996 files (996 creates). Sat Jan 21 16:14:26 2012 Child 0 verified 1056 files (1056 creates). Sat Jan 21 16:14:26 2012 Child 6 verified 1059 files (1059 creates). Sat Jan 21 16:14:26 2012 Child 7 verified 949 files (949 creates). Sat Jan 21 16:14:26 2012 Child 13 verified 1062 files (1062 creates). Sat Jan 21 16:14:30 2012 Child 3 verified 1117 files (1117 creates). Sat Jan 21 16:14:30 2012 Child 2 verified 1118 files (1118 creates). Sat Jan 21 16:14:30 2012 Child 1 verified 1155 files (1155 creates). Sat Jan 21 16:14:30 2012 Child 12 verified 996 files (996 creates). Sat Jan 21 16:14:30 2012 Child 10 verified 996 files (996 creates). Sat Jan 21 16:14:30 2012 Child 8 verified 996 files (996 creates). Sat Jan 21 16:14:30 2012 Child 5 verified 1155 files (1155 creates). Sat Jan 21 16:14:30 2012 Child 9 verified 996 files (996 creates). Sat Jan 21 16:14:30 2012 Child 14 verified 996 files (996 creates). Sat Jan 21 16:14:42 2012 Child 4 verified 1386 files, created 1373 Sat Jan 21 16:14:42 2012 Child 4 will create 2 symlinks. Sat Jan 21 16:14:42 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 16:14:52 2012 Child 5 verified 1386 files, created 1373 Sat Jan 21 16:14:52 2012 Child 5 will create 2 symlinks. Sat Jan 21 16:14:52 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 16:14:53 2012 Child 1 verified 1386 files, created 1373 Sat Jan 21 16:14:53 2012 Child 1 will create 2 symlinks. Sat Jan 21 16:14:57 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 16:14:58 2012 Child 2 verified 1386 files, created 1373 Sat Jan 21 16:14:58 2012 Child 2 will create 2 symlinks. Sat Jan 21 16:14:58 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 16:14:58 2012 Child 3 verified 1386 files, created 1373 Sat Jan 21 16:14:58 2012 Child 3 will create 2 symlinks. Sat Jan 21 16:14:58 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 16:15:02 2012 Child 13 verified 1386 files, created 1373 Sat Jan 21 16:15:02 2012 Child 13 will create 2 symlinks. Sat Jan 21 16:15:02 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 16:15:02 2012 Child 6 verified 1386 files, created 1373 Sat Jan 21 16:15:02 2012 Child 6 will create 2 symlinks. Sat Jan 21 16:15:02 2012 Child 11 verified 1386 files, created 1373 Sat Jan 21 16:15:02 2012 Child 11 will create 2 symlinks. Sat Jan 21 16:15:02 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 16:15:02 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 16:15:03 2012 Child 0 verified 1386 files, created 1373 Sat Jan 21 16:15:03 2012 Child 0 will create 2 symlinks. Sat Jan 21 16:15:03 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 8 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 8 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 14 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 14 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 10 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 10 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 15 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 15 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 12 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 12 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 9 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 9 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 16:15:05 2012 Child 7 verified 1386 files, created 1373 Sat Jan 21 16:15:05 2012 Child 7 will create 2 symlinks. Sat Jan 21 16:15:05 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 16:15:06 2012 Completed. Sat Jan 21 16:15:06 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:15:06 2012 Completed. Sat Jan 21 16:15:06 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:15:07 2012 Received. Sat Jan 21 16:15:07 2012 Performing 300 seconds pretest warmup. Sat Jan 21 16:20:07 2012 Completed. Sat Jan 21 16:20:07 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:20:07 2012 Completed. Sat Jan 21 16:20:07 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:20:08 2012 Received. Sat Jan 21 16:20:08 2012 Starting 300 seconds test run. Child 4 - 50 aborted ops Child 4 - 100 aborted ops SFS Single Client (c09-44) Results, Sat Jan 21 16:25:18 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.9% 7752 7752 0 0.19 0.03 0.00 5.0% setattr 4.0% 4.0% 1190 1190 0 0.97 1.24 0.06 3.9% lookup 24.0% 23.7% 7103 7103 0 0.20 0.03 0.00 4.7% readlink 1.0% 1.0% 302 302 0 0.19 0.02 0.02 0.2% read 18.0% 18.1% 5442 6647 0 1.32 3.24 0.05 24.4% write 10.0% 10.2% 3053 3706 0 4.25 16.94 0.15 44.0% create 1.0% 1.0% 302 302 0 2.01 1.69 0.15 2.1% remove 1.0% 1.0% 297 297 0 1.24 1.24 0.13 1.2% readdir 1.0% 1.0% 287 287 0 0.36 0.08 0.03 0.3% fsstat 1.0% 1.0% 292 292 0 0.19 0.03 0.02 0.2% access 11.0% 11.1% 3339 3339 0 0.19 0.04 0.01 2.2% commit 0.0% 0.0% 0 1342 0 0.00 0.00 0.00 10.5% readdirplus 2.0% 2.1% 629 629 0 0.56 0.04 0.02 1.2% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 99.96 Ops/Sec AVG. RESPONSE TIME: 0.98 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 100 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 29988 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 33188 PHYSICAL NFS V3 THROUGHPUT: 110.62 Ops/Sec FILE SET SIZE CREATED: 11537568.0 KB FILE SET SIZE ACCESSED: 3466368.0 - 3536574.0 KB (100.00% to 102.03% of Base) ------------------------------------------------------------------------ Sat Jan 21 16:25:18 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 100 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 21728 Files created for I/O operations 6512 Files accessed for I/O operations 448 Files for non-I/O operations 32 Symlinks 720 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 16:25:18 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:25:18 2012 Completed. Sat Jan 21 16:25:18 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 16:25:19 2012 Received. Sat Jan 21 16:25:19 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 16:25:19 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 16:25:21 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 16:25:21 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 16:25:35 2012 Completed. Sat Jan 21 16:25:35 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:25:35 2012 Completed. Sat Jan 21 16:25:35 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:25:37 2012 Received. Sat Jan 21 16:25:37 2012 Initializing test directories. Sat Jan 21 16:25:37 2012 Child 9 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 15 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 4 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 0 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 14 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 13 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 2 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 11 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 10 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 6 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 8 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 3 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 7 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 12 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 5 will create 95 directories. Sat Jan 21 16:25:37 2012 Child 1 will create 95 directories. Sat Jan 21 16:25:38 2012 Child 15 finished creating 95 directories. Sat Jan 21 16:25:38 2012 Child 15 will create 2897 files. Sat Jan 21 16:25:38 2012 Child 1 finished creating 95 directories. Sat Jan 21 16:25:38 2012 Child 1 will create 2897 files. Sat Jan 21 16:25:38 2012 Child 0 finished creating 95 directories. Sat Jan 21 16:25:38 2012 Child 0 will create 2897 files. Sat Jan 21 16:25:38 2012 Child 3 finished creating 95 directories. Sat Jan 21 16:25:38 2012 Child 3 will create 2897 files. Sat Jan 21 16:25:38 2012 Child 12 finished creating 95 directories. Sat Jan 21 16:25:38 2012 Child 12 will create 2897 files. Sat Jan 21 16:25:38 2012 Child 5 finished creating 95 directories. Sat Jan 21 16:25:38 2012 Child 5 will create 2897 files. Sat Jan 21 16:25:39 2012 Child 11 finished creating 95 directories. Sat Jan 21 16:25:39 2012 Child 11 will create 2897 files. Sat Jan 21 16:25:39 2012 Child 13 finished creating 95 directories. Sat Jan 21 16:25:39 2012 Child 13 will create 2897 files. Sat Jan 21 16:25:41 2012 Child 7 finished creating 95 directories. Sat Jan 21 16:25:41 2012 Child 7 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 14 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 14 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 9 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 9 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 4 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 4 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 2 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 2 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 10 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 10 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 8 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 8 will create 2897 files. Sat Jan 21 16:25:42 2012 Child 6 finished creating 95 directories. Sat Jan 21 16:25:42 2012 Child 6 will create 2897 files. Sat Jan 21 16:32:08 2012 Child 6 verified 1638 files (280 creates). Sat Jan 21 16:32:08 2012 Child 9 verified 1770 files (412 creates). Sat Jan 21 16:32:09 2012 Child 7 verified 1734 files (376 creates). Sat Jan 21 16:32:09 2012 Child 8 verified 1640 files (282 creates). Sat Jan 21 16:32:09 2012 Child 2 verified 1639 files (281 creates). Sat Jan 21 16:32:09 2012 Child 10 verified 1657 files (299 creates). Sat Jan 21 16:32:09 2012 Child 13 verified 1774 files (416 creates). Sat Jan 21 16:32:09 2012 Child 3 verified 1774 files (416 creates). Sat Jan 21 16:32:09 2012 Child 5 verified 1774 files (416 creates). Sat Jan 21 16:32:09 2012 Child 11 verified 1774 files (416 creates). Sat Jan 21 16:32:09 2012 Child 12 verified 1774 files (416 creates). Sat Jan 21 16:32:09 2012 Child 0 verified 1823 files (465 creates). Sat Jan 21 16:32:51 2012 Child 15 verified 1823 files (465 creates). Sat Jan 21 16:32:51 2012 Child 1 verified 1823 files (465 creates). Sat Jan 21 16:32:51 2012 Child 14 verified 1774 files (416 creates). Sat Jan 21 16:32:51 2012 Child 4 verified 1774 files (416 creates). Sat Jan 21 16:37:26 2012 Child 5 verified 1907 files (549 creates). Sat Jan 21 16:37:26 2012 Child 9 verified 1904 files (546 creates). Sat Jan 21 16:37:26 2012 Child 6 verified 1823 files (465 creates). Sat Jan 21 16:37:26 2012 Child 0 verified 2048 files (690 creates). Sat Jan 21 16:37:26 2012 Child 2 verified 1812 files (454 creates). Sat Jan 21 16:37:26 2012 Child 10 verified 1784 files (426 creates). Sat Jan 21 16:37:26 2012 Child 12 verified 1907 files (549 creates). Sat Jan 21 16:37:26 2012 Child 7 verified 1866 files (508 creates). Sat Jan 21 16:37:26 2012 Child 3 verified 1907 files (549 creates). Sat Jan 21 16:37:26 2012 Child 13 verified 1907 files (549 creates). Sat Jan 21 16:37:26 2012 Child 11 verified 1866 files (508 creates). Sat Jan 21 16:37:26 2012 Child 8 verified 1823 files (465 creates). Sat Jan 21 16:38:16 2012 Child 15 verified 2134 files (776 creates). Sat Jan 21 16:38:16 2012 Child 4 verified 1976 files (618 creates). Sat Jan 21 16:38:19 2012 Child 14 verified 1907 files (549 creates). Sat Jan 21 16:39:46 2012 Child 1 verified 2141 files (783 creates). Sat Jan 21 16:42:56 2012 Child 8 verified 2265 files (907 creates). Sat Jan 21 16:42:56 2012 Child 7 verified 2351 files (993 creates). Sat Jan 21 16:42:56 2012 Child 11 verified 2345 files (987 creates). Sat Jan 21 16:42:56 2012 Child 6 verified 2267 files (909 creates). Sat Jan 21 16:43:16 2012 Child 2 verified 2141 files (783 creates). Sat Jan 21 16:43:16 2012 Child 5 verified 2370 files (1012 creates). Sat Jan 21 16:43:16 2012 Child 9 verified 2364 files (1006 creates). Sat Jan 21 16:43:16 2012 Child 13 verified 2370 files (1012 creates). Sat Jan 21 16:43:16 2012 Child 10 verified 2203 files (845 creates). Sat Jan 21 16:43:16 2012 Child 4 verified 2364 files (1006 creates). Sat Jan 21 16:43:16 2012 Child 12 verified 2370 files (1012 creates). Sat Jan 21 16:43:16 2012 Child 3 verified 2364 files (1006 creates). Sat Jan 21 16:43:16 2012 Child 0 verified 2364 files (1006 creates). Sat Jan 21 16:43:16 2012 Child 15 verified 2501 files (1143 creates). Sat Jan 21 16:44:26 2012 Child 14 verified 2370 files (1012 creates). Sat Jan 21 16:45:29 2012 Child 1 verified 2378 files (1020 creates). Sat Jan 21 16:48:00 2012 Child 11 verified 2404 files (1046 creates). Sat Jan 21 16:48:01 2012 Child 7 verified 2405 files (1047 creates). Sat Jan 21 16:48:01 2012 Child 8 verified 2388 files (1030 creates). Sat Jan 21 16:48:01 2012 Child 6 verified 2392 files (1034 creates). Sat Jan 21 16:49:16 2012 Child 2 verified 2346 files (988 creates). Sat Jan 21 16:49:16 2012 Child 15 verified 2660 files (1302 creates). Sat Jan 21 16:49:46 2012 Child 5 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 0 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 13 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 3 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 14 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 4 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 10 verified 2448 files (1090 creates). Sat Jan 21 16:49:46 2012 Child 12 verified 2501 files (1143 creates). Sat Jan 21 16:49:46 2012 Child 9 verified 2539 files (1181 creates). Sat Jan 21 16:50:50 2012 Child 1 verified 2539 files (1181 creates). Sat Jan 21 16:52:50 2012 Child 15 verified 2897 files, created 1511 Sat Jan 21 16:52:50 2012 Child 15 will create 2 symlinks. Sat Jan 21 16:52:50 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 16:52:58 2012 Child 11 verified 2696 files (1338 creates). Sat Jan 21 16:53:02 2012 Child 6 verified 2694 files (1336 creates). Sat Jan 21 16:53:02 2012 Child 8 verified 2708 files (1350 creates). Sat Jan 21 16:53:02 2012 Child 7 verified 2725 files (1367 creates). Sat Jan 21 16:55:01 2012 Child 5 verified 2797 files (1439 creates). Sat Jan 21 16:55:01 2012 Child 2 verified 2600 files (1242 creates). Sat Jan 21 16:55:09 2012 Child 0 verified 2741 files (1383 creates). Sat Jan 21 16:55:09 2012 Child 12 verified 2741 files (1383 creates). Sat Jan 21 16:55:09 2012 Child 13 verified 2741 files (1383 creates). Sat Jan 21 16:55:09 2012 Child 10 verified 2741 files (1383 creates). Sat Jan 21 16:55:14 2012 Child 4 verified 2741 files (1383 creates). Sat Jan 21 16:55:14 2012 Child 14 verified 2741 files (1383 creates). Sat Jan 21 16:55:14 2012 Child 3 verified 2741 files (1383 creates). Sat Jan 21 16:55:18 2012 Child 5 verified 2897 files, created 1511 Sat Jan 21 16:55:18 2012 Child 5 will create 2 symlinks. Sat Jan 21 16:55:19 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 16:55:19 2012 Child 9 verified 2897 files, created 1511 Sat Jan 21 16:55:19 2012 Child 9 will create 2 symlinks. Sat Jan 21 16:55:23 2012 Child 7 verified 2897 files, created 1511 Sat Jan 21 16:55:23 2012 Child 7 will create 2 symlinks. Sat Jan 21 16:55:23 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 16:55:24 2012 Child 13 verified 2897 files, created 1511 Sat Jan 21 16:55:24 2012 Child 13 will create 2 symlinks. Sat Jan 21 16:55:24 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 16:55:24 2012 Child 0 verified 2897 files, created 1511 Sat Jan 21 16:55:24 2012 Child 0 will create 2 symlinks. Sat Jan 21 16:55:26 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 16:55:28 2012 Child 11 verified 2897 files, created 1511 Sat Jan 21 16:55:28 2012 Child 11 will create 2 symlinks. Sat Jan 21 16:55:28 2012 Child 12 verified 2897 files, created 1511 Sat Jan 21 16:55:28 2012 Child 12 will create 2 symlinks. Sat Jan 21 16:55:29 2012 Child 10 verified 2897 files, created 1511 Sat Jan 21 16:55:29 2012 Child 10 will create 2 symlinks. Sat Jan 21 16:55:29 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 16:55:29 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 16:55:29 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 16:55:29 2012 Child 1 verified 2897 files, created 1511 Sat Jan 21 16:55:29 2012 Child 1 will create 2 symlinks. Sat Jan 21 16:55:29 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 16:55:30 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 16:55:31 2012 Child 6 verified 2897 files, created 1511 Sat Jan 21 16:55:31 2012 Child 6 will create 2 symlinks. Sat Jan 21 16:55:31 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 16:55:31 2012 Child 3 verified 2897 files, created 1511 Sat Jan 21 16:55:31 2012 Child 3 will create 2 symlinks. Sat Jan 21 16:55:31 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 16:55:32 2012 Child 2 verified 2897 files, created 1511 Sat Jan 21 16:55:32 2012 Child 2 will create 2 symlinks. Sat Jan 21 16:55:32 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 16:55:33 2012 Child 8 verified 2897 files, created 1511 Sat Jan 21 16:55:33 2012 Child 8 will create 2 symlinks. Sat Jan 21 16:55:33 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 16:56:26 2012 Child 14 verified 2897 files, created 1511 Sat Jan 21 16:56:26 2012 Child 14 will create 2 symlinks. Sat Jan 21 16:56:26 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 16:56:27 2012 Child 4 verified 2897 files, created 1511 Sat Jan 21 16:56:27 2012 Child 4 will create 2 symlinks. Sat Jan 21 16:56:27 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 16:56:27 2012 Completed. Sat Jan 21 16:56:27 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:56:27 2012 Completed. Sat Jan 21 16:56:27 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 16:56:27 2012 Received. Sat Jan 21 16:56:27 2012 Performing 300 seconds pretest warmup. Sat Jan 21 17:01:27 2012 Completed. Sat Jan 21 17:01:27 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:01:27 2012 Completed. Sat Jan 21 17:01:27 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:01:28 2012 Received. Sat Jan 21 17:01:28 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 17:06:38 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 26.1% 15635 15635 0 0.20 0.05 0.00 4.5% setattr 4.0% 4.1% 2443 2443 0 1.43 2.73 0.07 5.1% lookup 24.0% 23.9% 14314 14314 0 0.20 0.05 0.00 4.3% readlink 1.0% 1.0% 622 622 0 0.20 0.05 0.02 0.2% read 18.0% 18.1% 10841 13424 0 2.34 5.50 0.04 37.3% write 10.0% 9.9% 5963 7270 0 3.40 8.71 0.07 29.8% create 1.0% 1.0% 600 600 0 2.98 2.94 0.14 2.6% remove 1.0% 1.0% 622 622 0 1.92 1.47 0.10 1.8% readdir 1.0% 1.0% 604 604 0 0.40 0.07 0.02 0.4% fsstat 1.0% 1.0% 616 616 0 0.20 0.04 0.02 0.2% access 11.0% 10.9% 6546 6546 0 0.20 0.04 0.01 1.9% commit 0.0% 0.0% 0 2598 0 0.00 0.00 0.00 10.8% readdirplus 2.0% 2.0% 1206 1206 0 0.65 0.07 0.01 1.2% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 200.04 Ops/Sec AVG. RESPONSE TIME: 1.13 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 200 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 60012 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 66500 PHYSICAL NFS V3 THROUGHPUT: 221.66 Ops/Sec FILE SET SIZE CREATED: 24128640.0 KB FILE SET SIZE ACCESSED: 7247088.0 - 7391683.0 KB (100.00% to 102.00% of Base) ------------------------------------------------------------------------ Sat Jan 21 17:06:38 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 200 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 45440 Files created for I/O operations 13632 Files accessed for I/O operations 912 Files for non-I/O operations 32 Symlinks 1504 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 17:06:38 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:06:38 2012 Completed. Sat Jan 21 17:06:38 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 17:06:39 2012 Received. Sat Jan 21 17:06:39 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 17:06:39 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 17:06:41 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 17:06:41 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 17:06:55 2012 Completed. Sat Jan 21 17:06:55 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:06:55 2012 Completed. Sat Jan 21 17:06:55 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:06:57 2012 Received. Sat Jan 21 17:06:57 2012 Initializing test directories. Sat Jan 21 17:06:57 2012 Child 15 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 6 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 10 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 0 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 14 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 5 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 1 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 2 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 12 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 9 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 4 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 3 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 11 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 13 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 7 will create 145 directories. Sat Jan 21 17:06:57 2012 Child 8 will create 145 directories. Sat Jan 21 17:06:58 2012 Child 4 finished creating 145 directories. Sat Jan 21 17:06:58 2012 Child 4 will create 4408 files. Sat Jan 21 17:06:58 2012 Child 5 finished creating 145 directories. Sat Jan 21 17:06:58 2012 Child 5 will create 4408 files. Sat Jan 21 17:06:58 2012 Child 1 finished creating 145 directories. Sat Jan 21 17:06:58 2012 Child 1 will create 4408 files. Sat Jan 21 17:06:59 2012 Child 3 finished creating 145 directories. Sat Jan 21 17:06:59 2012 Child 3 will create 4408 files. Sat Jan 21 17:06:59 2012 Child 10 finished creating 145 directories. Sat Jan 21 17:06:59 2012 Child 10 will create 4408 files. Sat Jan 21 17:07:00 2012 Child 13 finished creating 145 directories. Sat Jan 21 17:07:00 2012 Child 13 will create 4408 files. Sat Jan 21 17:07:00 2012 Child 15 finished creating 145 directories. Sat Jan 21 17:07:00 2012 Child 15 will create 4408 files. Sat Jan 21 17:07:01 2012 Child 6 finished creating 145 directories. Sat Jan 21 17:07:01 2012 Child 6 will create 4408 files. Sat Jan 21 17:07:03 2012 Child 9 finished creating 145 directories. Sat Jan 21 17:07:03 2012 Child 9 will create 4408 files. Sat Jan 21 17:07:03 2012 Child 7 finished creating 145 directories. Sat Jan 21 17:07:03 2012 Child 7 will create 4408 files. Sat Jan 21 17:07:06 2012 Child 12 finished creating 145 directories. Sat Jan 21 17:07:06 2012 Child 12 will create 4408 files. Sat Jan 21 17:07:07 2012 Child 8 finished creating 145 directories. Sat Jan 21 17:07:07 2012 Child 8 will create 4408 files. Sat Jan 21 17:07:07 2012 Child 0 finished creating 145 directories. Sat Jan 21 17:07:07 2012 Child 0 will create 4408 files. Sat Jan 21 17:07:07 2012 Child 14 finished creating 145 directories. Sat Jan 21 17:07:07 2012 Child 14 will create 4408 files. Sat Jan 21 17:07:07 2012 Child 2 finished creating 145 directories. Sat Jan 21 17:07:07 2012 Child 2 will create 4408 files. Sat Jan 21 17:07:09 2012 Child 11 finished creating 145 directories. Sat Jan 21 17:07:09 2012 Child 11 will create 4408 files. Sat Jan 21 17:12:18 2012 Child 15 verified 3091 files (251 creates). Sat Jan 21 17:12:18 2012 Child 10 verified 3184 files (344 creates). Sat Jan 21 17:12:18 2012 Child 3 verified 3155 files (315 creates). Sat Jan 21 17:12:18 2012 Child 11 verified 2984 files (144 creates). Sat Jan 21 17:12:18 2012 Child 13 verified 3069 files (229 creates). Sat Jan 21 17:12:21 2012 Child 0 verified 3036 files (196 creates). Sat Jan 21 17:12:21 2012 Child 14 verified 3036 files (196 creates). Sat Jan 21 17:12:21 2012 Child 12 verified 3047 files (207 creates). Sat Jan 21 17:12:21 2012 Child 6 verified 3047 files (207 creates). Sat Jan 21 17:12:21 2012 Child 7 verified 3036 files (196 creates). Sat Jan 21 17:12:21 2012 Child 2 verified 3036 files (196 creates). Sat Jan 21 17:12:21 2012 Child 8 verified 3036 files (196 creates). Sat Jan 21 17:12:21 2012 Child 9 verified 3036 files (196 creates). Sat Jan 21 17:12:21 2012 Child 5 verified 3208 files (368 creates). Sat Jan 21 17:12:21 2012 Child 4 verified 3208 files (368 creates). Sat Jan 21 17:12:21 2012 Child 1 verified 3192 files (352 creates). Sat Jan 21 17:18:22 2012 Child 12 verified 3315 files (475 creates). Sat Jan 21 17:18:22 2012 Child 6 verified 3366 files (526 creates). Sat Jan 21 17:18:22 2012 Child 14 verified 3316 files (476 creates). Sat Jan 21 17:18:31 2012 Child 4 verified 3508 files (668 creates). Sat Jan 21 17:18:31 2012 Child 1 verified 3508 files (668 creates). Sat Jan 21 17:18:31 2012 Child 9 verified 3231 files (391 creates). Sat Jan 21 17:18:31 2012 Child 8 verified 3231 files (391 creates). Sat Jan 21 17:18:31 2012 Child 3 verified 3456 files (616 creates). Sat Jan 21 17:18:31 2012 Child 10 verified 3505 files (665 creates). Sat Jan 21 17:18:31 2012 Child 11 verified 3231 files (391 creates). Sat Jan 21 17:18:31 2012 Child 7 verified 3231 files (391 creates). Sat Jan 21 17:18:31 2012 Child 2 verified 3231 files (391 creates). Sat Jan 21 17:18:31 2012 Child 0 verified 3231 files (391 creates). Sat Jan 21 17:18:31 2012 Child 5 verified 3508 files (668 creates). Sat Jan 21 17:18:31 2012 Child 13 verified 3370 files (530 creates). Sat Jan 21 17:18:31 2012 Child 15 verified 3370 files (530 creates). Sat Jan 21 17:23:31 2012 Child 14 verified 3544 files (704 creates). Sat Jan 21 17:23:32 2012 Child 6 verified 3661 files (821 creates). Sat Jan 21 17:23:32 2012 Child 13 verified 3663 files (823 creates). Sat Jan 21 17:23:32 2012 Child 15 verified 3663 files (823 creates). Sat Jan 21 17:23:32 2012 Child 9 verified 3536 files (696 creates). Sat Jan 21 17:23:32 2012 Child 7 verified 3448 files (608 creates). Sat Jan 21 17:23:36 2012 Child 1 verified 3778 files (938 creates). Sat Jan 21 17:23:36 2012 Child 12 verified 3505 files (665 creates). Sat Jan 21 17:23:36 2012 Child 8 verified 3505 files (665 creates). Sat Jan 21 17:23:36 2012 Child 0 verified 3505 files (665 creates). Sat Jan 21 17:23:36 2012 Child 10 verified 3778 files (938 creates). Sat Jan 21 17:23:36 2012 Child 3 verified 3743 files (903 creates). Sat Jan 21 17:23:36 2012 Child 4 verified 3778 files (938 creates). Sat Jan 21 17:23:36 2012 Child 5 verified 3778 files (938 creates). Sat Jan 21 17:23:36 2012 Child 2 verified 3505 files (665 creates). Sat Jan 21 17:23:36 2012 Child 11 verified 3505 files (665 creates). Sat Jan 21 17:28:28 2012 Child 14 verified 3947 files (1107 creates). Sat Jan 21 17:28:28 2012 Child 6 verified 4020 files (1180 creates). Sat Jan 21 17:28:33 2012 Child 15 verified 4100 files (1260 creates). Sat Jan 21 17:28:33 2012 Child 13 verified 4102 files (1262 creates). Sat Jan 21 17:28:33 2012 Child 7 verified 3858 files (1018 creates). Sat Jan 21 17:28:33 2012 Child 9 verified 3920 files (1080 creates). Sat Jan 21 17:28:34 2012 Child 1 verified 4150 files (1310 creates). Sat Jan 21 17:28:35 2012 Child 3 verified 4141 files (1301 creates). Sat Jan 21 17:28:36 2012 Child 5 verified 4165 files (1325 creates). Sat Jan 21 17:28:39 2012 Child 0 verified 3879 files (1039 creates). Sat Jan 21 17:28:39 2012 Child 4 verified 4171 files (1331 creates). Sat Jan 21 17:28:39 2012 Child 10 verified 4171 files (1331 creates). Sat Jan 21 17:28:39 2012 Child 2 verified 3874 files (1034 creates). Sat Jan 21 17:28:39 2012 Child 8 verified 3874 files (1034 creates). Sat Jan 21 17:28:39 2012 Child 12 verified 3886 files (1046 creates). Sat Jan 21 17:28:39 2012 Child 11 verified 3886 files (1046 creates). Sat Jan 21 17:28:57 2012 Child 1 verified 4408 files, created 1525 Sat Jan 21 17:28:57 2012 Child 1 will create 2 symlinks. Sat Jan 21 17:28:57 2012 Child 4 verified 4408 files, created 1525 Sat Jan 21 17:28:57 2012 Child 4 will create 2 symlinks. Sat Jan 21 17:28:57 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 17:28:57 2012 Child 10 verified 4408 files, created 1525 Sat Jan 21 17:28:57 2012 Child 10 will create 2 symlinks. Sat Jan 21 17:28:57 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 17:28:57 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 17:28:58 2012 Child 13 verified 4408 files, created 1525 Sat Jan 21 17:28:58 2012 Child 13 will create 2 symlinks. Sat Jan 21 17:28:58 2012 Child 3 verified 4408 files, created 1525 Sat Jan 21 17:28:58 2012 Child 3 will create 2 symlinks. Sat Jan 21 17:28:58 2012 Child 15 verified 4408 files, created 1525 Sat Jan 21 17:28:58 2012 Child 15 will create 2 symlinks. Sat Jan 21 17:28:58 2012 Child 5 verified 4408 files, created 1525 Sat Jan 21 17:28:58 2012 Child 5 will create 2 symlinks. Sat Jan 21 17:28:58 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 17:28:58 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 17:28:58 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 17:28:58 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 17:29:04 2012 Child 14 verified 4408 files, created 1525 Sat Jan 21 17:29:04 2012 Child 14 will create 2 symlinks. Sat Jan 21 17:29:04 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 17:29:06 2012 Child 9 verified 4408 files, created 1525 Sat Jan 21 17:29:06 2012 Child 9 will create 2 symlinks. Sat Jan 21 17:29:06 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 17:29:08 2012 Child 12 verified 4408 files, created 1525 Sat Jan 21 17:29:08 2012 Child 12 will create 2 symlinks. Sat Jan 21 17:29:08 2012 Child 11 verified 4408 files, created 1525 Sat Jan 21 17:29:08 2012 Child 11 will create 2 symlinks. Sat Jan 21 17:29:08 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 17:29:08 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 17:29:09 2012 Child 7 verified 4408 files, created 1525 Sat Jan 21 17:29:09 2012 Child 7 will create 2 symlinks. Sat Jan 21 17:29:09 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 17:29:53 2012 Child 6 verified 4408 files, created 1525 Sat Jan 21 17:29:53 2012 Child 6 will create 2 symlinks. Sat Jan 21 17:29:53 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 17:30:01 2012 Child 8 verified 4408 files, created 1525 Sat Jan 21 17:30:01 2012 Child 8 will create 2 symlinks. Sat Jan 21 17:30:01 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 17:30:01 2012 Child 2 verified 4408 files, created 1525 Sat Jan 21 17:30:01 2012 Child 2 will create 2 symlinks. Sat Jan 21 17:30:01 2012 Child 0 verified 4408 files, created 1525 Sat Jan 21 17:30:01 2012 Child 0 will create 2 symlinks. Sat Jan 21 17:30:01 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 17:30:01 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 17:30:01 2012 Completed. Sat Jan 21 17:30:01 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:30:01 2012 Completed. Sat Jan 21 17:30:01 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:30:02 2012 Received. Sat Jan 21 17:30:02 2012 Performing 300 seconds pretest warmup. Sat Jan 21 17:35:02 2012 Completed. Sat Jan 21 17:35:02 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:35:02 2012 Completed. Sat Jan 21 17:35:02 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:35:03 2012 Received. Sat Jan 21 17:35:03 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 17:40:13 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 26.0% 23365 23365 0 0.20 0.08 0.00 3.7% setattr 4.0% 4.0% 3592 3592 0 1.89 3.53 0.06 5.3% lookup 24.0% 24.1% 21680 21680 0 0.21 0.16 0.01 3.5% readlink 1.0% 1.0% 918 918 0 0.20 0.06 0.02 0.1% read 18.0% 17.9% 16097 19744 0 2.90 9.75 0.05 36.6% write 10.0% 10.1% 9124 11173 0 4.38 12.32 0.07 31.3% create 1.0% 1.0% 932 932 0 4.34 4.66 0.14 3.2% remove 1.0% 1.0% 871 871 0 3.16 3.88 0.13 2.2% readdir 1.0% 1.0% 856 856 0 0.41 0.08 0.02 0.3% fsstat 1.0% 1.0% 931 931 0 0.20 0.04 0.01 0.1% access 11.0% 11.0% 9879 9879 0 0.20 0.07 0.01 1.6% commit 0.0% 0.0% 0 4050 0 0.00 0.00 0.00 11.1% readdirplus 2.0% 2.0% 1790 1790 0 0.70 0.09 0.01 1.0% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 300.11 Ops/Sec AVG. RESPONSE TIME: 1.41 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 300 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 90035 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 99781 PHYSICAL NFS V3 THROUGHPUT: 332.60 Ops/Sec FILE SET SIZE CREATED: 36711216.0 KB FILE SET SIZE ACCESSED: 11019312.0 - 11238364.0 KB (100.00% to 101.99% of Base) ------------------------------------------------------------------------ Sat Jan 21 17:40:13 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 300 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 69136 Files created for I/O operations 20736 Files accessed for I/O operations 1392 Files for non-I/O operations 32 Symlinks 2304 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 17:40:13 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:40:13 2012 Completed. Sat Jan 21 17:40:13 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 17:40:14 2012 Received. Sat Jan 21 17:40:14 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 17:40:14 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 17:40:16 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 17:40:16 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 17:40:30 2012 Completed. Sat Jan 21 17:40:30 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:40:30 2012 Completed. Sat Jan 21 17:40:30 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:40:32 2012 Received. Sat Jan 21 17:40:32 2012 Initializing test directories. Sat Jan 21 17:40:32 2012 Child 7 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 15 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 6 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 0 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 8 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 10 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 5 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 11 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 1 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 9 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 3 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 13 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 12 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 2 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 14 will create 190 directories. Sat Jan 21 17:40:32 2012 Child 4 will create 190 directories. Sat Jan 21 17:40:33 2012 Child 2 finished creating 190 directories. Sat Jan 21 17:40:33 2012 Child 2 will create 5793 files. Sat Jan 21 17:40:34 2012 Child 15 finished creating 190 directories. Sat Jan 21 17:40:34 2012 Child 15 will create 5793 files. Sat Jan 21 17:40:35 2012 Child 6 finished creating 190 directories. Sat Jan 21 17:40:35 2012 Child 6 will create 5793 files. Sat Jan 21 17:40:36 2012 Child 10 finished creating 190 directories. Sat Jan 21 17:40:36 2012 Child 10 will create 5793 files. Sat Jan 21 17:40:37 2012 Child 7 finished creating 190 directories. Sat Jan 21 17:40:37 2012 Child 7 will create 5793 files. Sat Jan 21 17:40:38 2012 Child 0 finished creating 190 directories. Sat Jan 21 17:40:38 2012 Child 0 will create 5793 files. Sat Jan 21 17:40:38 2012 Child 8 finished creating 190 directories. Sat Jan 21 17:40:38 2012 Child 8 will create 5793 files. Sat Jan 21 17:40:38 2012 Child 13 finished creating 190 directories. Sat Jan 21 17:40:38 2012 Child 13 will create 5793 files. Sat Jan 21 17:40:46 2012 Child 14 finished creating 190 directories. Sat Jan 21 17:40:46 2012 Child 14 will create 5793 files. Sat Jan 21 17:40:46 2012 Child 12 finished creating 190 directories. Sat Jan 21 17:40:46 2012 Child 12 will create 5793 files. Sat Jan 21 17:40:46 2012 Child 4 finished creating 190 directories. Sat Jan 21 17:40:46 2012 Child 4 will create 5793 files. Sat Jan 21 17:40:46 2012 Child 11 finished creating 190 directories. Sat Jan 21 17:40:46 2012 Child 11 will create 5793 files. Sat Jan 21 17:40:46 2012 Child 9 finished creating 190 directories. Sat Jan 21 17:40:46 2012 Child 9 will create 5793 files. Sat Jan 21 17:40:46 2012 Child 5 finished creating 190 directories. Sat Jan 21 17:40:46 2012 Child 5 will create 5793 files. Sat Jan 21 17:40:48 2012 Child 3 finished creating 190 directories. Sat Jan 21 17:40:48 2012 Child 3 will create 5793 files. Sat Jan 21 17:40:48 2012 Child 1 finished creating 190 directories. Sat Jan 21 17:40:48 2012 Child 1 will create 5793 files. Sat Jan 21 17:45:49 2012 Child 15 verified 4883 files (562 creates). Sat Jan 21 17:45:49 2012 Child 6 verified 4883 files (562 creates). Sat Jan 21 17:45:49 2012 Child 2 verified 4929 files (608 creates). Sat Jan 21 17:45:50 2012 Child 10 verified 4808 files (487 creates). Sat Jan 21 17:45:51 2012 Child 0 verified 4749 files (428 creates). Sat Jan 21 17:45:51 2012 Child 8 verified 4756 files (435 creates). Sat Jan 21 17:45:51 2012 Child 13 verified 4708 files (387 creates). Sat Jan 21 17:45:51 2012 Child 11 verified 4525 files (204 creates). Sat Jan 21 17:45:51 2012 Child 4 verified 4525 files (204 creates). Sat Jan 21 17:45:51 2012 Child 7 verified 4777 files (456 creates). Sat Jan 21 17:45:51 2012 Child 9 verified 4525 files (204 creates). Sat Jan 21 17:45:51 2012 Child 1 verified 4514 files (193 creates). Sat Jan 21 17:45:51 2012 Child 12 verified 4525 files (204 creates). Sat Jan 21 17:45:51 2012 Child 5 verified 4525 files (204 creates). Sat Jan 21 17:45:51 2012 Child 14 verified 4525 files (204 creates). Sat Jan 21 17:45:52 2012 Child 3 verified 4514 files (193 creates). Sat Jan 21 17:50:57 2012 Child 7 verified 5109 files (788 creates). Sat Jan 21 17:50:57 2012 Child 6 verified 5166 files (845 creates). Sat Jan 21 17:50:57 2012 Child 3 verified 4872 files (551 creates). Sat Jan 21 17:50:57 2012 Child 10 verified 5142 files (821 creates). Sat Jan 21 17:50:57 2012 Child 1 verified 4855 files (534 creates). Sat Jan 21 17:51:07 2012 Child 8 verified 4991 files (670 creates). Sat Jan 21 17:51:09 2012 Child 0 verified 4991 files (670 creates). Sat Jan 21 17:51:09 2012 Child 12 verified 4886 files (565 creates). Sat Jan 21 17:51:09 2012 Child 5 verified 4886 files (565 creates). Sat Jan 21 17:51:09 2012 Child 13 verified 4991 files (670 creates). Sat Jan 21 17:51:35 2012 Child 2 verified 5269 files (948 creates). Sat Jan 21 17:51:35 2012 Child 11 verified 4886 files (565 creates). Sat Jan 21 17:51:35 2012 Child 4 verified 4886 files (565 creates). Sat Jan 21 17:51:35 2012 Child 9 verified 4886 files (565 creates). Sat Jan 21 17:51:35 2012 Child 14 verified 4886 files (565 creates). Sat Jan 21 17:51:35 2012 Child 15 verified 5191 files (870 creates). Sat Jan 21 17:56:00 2012 Child 6 verified 5401 files (1080 creates). Sat Jan 21 17:56:00 2012 Child 10 verified 5362 files (1041 creates). Sat Jan 21 17:56:00 2012 Child 3 verified 5076 files (755 creates). Sat Jan 21 17:56:00 2012 Child 7 verified 5362 files (1041 creates). Sat Jan 21 17:56:00 2012 Child 8 verified 5276 files (955 creates). Sat Jan 21 17:56:00 2012 Child 1 verified 5126 files (805 creates). Sat Jan 21 17:56:08 2012 Child 0 verified 5352 files (1031 creates). Sat Jan 21 17:56:09 2012 Child 5 verified 5215 files (894 creates). Sat Jan 21 17:56:09 2012 Child 12 verified 5205 files (884 creates). Sat Jan 21 17:56:12 2012 Child 13 verified 5354 files (1033 creates). Sat Jan 21 17:56:22 2012 Child 2 verified 5793 files, created 1416 Sat Jan 21 17:56:22 2012 Child 2 will create 2 symlinks. Sat Jan 21 17:56:22 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 17:56:37 2012 Child 4 verified 5303 files (982 creates). Sat Jan 21 17:56:37 2012 Child 11 verified 5269 files (948 creates). Sat Jan 21 17:56:37 2012 Child 15 verified 5642 files (1321 creates). Sat Jan 21 17:56:37 2012 Child 14 verified 5276 files (955 creates). Sat Jan 21 17:56:38 2012 Child 9 verified 5270 files (949 creates). Sat Jan 21 17:56:40 2012 Child 7 verified 5793 files, created 1416 Sat Jan 21 17:56:40 2012 Child 7 will create 2 symlinks. Sat Jan 21 17:56:44 2012 Child 10 verified 5793 files, created 1416 Sat Jan 21 17:56:44 2012 Child 10 will create 2 symlinks. Sat Jan 21 17:56:44 2012 Child 15 verified 5793 files, created 1416 Sat Jan 21 17:56:44 2012 Child 15 will create 2 symlinks. Sat Jan 21 17:56:44 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 17:56:44 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 17:56:45 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 17:56:49 2012 Child 6 verified 5793 files, created 1416 Sat Jan 21 17:56:49 2012 Child 6 will create 2 symlinks. Sat Jan 21 17:56:52 2012 Child 0 verified 5793 files, created 1416 Sat Jan 21 17:56:52 2012 Child 0 will create 2 symlinks. Sat Jan 21 17:56:52 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 17:56:54 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 17:56:54 2012 Child 8 verified 5793 files, created 1416 Sat Jan 21 17:56:54 2012 Child 8 will create 2 symlinks. Sat Jan 21 17:56:54 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 17:56:56 2012 Child 13 verified 5793 files, created 1416 Sat Jan 21 17:56:56 2012 Child 13 will create 2 symlinks. Sat Jan 21 17:56:56 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 17:57:05 2012 Child 4 verified 5793 files, created 1416 Sat Jan 21 17:57:05 2012 Child 4 will create 2 symlinks. Sat Jan 21 17:57:05 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 17:57:06 2012 Child 12 verified 5793 files, created 1416 Sat Jan 21 17:57:06 2012 Child 12 will create 2 symlinks. Sat Jan 21 17:57:06 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 17:57:06 2012 Child 1 verified 5793 files, created 1416 Sat Jan 21 17:57:06 2012 Child 1 will create 2 symlinks. Sat Jan 21 17:57:06 2012 Child 3 verified 5793 files, created 1416 Sat Jan 21 17:57:06 2012 Child 3 will create 2 symlinks. Sat Jan 21 17:57:06 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 17:57:06 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 17:57:06 2012 Child 5 verified 5793 files, created 1416 Sat Jan 21 17:57:06 2012 Child 5 will create 2 symlinks. Sat Jan 21 17:57:06 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 17:57:07 2012 Child 14 verified 5793 files, created 1416 Sat Jan 21 17:57:07 2012 Child 14 will create 2 symlinks. Sat Jan 21 17:57:07 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 17:57:08 2012 Child 9 verified 5793 files, created 1416 Sat Jan 21 17:57:08 2012 Child 9 will create 2 symlinks. Sat Jan 21 17:57:08 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 17:57:08 2012 Child 11 verified 5793 files, created 1416 Sat Jan 21 17:57:08 2012 Child 11 will create 2 symlinks. Sat Jan 21 17:57:08 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 17:57:08 2012 Completed. Sat Jan 21 17:57:08 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:57:08 2012 Completed. Sat Jan 21 17:57:08 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 17:57:09 2012 Received. Sat Jan 21 17:57:09 2012 Performing 300 seconds pretest warmup. Sat Jan 21 18:02:09 2012 Completed. Sat Jan 21 18:02:09 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:02:09 2012 Completed. Sat Jan 21 18:02:09 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:02:10 2012 Received. Sat Jan 21 18:02:10 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 18:07:21 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.9% 31088 31088 0 0.22 1.43 0.01 2.5% setattr 4.0% 4.0% 4815 4815 0 2.67 5.60 0.07 4.7% lookup 24.0% 23.9% 28649 28649 0 0.22 1.34 0.01 2.3% readlink 1.0% 1.0% 1239 1239 0 0.20 0.07 0.02 0.1% read 18.0% 18.1% 21772 26797 0 5.33 43.37 0.09 42.1% write 10.0% 10.1% 12146 14881 0 7.74 25.43 0.09 34.1% create 1.0% 1.0% 1166 1166 0 5.37 7.17 0.15 2.3% remove 1.0% 1.0% 1205 1205 0 4.16 6.48 0.14 1.8% readdir 1.0% 1.0% 1161 1161 0 0.42 0.11 0.02 0.2% fsstat 1.0% 1.0% 1260 1260 0 0.30 3.47 0.10 0.1% access 11.0% 11.0% 13180 13180 0 0.22 1.67 0.02 1.1% commit 0.0% 0.0% 0 5356 0 0.00 0.00 0.00 8.3% readdirplus 2.0% 2.0% 2402 2402 0 0.71 0.11 0.01 0.6% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 400.27 Ops/Sec AVG. RESPONSE TIME: 2.29 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 400 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 120083 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 133199 PHYSICAL NFS V3 THROUGHPUT: 443.99 Ops/Sec FILE SET SIZE CREATED: 48248784.0 KB FILE SET SIZE ACCESSED: 14477184.0 - 14769036.0 KB (100.00% to 102.02% of Base) ------------------------------------------------------------------------ Sat Jan 21 18:07:21 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 400 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 90864 Files created for I/O operations 27248 Files accessed for I/O operations 1824 Files for non-I/O operations 32 Symlinks 3024 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 18:07:21 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:07:21 2012 Completed. Sat Jan 21 18:07:21 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 18:07:21 2012 Received. Sat Jan 21 18:07:21 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 18:07:21 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 18:07:23 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 18:07:23 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 18:07:38 2012 Completed. Sat Jan 21 18:07:38 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:07:38 2012 Completed. Sat Jan 21 18:07:38 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:07:40 2012 Received. Sat Jan 21 18:07:40 2012 Initializing test directories. Sat Jan 21 18:07:40 2012 Child 15 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 1 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 14 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 13 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 4 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 12 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 0 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 11 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 6 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 9 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 2 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 10 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 8 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 7 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 3 will create 239 directories. Sat Jan 21 18:07:40 2012 Child 5 will create 239 directories. Sat Jan 21 18:07:41 2012 Child 2 finished creating 239 directories. Sat Jan 21 18:07:41 2012 Child 2 will create 7304 files. Sat Jan 21 18:07:41 2012 Child 6 finished creating 239 directories. Sat Jan 21 18:07:41 2012 Child 6 will create 7304 files. Sat Jan 21 18:07:41 2012 Child 3 finished creating 239 directories. Sat Jan 21 18:07:41 2012 Child 3 will create 7304 files. Sat Jan 21 18:07:45 2012 Child 10 finished creating 239 directories. Sat Jan 21 18:07:45 2012 Child 10 will create 7304 files. Sat Jan 21 18:07:45 2012 Child 12 finished creating 239 directories. Sat Jan 21 18:07:45 2012 Child 12 will create 7304 files. Sat Jan 21 18:07:48 2012 Child 9 finished creating 239 directories. Sat Jan 21 18:07:48 2012 Child 9 will create 7304 files. Sat Jan 21 18:07:48 2012 Child 15 finished creating 239 directories. Sat Jan 21 18:07:48 2012 Child 15 will create 7304 files. Sat Jan 21 18:07:51 2012 Child 11 finished creating 239 directories. Sat Jan 21 18:07:51 2012 Child 11 will create 7304 files. Sat Jan 21 18:07:51 2012 Child 4 finished creating 239 directories. Sat Jan 21 18:07:51 2012 Child 0 finished creating 239 directories. Sat Jan 21 18:07:51 2012 Child 0 will create 7304 files. Sat Jan 21 18:07:51 2012 Child 4 will create 7304 files. Sat Jan 21 18:07:52 2012 Child 1 finished creating 239 directories. Sat Jan 21 18:07:52 2012 Child 1 will create 7304 files. Sat Jan 21 18:07:55 2012 Child 7 finished creating 239 directories. Sat Jan 21 18:07:55 2012 Child 7 will create 7304 files. Sat Jan 21 18:07:55 2012 Child 13 finished creating 239 directories. Sat Jan 21 18:07:55 2012 Child 13 will create 7304 files. Sat Jan 21 18:07:56 2012 Child 5 finished creating 239 directories. Sat Jan 21 18:07:56 2012 Child 5 will create 7304 files. Sat Jan 21 18:07:57 2012 Child 14 finished creating 239 directories. Sat Jan 21 18:07:57 2012 Child 14 will create 7304 files. Sat Jan 21 18:08:00 2012 Child 8 finished creating 239 directories. Sat Jan 21 18:08:00 2012 Child 8 will create 7304 files. Sat Jan 21 18:14:16 2012 Child 4 verified 6087 files (408 creates). Sat Jan 21 18:14:16 2012 Child 9 verified 6237 files (558 creates). Sat Jan 21 18:14:17 2012 Child 12 verified 6156 files (477 creates). Sat Jan 21 18:14:17 2012 Child 6 verified 6488 files (809 creates). Sat Jan 21 18:14:17 2012 Child 1 verified 6039 files (360 creates). Sat Jan 21 18:14:17 2012 Child 15 verified 6156 files (477 creates). Sat Jan 21 18:14:17 2012 Child 10 verified 6236 files (557 creates). Sat Jan 21 18:14:17 2012 Child 11 verified 6091 files (412 creates). Sat Jan 21 18:14:17 2012 Child 14 verified 5981 files (302 creates). Sat Jan 21 18:14:17 2012 Child 7 verified 5981 files (302 creates). Sat Jan 21 18:14:17 2012 Child 0 verified 6092 files (413 creates). Sat Jan 21 18:14:17 2012 Child 5 verified 5981 files (302 creates). Sat Jan 21 18:14:17 2012 Child 2 verified 6500 files (821 creates). Sat Jan 21 18:14:17 2012 Child 3 verified 6500 files (821 creates). Sat Jan 21 18:14:17 2012 Child 8 verified 5981 files (302 creates). Sat Jan 21 18:14:17 2012 Child 13 verified 5981 files (302 creates). Sat Jan 21 18:19:15 2012 Child 12 verified 6510 files (831 creates). Sat Jan 21 18:19:15 2012 Child 6 verified 6754 files (1075 creates). Sat Jan 21 18:19:15 2012 Child 4 verified 6398 files (719 creates). Sat Jan 21 18:19:15 2012 Child 1 verified 6399 files (720 creates). Sat Jan 21 18:19:15 2012 Child 11 verified 6497 files (818 creates). Sat Jan 21 18:19:15 2012 Child 14 verified 6361 files (682 creates). Sat Jan 21 18:19:15 2012 Child 15 verified 6511 files (832 creates). Sat Jan 21 18:19:15 2012 Child 10 verified 6510 files (831 creates). Sat Jan 21 18:19:29 2012 Child 2 verified 6763 files (1084 creates). Sat Jan 21 18:19:29 2012 Child 8 verified 6353 files (674 creates). Sat Jan 21 18:19:29 2012 Child 7 verified 6353 files (674 creates). Sat Jan 21 18:19:29 2012 Child 13 verified 6353 files (674 creates). Sat Jan 21 18:19:29 2012 Child 9 verified 6543 files (864 creates). Sat Jan 21 18:19:29 2012 Child 0 verified 6500 files (821 creates). Sat Jan 21 18:19:29 2012 Child 3 verified 6770 files (1091 creates). Sat Jan 21 18:19:29 2012 Child 5 verified 6353 files (674 creates). Sat Jan 21 18:24:21 2012 Child 11 verified 6670 files (991 creates). Sat Jan 21 18:24:21 2012 Child 14 verified 6562 files (883 creates). Sat Jan 21 18:24:21 2012 Child 10 verified 6733 files (1054 creates). Sat Jan 21 18:24:21 2012 Child 4 verified 6562 files (883 creates). Sat Jan 21 18:24:21 2012 Child 1 verified 6612 files (933 creates). Sat Jan 21 18:24:21 2012 Child 12 verified 6710 files (1031 creates). Sat Jan 21 18:24:21 2012 Child 6 verified 7036 files (1357 creates). Sat Jan 21 18:24:21 2012 Child 15 verified 6747 files (1068 creates). Sat Jan 21 18:24:28 2012 Child 3 verified 7089 files (1410 creates). Sat Jan 21 18:24:31 2012 Child 9 verified 6880 files (1201 creates). Sat Jan 21 18:24:31 2012 Child 2 verified 7061 files (1382 creates). Sat Jan 21 18:24:31 2012 Child 7 verified 6569 files (890 creates). Sat Jan 21 18:24:31 2012 Child 5 verified 6591 files (912 creates). Sat Jan 21 18:24:31 2012 Child 13 verified 6591 files (912 creates). Sat Jan 21 18:24:31 2012 Child 8 verified 6570 files (891 creates). Sat Jan 21 18:24:31 2012 Child 0 verified 6748 files (1069 creates). Sat Jan 21 18:24:41 2012 Child 3 verified 7304 files, created 1554 Sat Jan 21 18:24:41 2012 Child 3 will create 2 symlinks. Sat Jan 21 18:24:41 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 18:24:44 2012 Child 6 verified 7304 files, created 1554 Sat Jan 21 18:24:44 2012 Child 6 will create 2 symlinks. Sat Jan 21 18:24:44 2012 Child 2 verified 7304 files, created 1554 Sat Jan 21 18:24:44 2012 Child 2 will create 2 symlinks. Sat Jan 21 18:24:44 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 18:24:44 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 18:25:07 2012 Child 9 verified 7304 files, created 1554 Sat Jan 21 18:25:07 2012 Child 9 will create 2 symlinks. Sat Jan 21 18:25:12 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 18:25:19 2012 Child 15 verified 7304 files, created 1554 Sat Jan 21 18:25:19 2012 Child 15 will create 2 symlinks. Sat Jan 21 18:25:19 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 18:25:23 2012 Child 10 verified 7304 files, created 1554 Sat Jan 21 18:25:23 2012 Child 10 will create 2 symlinks. Sat Jan 21 18:25:23 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 18:25:27 2012 Child 0 verified 7304 files, created 1554 Sat Jan 21 18:25:27 2012 Child 0 will create 2 symlinks. Sat Jan 21 18:25:27 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 18:25:28 2012 Child 12 verified 7304 files, created 1554 Sat Jan 21 18:25:28 2012 Child 12 will create 2 symlinks. Sat Jan 21 18:25:28 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 18:25:28 2012 Child 11 verified 7304 files, created 1554 Sat Jan 21 18:25:28 2012 Child 11 will create 2 symlinks. Sat Jan 21 18:25:28 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 18:25:34 2012 Child 1 verified 7304 files, created 1554 Sat Jan 21 18:25:34 2012 Child 1 will create 2 symlinks. Sat Jan 21 18:25:35 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 18:25:42 2012 Child 4 verified 7304 files, created 1554 Sat Jan 21 18:25:42 2012 Child 4 will create 2 symlinks. Sat Jan 21 18:25:42 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 18:25:42 2012 Child 14 verified 7304 files, created 1554 Sat Jan 21 18:25:42 2012 Child 14 will create 2 symlinks. Sat Jan 21 18:25:42 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 18:25:44 2012 Child 8 verified 7304 files, created 1554 Sat Jan 21 18:25:44 2012 Child 8 will create 2 symlinks. Sat Jan 21 18:25:44 2012 Child 13 verified 7304 files, created 1554 Sat Jan 21 18:25:44 2012 Child 13 will create 2 symlinks. Sat Jan 21 18:25:44 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 18:25:44 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 18:25:44 2012 Child 5 verified 7304 files, created 1554 Sat Jan 21 18:25:44 2012 Child 5 will create 2 symlinks. Sat Jan 21 18:25:44 2012 Child 7 verified 7304 files, created 1554 Sat Jan 21 18:25:44 2012 Child 7 will create 2 symlinks. Sat Jan 21 18:25:44 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 18:25:44 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 18:25:45 2012 Completed. Sat Jan 21 18:25:45 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:25:45 2012 Completed. Sat Jan 21 18:25:45 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:25:46 2012 Received. Sat Jan 21 18:25:46 2012 Performing 300 seconds pretest warmup. Sat Jan 21 18:30:46 2012 Completed. Sat Jan 21 18:30:46 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:30:46 2012 Completed. Sat Jan 21 18:30:46 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:30:47 2012 Received. Sat Jan 21 18:30:47 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 18:35:57 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.9% 38885 38885 0 0.35 1.74 0.01 1.9% setattr 4.0% 4.0% 5959 5959 0 7.84 15.57 0.10 6.7% lookup 24.0% 23.8% 35759 35759 0 0.35 1.75 0.01 1.8% readlink 1.0% 1.0% 1530 1530 0 0.37 1.70 0.07 0.1% read 18.0% 18.3% 27433 33764 0 10.89 98.78 0.12 42.8% write 10.0% 10.0% 15066 18220 0 14.31 50.02 0.11 30.9% create 1.0% 1.0% 1473 1473 0 14.83 28.92 0.27 3.1% remove 1.0% 1.0% 1509 1509 0 9.97 19.90 0.23 2.2% readdir 1.0% 1.0% 1493 1493 0 0.55 1.71 0.07 0.1% fsstat 1.0% 1.0% 1480 1480 0 0.35 1.93 0.07 0.1% access 11.0% 11.0% 16592 16592 0 0.36 1.67 0.02 0.8% commit 0.0% 0.0% 0 6390 0 0.00 0.00 0.00 9.1% readdirplus 2.0% 2.1% 3111 3111 0 0.89 1.42 0.04 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 502.64 Ops/Sec AVG. RESPONSE TIME: 4.64 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 500 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 150290 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 166165 PHYSICAL NFS V3 THROUGHPUT: 555.73 Ops/Sec FILE SET SIZE CREATED: 60831360.0 KB FILE SET SIZE ACCESSED: 18257904.0 - 18576228.0 KB (100.00% to 101.74% of Base) ------------------------------------------------------------------------ Sat Jan 21 18:35:57 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 500 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 114560 Files created for I/O operations 34368 Files accessed for I/O operations 2304 Files for non-I/O operations 32 Symlinks 3808 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 18:35:57 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:35:57 2012 Completed. Sat Jan 21 18:35:57 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 18:35:58 2012 Received. Sat Jan 21 18:35:58 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 18:35:58 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 18:36:00 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 18:36:00 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 18:36:14 2012 Completed. Sat Jan 21 18:36:14 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:36:14 2012 Completed. Sat Jan 21 18:36:14 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:36:16 2012 Received. Sat Jan 21 18:36:16 2012 Initializing test directories. Sat Jan 21 18:36:16 2012 Child 13 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 15 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 8 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 14 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 1 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 0 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 10 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 4 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 12 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 6 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 9 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 7 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 2 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 5 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 3 will create 289 directories. Sat Jan 21 18:36:16 2012 Child 11 will create 289 directories. Sat Jan 21 18:36:19 2012 Child 2 finished creating 289 directories. Sat Jan 21 18:36:19 2012 Child 2 will create 8814 files. Sat Jan 21 18:36:21 2012 Child 13 finished creating 289 directories. Sat Jan 21 18:36:21 2012 Child 13 will create 8814 files. Sat Jan 21 18:36:22 2012 Child 3 finished creating 289 directories. Sat Jan 21 18:36:22 2012 Child 3 will create 8814 files. Sat Jan 21 18:36:24 2012 Child 9 finished creating 289 directories. Sat Jan 21 18:36:24 2012 Child 9 will create 8814 files. Sat Jan 21 18:36:27 2012 Child 5 finished creating 289 directories. Sat Jan 21 18:36:27 2012 Child 5 will create 8814 files. Sat Jan 21 18:36:30 2012 Child 14 finished creating 289 directories. Sat Jan 21 18:36:30 2012 Child 14 will create 8814 files. Sat Jan 21 18:36:30 2012 Child 10 finished creating 289 directories. Sat Jan 21 18:36:30 2012 Child 10 will create 8814 files. Sat Jan 21 18:36:32 2012 Child 15 finished creating 289 directories. Sat Jan 21 18:36:32 2012 Child 15 will create 8814 files. Sat Jan 21 18:36:32 2012 Child 8 finished creating 289 directories. Sat Jan 21 18:36:32 2012 Child 8 will create 8814 files. Sat Jan 21 18:36:36 2012 Child 12 finished creating 289 directories. Sat Jan 21 18:36:36 2012 Child 12 will create 8814 files. Sat Jan 21 18:36:36 2012 Child 7 finished creating 289 directories. Sat Jan 21 18:36:36 2012 Child 7 will create 8814 files. Sat Jan 21 18:36:36 2012 Child 4 finished creating 289 directories. Sat Jan 21 18:36:36 2012 Child 4 will create 8814 files. Sat Jan 21 18:36:36 2012 Child 1 finished creating 289 directories. Sat Jan 21 18:36:36 2012 Child 1 will create 8814 files. Sat Jan 21 18:36:37 2012 Child 6 finished creating 289 directories. Sat Jan 21 18:36:37 2012 Child 6 will create 8814 files. Sat Jan 21 18:36:40 2012 Child 11 finished creating 289 directories. Sat Jan 21 18:36:40 2012 Child 11 will create 8814 files. Sat Jan 21 18:36:40 2012 Child 0 finished creating 289 directories. Sat Jan 21 18:36:40 2012 Child 0 will create 8814 files. Sat Jan 21 18:41:20 2012 Child 2 verified 8100 files (940 creates). Sat Jan 21 18:41:22 2012 Child 13 verified 7938 files (778 creates). Sat Jan 21 18:41:24 2012 Child 3 verified 7800 files (640 creates). Sat Jan 21 18:41:25 2012 Child 9 verified 7775 files (615 creates). Sat Jan 21 18:42:18 2012 Child 4 verified 7462 files (302 creates). Sat Jan 21 18:42:18 2012 Child 5 verified 7680 files (520 creates). Sat Jan 21 18:42:27 2012 Child 8 verified 7462 files (302 creates). Sat Jan 21 18:42:27 2012 Child 15 verified 7462 files (302 creates). Sat Jan 21 18:42:27 2012 Child 1 verified 7462 files (302 creates). Sat Jan 21 18:42:31 2012 Child 6 verified 7370 files (210 creates). Sat Jan 21 18:42:31 2012 Child 11 verified 7370 files (210 creates). Sat Jan 21 18:42:31 2012 Child 0 verified 7370 files (210 creates). Sat Jan 21 18:42:31 2012 Child 12 verified 7370 files (210 creates). Sat Jan 21 18:42:31 2012 Child 7 verified 7370 files (210 creates). Sat Jan 21 18:42:31 2012 Child 10 verified 7563 files (403 creates). Sat Jan 21 18:42:31 2012 Child 14 verified 7538 files (378 creates). Sat Jan 21 18:46:14 2012 Child 2 verified 8814 files, created 1568 Sat Jan 21 18:46:14 2012 Child 2 will create 2 symlinks. Sat Jan 21 18:46:14 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 18:46:23 2012 Child 13 verified 8624 files (1464 creates). Sat Jan 21 18:46:26 2012 Child 9 verified 8312 files (1152 creates). Sat Jan 21 18:46:27 2012 Child 3 verified 8525 files (1365 creates). Sat Jan 21 18:46:34 2012 Child 13 verified 8814 files, created 1568 Sat Jan 21 18:46:34 2012 Child 13 will create 2 symlinks. Sat Jan 21 18:46:34 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 18:47:03 2012 Child 3 verified 8814 files, created 1568 Sat Jan 21 18:47:03 2012 Child 3 will create 2 symlinks. Sat Jan 21 18:47:03 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 18:52:03 2012 Child 11 verified 8192 files (1032 creates). Sat Jan 21 18:52:16 2012 Child 7 verified 8205 files (1045 creates). Sat Jan 21 18:52:16 2012 Child 14 verified 8385 files (1225 creates). Sat Jan 21 18:52:16 2012 Child 6 verified 8205 files (1045 creates). Sat Jan 21 18:52:16 2012 Child 8 verified 8332 files (1172 creates). Sat Jan 21 18:52:16 2012 Child 4 verified 8332 files (1172 creates). Sat Jan 21 18:52:16 2012 Child 15 verified 8332 files (1172 creates). Sat Jan 21 18:52:16 2012 Child 0 verified 8205 files (1045 creates). Sat Jan 21 18:52:16 2012 Child 5 verified 8633 files (1473 creates). Sat Jan 21 18:52:16 2012 Child 1 verified 8385 files (1225 creates). Sat Jan 21 18:52:16 2012 Child 9 verified 8526 files (1366 creates). Sat Jan 21 18:52:23 2012 Child 5 verified 8814 files, created 1568 Sat Jan 21 18:52:23 2012 Child 5 will create 2 symlinks. Sat Jan 21 18:52:23 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 18:53:04 2012 Child 10 verified 8286 files (1126 creates). Sat Jan 21 18:53:04 2012 Child 12 verified 8286 files (1126 creates). Sat Jan 21 18:53:32 2012 Child 9 verified 8814 files, created 1568 Sat Jan 21 18:53:32 2012 Child 9 will create 2 symlinks. Sat Jan 21 18:53:32 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 18:54:50 2012 Child 1 verified 8814 files, created 1568 Sat Jan 21 18:54:50 2012 Child 1 will create 2 symlinks. Sat Jan 21 18:54:50 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 18:54:50 2012 Child 14 verified 8814 files, created 1568 Sat Jan 21 18:54:50 2012 Child 14 will create 2 symlinks. Sat Jan 21 18:54:50 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 18:55:43 2012 Child 15 verified 8814 files, created 1568 Sat Jan 21 18:55:43 2012 Child 15 will create 2 symlinks. Sat Jan 21 18:55:43 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 18:56:39 2012 Child 8 verified 8814 files, created 1568 Sat Jan 21 18:56:39 2012 Child 8 will create 2 symlinks. Sat Jan 21 18:56:39 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 18:56:39 2012 Child 4 verified 8814 files, created 1568 Sat Jan 21 18:56:39 2012 Child 4 will create 2 symlinks. Sat Jan 21 18:56:39 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 18:57:15 2012 Child 11 verified 8613 files (1453 creates). Sat Jan 21 18:57:15 2012 Child 7 verified 8614 files (1454 creates). Sat Jan 21 18:57:16 2012 Child 6 verified 8631 files (1471 creates). Sat Jan 21 18:57:22 2012 Child 0 verified 8633 files (1473 creates). Sat Jan 21 18:57:24 2012 Child 7 verified 8814 files, created 1568 Sat Jan 21 18:57:24 2012 Child 7 will create 2 symlinks. Sat Jan 21 18:57:24 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 18:57:24 2012 Child 11 verified 8814 files, created 1568 Sat Jan 21 18:57:24 2012 Child 11 will create 2 symlinks. Sat Jan 21 18:57:24 2012 Child 6 verified 8814 files, created 1568 Sat Jan 21 18:57:24 2012 Child 6 will create 2 symlinks. Sat Jan 21 18:57:24 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 18:57:24 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 18:57:24 2012 Child 12 verified 8814 files, created 1568 Sat Jan 21 18:57:24 2012 Child 12 will create 2 symlinks. Sat Jan 21 18:57:24 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 18:57:24 2012 Child 0 verified 8814 files, created 1568 Sat Jan 21 18:57:24 2012 Child 0 will create 2 symlinks. Sat Jan 21 18:57:24 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 18:57:24 2012 Child 10 verified 8814 files, created 1568 Sat Jan 21 18:57:24 2012 Child 10 will create 2 symlinks. Sat Jan 21 18:57:24 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 18:57:25 2012 Completed. Sat Jan 21 18:57:25 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:57:25 2012 Completed. Sat Jan 21 18:57:25 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 18:57:26 2012 Received. Sat Jan 21 18:57:26 2012 Performing 300 seconds pretest warmup. Sat Jan 21 19:02:26 2012 Completed. Sat Jan 21 19:02:26 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:02:26 2012 Completed. Sat Jan 21 19:02:26 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:02:27 2012 Received. Sat Jan 21 19:02:27 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 19:07:37 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 26.2% 47178 47178 0 0.22 0.42 0.01 1.4% setattr 4.0% 4.0% 7188 7188 0 5.67 11.84 0.08 5.7% lookup 24.0% 23.9% 43062 43062 0 0.22 0.37 0.01 1.3% readlink 1.0% 1.0% 1818 1818 0 0.22 0.43 0.03 0.1% read 18.0% 18.0% 32454 39979 0 9.83 67.31 0.09 44.7% write 10.0% 10.0% 17955 21889 0 12.54 32.23 0.08 31.5% create 1.0% 1.0% 1861 1861 0 10.49 16.16 0.18 2.7% remove 1.0% 1.0% 1820 1820 0 7.93 12.01 0.16 2.0% readdir 1.0% 1.0% 1813 1813 0 0.46 0.82 0.04 0.1% fsstat 1.0% 1.0% 1780 1780 0 0.21 0.15 0.02 0.1% access 11.0% 10.9% 19678 19678 0 0.22 0.32 0.01 0.6% commit 0.0% 0.0% 0 7819 0 0.00 0.00 0.00 9.3% readdirplus 2.0% 2.0% 3687 3687 0 0.75 0.26 0.02 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 602.98 Ops/Sec AVG. RESPONSE TIME: 3.95 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 600 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 180294 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 199572 PHYSICAL NFS V3 THROUGHPUT: 667.46 Ops/Sec FILE SET SIZE CREATED: 73413936.0 KB FILE SET SIZE ACCESSED: 22030128.0 - 22448224.0 KB (100.00% to 101.90% of Base) ------------------------------------------------------------------------ Sat Jan 21 19:07:37 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 600 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 138256 Files created for I/O operations 41472 Files accessed for I/O operations 2768 Files for non-I/O operations 32 Symlinks 4608 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 19:07:37 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:07:37 2012 Completed. Sat Jan 21 19:07:37 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 19:07:38 2012 Received. Sat Jan 21 19:07:38 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 19:07:38 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 19:07:40 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 19:07:40 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 19:07:54 2012 Completed. Sat Jan 21 19:07:54 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:07:54 2012 Completed. Sat Jan 21 19:07:54 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:07:56 2012 Received. Sat Jan 21 19:07:56 2012 Initializing test directories. Sat Jan 21 19:07:56 2012 Child 15 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 14 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 13 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 0 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 1 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 12 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 2 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 3 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 11 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 10 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 4 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 9 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 5 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 8 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 6 will create 338 directories. Sat Jan 21 19:07:56 2012 Child 7 will create 338 directories. Sat Jan 21 19:08:00 2012 Child 0 finished creating 338 directories. Sat Jan 21 19:08:00 2012 Child 0 will create 10325 files. Sat Jan 21 19:08:00 2012 Child 8 finished creating 338 directories. Sat Jan 21 19:08:00 2012 Child 8 will create 10325 files. Sat Jan 21 19:08:01 2012 Child 2 finished creating 338 directories. Sat Jan 21 19:08:01 2012 Child 2 will create 10325 files. Sat Jan 21 19:08:02 2012 Child 5 finished creating 338 directories. Sat Jan 21 19:08:02 2012 Child 5 will create 10325 files. Sat Jan 21 19:08:04 2012 Child 6 finished creating 338 directories. Sat Jan 21 19:08:04 2012 Child 6 will create 10325 files. Sat Jan 21 19:08:05 2012 Child 4 finished creating 338 directories. Sat Jan 21 19:08:05 2012 Child 4 will create 10325 files. Sat Jan 21 19:08:07 2012 Child 11 finished creating 338 directories. Sat Jan 21 19:08:07 2012 Child 11 will create 10325 files. Sat Jan 21 19:08:07 2012 Child 10 finished creating 338 directories. Sat Jan 21 19:08:07 2012 Child 10 will create 10325 files. Sat Jan 21 19:08:09 2012 Child 7 finished creating 338 directories. Sat Jan 21 19:08:09 2012 Child 7 will create 10325 files. Sat Jan 21 19:08:09 2012 Child 13 finished creating 338 directories. Sat Jan 21 19:08:09 2012 Child 13 will create 10325 files. Sat Jan 21 19:08:11 2012 Child 14 finished creating 338 directories. Sat Jan 21 19:08:11 2012 Child 14 will create 10325 files. Sat Jan 21 19:08:13 2012 Child 9 finished creating 338 directories. Sat Jan 21 19:08:13 2012 Child 9 will create 10325 files. Sat Jan 21 19:08:18 2012 Child 15 finished creating 338 directories. Sat Jan 21 19:08:18 2012 Child 15 will create 10325 files. Sat Jan 21 19:08:19 2012 Child 12 finished creating 338 directories. Sat Jan 21 19:08:19 2012 Child 12 will create 10325 files. Sat Jan 21 19:08:20 2012 Child 1 finished creating 338 directories. Sat Jan 21 19:08:20 2012 Child 1 will create 10325 files. Sat Jan 21 19:08:23 2012 Child 3 finished creating 338 directories. Sat Jan 21 19:08:23 2012 Child 3 will create 10325 files. Sat Jan 21 19:13:02 2012 Child 2 verified 9328 files (687 creates). Sat Jan 21 19:13:03 2012 Child 8 verified 9375 files (734 creates). Sat Jan 21 19:13:03 2012 Child 0 verified 9370 files (729 creates). Sat Jan 21 19:13:04 2012 Child 5 verified 9277 files (636 creates). Sat Jan 21 19:13:09 2012 Child 4 verified 8957 files (316 creates). Sat Jan 21 19:13:09 2012 Child 11 verified 8957 files (316 creates). Sat Jan 21 19:13:09 2012 Child 10 verified 8971 files (330 creates). Sat Jan 21 19:13:09 2012 Child 6 verified 9121 files (480 creates). Sat Jan 21 19:13:10 2012 Child 7 verified 8959 files (318 creates). Sat Jan 21 19:13:10 2012 Child 13 verified 8920 files (279 creates). Sat Jan 21 19:13:12 2012 Child 14 verified 8896 files (255 creates). Sat Jan 21 19:13:14 2012 Child 9 verified 8920 files (279 creates). Sat Jan 21 19:14:33 2012 Child 3 verified 8975 files (334 creates). Sat Jan 21 19:14:51 2012 Child 15 verified 8926 files (285 creates). Sat Jan 21 19:14:51 2012 Child 1 verified 8926 files (285 creates). Sat Jan 21 19:14:51 2012 Child 12 verified 8926 files (285 creates). Sat Jan 21 19:19:33 2012 Child 14 verified 9267 files (626 creates). Sat Jan 21 19:19:33 2012 Child 9 verified 9305 files (664 creates). Sat Jan 21 19:19:33 2012 Child 6 verified 9564 files (923 creates). Sat Jan 21 19:19:47 2012 Child 3 verified 9335 files (694 creates). Sat Jan 21 19:19:47 2012 Child 2 verified 9761 files (1120 creates). Sat Jan 21 19:19:47 2012 Child 5 verified 9761 files (1120 creates). Sat Jan 21 19:19:47 2012 Child 0 verified 9818 files (1177 creates). Sat Jan 21 19:19:47 2012 Child 8 verified 9818 files (1177 creates). Sat Jan 21 19:19:47 2012 Child 10 verified 9369 files (728 creates). Sat Jan 21 19:19:47 2012 Child 11 verified 9369 files (728 creates). Sat Jan 21 19:19:47 2012 Child 4 verified 9369 files (728 creates). Sat Jan 21 19:19:47 2012 Child 13 verified 9369 files (728 creates). Sat Jan 21 19:19:47 2012 Child 7 verified 9369 files (728 creates). Sat Jan 21 19:19:50 2012 Child 15 verified 9285 files (644 creates). Sat Jan 21 19:19:51 2012 Child 1 verified 9302 files (661 creates). Sat Jan 21 19:19:53 2012 Child 12 verified 9353 files (712 creates). Sat Jan 21 19:23:41 2012 Child 8 verified 10325 files, created 1583 Sat Jan 21 19:23:41 2012 Child 8 will create 2 symlinks. Sat Jan 21 19:23:42 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 19:23:42 2012 Child 0 verified 10325 files, created 1583 Sat Jan 21 19:23:42 2012 Child 0 will create 2 symlinks. Sat Jan 21 19:23:42 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 19:23:44 2012 Child 5 verified 10325 files, created 1583 Sat Jan 21 19:23:44 2012 Child 5 will create 2 symlinks. Sat Jan 21 19:23:44 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 19:23:45 2012 Child 2 verified 10325 files, created 1583 Sat Jan 21 19:23:45 2012 Child 2 will create 2 symlinks. Sat Jan 21 19:23:45 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 19:24:44 2012 Child 9 verified 9746 files (1105 creates). Sat Jan 21 19:24:56 2012 Child 1 verified 9712 files (1071 creates). Sat Jan 21 19:24:56 2012 Child 15 verified 9712 files (1071 creates). Sat Jan 21 19:24:56 2012 Child 7 verified 9654 files (1013 creates). Sat Jan 21 19:24:56 2012 Child 4 verified 9654 files (1013 creates). Sat Jan 21 19:24:56 2012 Child 3 verified 9761 files (1120 creates). Sat Jan 21 19:24:56 2012 Child 6 verified 9918 files (1277 creates). Sat Jan 21 19:24:56 2012 Child 11 verified 9794 files (1153 creates). Sat Jan 21 19:24:56 2012 Child 14 verified 9653 files (1012 creates). Sat Jan 21 19:24:56 2012 Child 13 verified 9794 files (1153 creates). Sat Jan 21 19:24:56 2012 Child 10 verified 9794 files (1153 creates). Sat Jan 21 19:24:56 2012 Child 12 verified 9510 files (869 creates). Sat Jan 21 19:26:20 2012 Child 6 verified 10325 files, created 1583 Sat Jan 21 19:26:20 2012 Child 6 will create 2 symlinks. Sat Jan 21 19:26:20 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 19:28:10 2012 Child 10 verified 10325 files, created 1583 Sat Jan 21 19:28:10 2012 Child 10 will create 2 symlinks. Sat Jan 21 19:28:10 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 19:28:52 2012 Child 3 verified 10325 files, created 1583 Sat Jan 21 19:28:52 2012 Child 3 will create 2 symlinks. Sat Jan 21 19:28:52 2012 Child 13 verified 10325 files, created 1583 Sat Jan 21 19:28:52 2012 Child 13 will create 2 symlinks. Sat Jan 21 19:28:52 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 19:28:52 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 19:28:52 2012 Child 11 verified 10325 files, created 1583 Sat Jan 21 19:28:52 2012 Child 11 will create 2 symlinks. Sat Jan 21 19:28:52 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 19:28:54 2012 Child 15 verified 10325 files, created 1583 Sat Jan 21 19:28:54 2012 Child 15 will create 2 symlinks. Sat Jan 21 19:28:54 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 19:28:56 2012 Child 1 verified 10325 files, created 1583 Sat Jan 21 19:28:56 2012 Child 1 will create 2 symlinks. Sat Jan 21 19:28:56 2012 Child 9 verified 10325 files, created 1583 Sat Jan 21 19:28:56 2012 Child 9 will create 2 symlinks. Sat Jan 21 19:28:56 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 19:28:56 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 19:28:57 2012 Child 7 verified 10325 files, created 1583 Sat Jan 21 19:28:57 2012 Child 7 will create 2 symlinks. Sat Jan 21 19:28:57 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 19:28:57 2012 Child 4 verified 10325 files, created 1583 Sat Jan 21 19:28:57 2012 Child 4 will create 2 symlinks. Sat Jan 21 19:28:57 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 19:28:57 2012 Child 14 verified 10325 files, created 1583 Sat Jan 21 19:28:57 2012 Child 14 will create 2 symlinks. Sat Jan 21 19:28:57 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 19:28:59 2012 Child 12 verified 10325 files, created 1583 Sat Jan 21 19:28:59 2012 Child 12 will create 2 symlinks. Sat Jan 21 19:28:59 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 19:28:59 2012 Completed. Sat Jan 21 19:28:59 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:28:59 2012 Completed. Sat Jan 21 19:28:59 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:28:59 2012 Received. Sat Jan 21 19:28:59 2012 Performing 300 seconds pretest warmup. Sat Jan 21 19:33:59 2012 Completed. Sat Jan 21 19:33:59 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:33:59 2012 Completed. Sat Jan 21 19:33:59 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:34:00 2012 Received. Sat Jan 21 19:34:00 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 19:39:10 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 26.0% 54720 54720 0 0.32 1.71 0.01 1.4% setattr 4.0% 4.0% 8479 8479 0 11.04 24.14 0.10 7.5% lookup 24.0% 24.0% 50505 50505 0 0.34 1.73 0.01 1.4% readlink 1.0% 1.0% 2102 2102 0 0.31 1.32 0.05 0.1% read 18.0% 18.1% 38052 46973 0 15.88 143.91 0.12 48.3% write 10.0% 9.9% 20936 25333 0 15.23 52.24 0.10 25.5% create 1.0% 1.0% 2191 2191 0 18.46 29.39 0.23 3.2% remove 1.0% 1.0% 2076 2076 0 13.14 23.94 0.21 2.2% readdir 1.0% 1.0% 2091 2091 0 0.55 1.38 0.05 0.1% fsstat 1.0% 1.0% 2142 2142 0 0.33 1.48 0.05 0.1% access 11.0% 10.9% 23052 23052 0 0.32 1.65 0.02 0.6% commit 0.0% 0.0% 0 9020 0 0.00 0.00 0.00 9.5% readdirplus 2.0% 2.0% 4240 4240 0 0.93 1.93 0.04 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 704.30 Ops/Sec AVG. RESPONSE TIME: 5.94 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 700 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 210586 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 232924 PHYSICAL NFS V3 THROUGHPUT: 779.01 Ops/Sec FILE SET SIZE CREATED: 85996512.0 KB FILE SET SIZE ACCESSED: 25802352.0 - 26270960.0 KB (100.00% to 101.82% of Base) ------------------------------------------------------------------------ Sat Jan 21 19:39:10 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 700 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 161952 Files created for I/O operations 48576 Files accessed for I/O operations 3248 Files for non-I/O operations 32 Symlinks 5392 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 19:39:10 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:39:10 2012 Completed. Sat Jan 21 19:39:10 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 19:39:11 2012 Received. Sat Jan 21 19:39:11 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 19:39:11 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 19:39:13 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 19:39:13 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 19:39:27 2012 Completed. Sat Jan 21 19:39:27 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:39:27 2012 Completed. Sat Jan 21 19:39:27 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:39:29 2012 Received. Sat Jan 21 19:39:29 2012 Initializing test directories. Sat Jan 21 19:39:29 2012 Child 15 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 12 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 0 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 6 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 8 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 4 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 11 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 1 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 3 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 7 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 2 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 9 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 10 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 13 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 14 will create 383 directories. Sat Jan 21 19:39:29 2012 Child 5 will create 383 directories. Sat Jan 21 19:39:30 2012 Child 14 finished creating 383 directories. Sat Jan 21 19:39:30 2012 Child 14 will create 11710 files. Sat Jan 21 19:39:30 2012 Child 7 finished creating 383 directories. Sat Jan 21 19:39:30 2012 Child 7 will create 11710 files. Sat Jan 21 19:39:32 2012 Child 12 finished creating 383 directories. Sat Jan 21 19:39:32 2012 Child 12 will create 11710 files. Sat Jan 21 19:39:38 2012 Child 15 finished creating 383 directories. Sat Jan 21 19:39:38 2012 Child 15 will create 11710 files. Sat Jan 21 19:39:40 2012 Child 13 finished creating 383 directories. Sat Jan 21 19:39:40 2012 Child 13 will create 11710 files. Sat Jan 21 19:39:41 2012 Child 3 finished creating 383 directories. Sat Jan 21 19:39:41 2012 Child 3 will create 11710 files. Sat Jan 21 19:39:43 2012 Child 9 finished creating 383 directories. Sat Jan 21 19:39:43 2012 Child 9 will create 11710 files. Sat Jan 21 19:39:43 2012 Child 1 finished creating 383 directories. Sat Jan 21 19:39:43 2012 Child 1 will create 11710 files. Sat Jan 21 19:39:43 2012 Child 6 finished creating 383 directories. Sat Jan 21 19:39:43 2012 Child 6 will create 11710 files. Sat Jan 21 19:39:50 2012 Child 10 finished creating 383 directories. Sat Jan 21 19:39:50 2012 Child 10 will create 11710 files. Sat Jan 21 19:39:52 2012 Child 8 finished creating 383 directories. Sat Jan 21 19:39:52 2012 Child 8 will create 11710 files. Sat Jan 21 19:39:52 2012 Child 0 finished creating 383 directories. Sat Jan 21 19:39:52 2012 Child 0 will create 11710 files. Sat Jan 21 19:39:55 2012 Child 2 finished creating 383 directories. Sat Jan 21 19:39:55 2012 Child 2 will create 11710 files. Sat Jan 21 19:39:58 2012 Child 5 finished creating 383 directories. Sat Jan 21 19:39:58 2012 Child 5 will create 11710 files. Sat Jan 21 19:39:58 2012 Child 11 finished creating 383 directories. Sat Jan 21 19:39:58 2012 Child 11 will create 11710 files. Sat Jan 21 19:40:00 2012 Child 4 finished creating 383 directories. Sat Jan 21 19:40:00 2012 Child 4 will create 11710 files. Sat Jan 21 19:44:32 2012 Child 7 verified 11071 files (949 creates). Sat Jan 21 19:44:33 2012 Child 12 verified 10937 files (815 creates). Sat Jan 21 19:44:37 2012 Child 14 verified 11148 files (1026 creates). Sat Jan 21 19:44:43 2012 Child 13 verified 10485 files (363 creates). Sat Jan 21 19:44:43 2012 Child 15 verified 10533 files (411 creates). Sat Jan 21 19:44:43 2012 Child 3 verified 10481 files (359 creates). Sat Jan 21 19:44:44 2012 Child 1 verified 10523 files (401 creates). Sat Jan 21 19:44:45 2012 Child 6 verified 10422 files (300 creates). Sat Jan 21 19:46:08 2012 Child 10 verified 10376 files (254 creates). Sat Jan 21 19:46:18 2012 Child 2 verified 10264 files (142 creates). Sat Jan 21 19:46:18 2012 Child 4 verified 10264 files (142 creates). Sat Jan 21 19:46:18 2012 Child 0 verified 10353 files (231 creates). Sat Jan 21 19:46:18 2012 Child 8 verified 10353 files (231 creates). Sat Jan 21 19:46:23 2012 Child 5 verified 10275 files (153 creates). Sat Jan 21 19:46:23 2012 Child 9 verified 10485 files (363 creates). Sat Jan 21 19:46:23 2012 Child 11 verified 10275 files (153 creates). Sat Jan 21 19:48:26 2012 Child 14 verified 11710 files, created 1474 Sat Jan 21 19:48:26 2012 Child 14 will create 2 symlinks. Sat Jan 21 19:48:28 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 19:48:35 2012 Child 7 verified 11710 files, created 1474 Sat Jan 21 19:48:35 2012 Child 7 will create 2 symlinks. Sat Jan 21 19:48:35 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 19:49:01 2012 Child 12 verified 11710 files, created 1474 Sat Jan 21 19:49:01 2012 Child 12 will create 2 symlinks. Sat Jan 21 19:49:01 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 19:53:11 2012 Child 4 verified 10844 files (722 creates). Sat Jan 21 19:53:11 2012 Child 15 verified 11182 files (1060 creates). Sat Jan 21 19:53:11 2012 Child 1 verified 11182 files (1060 creates). Sat Jan 21 19:53:11 2012 Child 2 verified 10813 files (691 creates). Sat Jan 21 19:53:11 2012 Child 0 verified 10972 files (850 creates). Sat Jan 21 19:53:11 2012 Child 9 verified 11100 files (978 creates). Sat Jan 21 19:53:11 2012 Child 8 verified 10972 files (850 creates). Sat Jan 21 19:53:11 2012 Child 13 verified 11148 files (1026 creates). Sat Jan 21 19:53:11 2012 Child 10 verified 11008 files (886 creates). Sat Jan 21 19:53:11 2012 Child 6 verified 11071 files (949 creates). Sat Jan 21 19:53:11 2012 Child 3 verified 11094 files (972 creates). Sat Jan 21 19:53:11 2012 Child 11 verified 10908 files (786 creates). Sat Jan 21 19:53:11 2012 Child 5 verified 10909 files (787 creates). Sat Jan 21 19:56:12 2012 Child 15 verified 11710 files, created 1474 Sat Jan 21 19:56:12 2012 Child 15 will create 2 symlinks. Sat Jan 21 19:56:12 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 19:56:12 2012 Child 1 verified 11710 files, created 1474 Sat Jan 21 19:56:12 2012 Child 1 will create 2 symlinks. Sat Jan 21 19:56:12 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 19:56:19 2012 Child 13 verified 11710 files, created 1474 Sat Jan 21 19:56:19 2012 Child 13 will create 2 symlinks. Sat Jan 21 19:56:19 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 19:56:26 2012 Child 9 verified 11710 files, created 1474 Sat Jan 21 19:56:26 2012 Child 9 will create 2 symlinks. Sat Jan 21 19:56:26 2012 Child 3 verified 11710 files, created 1474 Sat Jan 21 19:56:26 2012 Child 3 will create 2 symlinks. Sat Jan 21 19:56:26 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 19:56:26 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 19:58:57 2012 Child 10 verified 11391 files (1269 creates). Sat Jan 21 19:58:57 2012 Child 5 verified 11391 files (1269 creates). Sat Jan 21 19:58:57 2012 Child 4 verified 11428 files (1306 creates). Sat Jan 21 19:58:57 2012 Child 11 verified 11437 files (1315 creates). Sat Jan 21 19:58:57 2012 Child 8 verified 11437 files (1315 creates). Sat Jan 21 19:58:57 2012 Child 0 verified 11437 files (1315 creates). Sat Jan 21 19:58:57 2012 Child 6 verified 11437 files (1315 creates). Sat Jan 21 19:58:57 2012 Child 2 verified 11391 files (1269 creates). Sat Jan 21 19:59:03 2012 Child 11 verified 11710 files, created 1474 Sat Jan 21 19:59:03 2012 Child 11 will create 2 symlinks. Sat Jan 21 19:59:03 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 19:59:03 2012 Child 0 verified 11710 files, created 1474 Sat Jan 21 19:59:03 2012 Child 0 will create 2 symlinks. Sat Jan 21 19:59:03 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 19:59:03 2012 Child 8 verified 11710 files, created 1474 Sat Jan 21 19:59:03 2012 Child 8 will create 2 symlinks. Sat Jan 21 19:59:03 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 19:59:03 2012 Child 6 verified 11710 files, created 1474 Sat Jan 21 19:59:03 2012 Child 6 will create 2 symlinks. Sat Jan 21 19:59:03 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 19:59:03 2012 Child 4 verified 11710 files, created 1474 Sat Jan 21 19:59:03 2012 Child 4 will create 2 symlinks. Sat Jan 21 19:59:03 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 19:59:07 2012 Child 2 verified 11710 files, created 1474 Sat Jan 21 19:59:07 2012 Child 2 will create 2 symlinks. Sat Jan 21 19:59:07 2012 Child 10 verified 11710 files, created 1474 Sat Jan 21 19:59:07 2012 Child 10 will create 2 symlinks. Sat Jan 21 19:59:07 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 19:59:07 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 19:59:07 2012 Child 5 verified 11710 files, created 1474 Sat Jan 21 19:59:07 2012 Child 5 will create 2 symlinks. Sat Jan 21 19:59:07 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 19:59:07 2012 Completed. Sat Jan 21 19:59:07 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:59:07 2012 Completed. Sat Jan 21 19:59:07 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 19:59:07 2012 Received. Sat Jan 21 19:59:07 2012 Performing 300 seconds pretest warmup. Sat Jan 21 20:04:07 2012 Completed. Sat Jan 21 20:04:07 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:04:07 2012 Completed. Sat Jan 21 20:04:07 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:04:08 2012 Received. Sat Jan 21 20:04:08 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 20:09:19 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.9% 62622 62622 0 0.81 14.97 0.03 2.3% setattr 4.0% 4.0% 9784 9784 0 20.03 61.10 0.15 8.9% lookup 24.0% 24.1% 58259 58259 0 0.82 12.77 0.03 2.2% readlink 1.0% 1.0% 2408 2408 0 0.58 4.11 0.08 0.1% read 18.0% 18.0% 43432 53259 0 23.88 159.30 0.12 46.9% write 10.0% 10.0% 24137 29095 0 19.29 78.99 0.11 21.1% create 1.0% 1.0% 2462 2462 0 33.94 86.46 0.37 3.8% remove 1.0% 1.0% 2405 2405 0 25.60 66.98 0.33 2.8% readdir 1.0% 1.0% 2453 2453 0 1.03 10.64 0.13 0.1% fsstat 1.0% 1.0% 2383 2383 0 0.63 3.82 0.08 0.1% access 11.0% 11.0% 26555 26555 0 0.95 21.57 0.06 1.1% commit 0.0% 0.0% 0 10032 0 0.00 0.00 0.00 10.4% readdirplus 2.0% 2.0% 4823 4823 0 1.71 27.76 0.15 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 805.74 Ops/Sec AVG. RESPONSE TIME: 9.14 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 800 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 241723 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 266540 PHYSICAL NFS V3 THROUGHPUT: 888.46 Ops/Sec FILE SET SIZE CREATED: 97534080.0 KB FILE SET SIZE ACCESSED: 29268720.0 - 29731540.0 KB (100.00% to 101.58% of Base) ------------------------------------------------------------------------ Sat Jan 21 20:09:19 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 800 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 183680 Files created for I/O operations 55104 Files accessed for I/O operations 3680 Files for non-I/O operations 32 Symlinks 6112 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 20:09:19 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:09:19 2012 Completed. Sat Jan 21 20:09:19 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 20:09:20 2012 Received. Sat Jan 21 20:09:20 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 20:09:20 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 20:09:22 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 20:09:22 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 20:09:36 2012 Completed. Sat Jan 21 20:09:36 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:09:36 2012 Completed. Sat Jan 21 20:09:36 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:09:38 2012 Received. Sat Jan 21 20:09:38 2012 Initializing test directories. Sat Jan 21 20:09:38 2012 Child 15 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 11 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 13 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 2 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 1 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 8 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 9 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 3 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 7 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 4 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 0 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 10 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 5 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 6 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 12 will create 433 directories. Sat Jan 21 20:09:38 2012 Child 14 will create 433 directories. Sat Jan 21 20:09:40 2012 Child 10 finished creating 433 directories. Sat Jan 21 20:09:40 2012 Child 10 will create 13221 files. Sat Jan 21 20:09:41 2012 Child 5 finished creating 433 directories. Sat Jan 21 20:09:41 2012 Child 5 will create 13221 files. Sat Jan 21 20:09:45 2012 Child 7 finished creating 433 directories. Sat Jan 21 20:09:45 2012 Child 7 will create 13221 files. Sat Jan 21 20:09:45 2012 Child 3 finished creating 433 directories. Sat Jan 21 20:09:45 2012 Child 3 will create 13221 files. Sat Jan 21 20:09:48 2012 Child 8 finished creating 433 directories. Sat Jan 21 20:09:48 2012 Child 8 will create 13221 files. Sat Jan 21 20:09:56 2012 Child 1 finished creating 433 directories. Sat Jan 21 20:09:56 2012 Child 1 will create 13221 files. Sat Jan 21 20:09:56 2012 Child 12 finished creating 433 directories. Sat Jan 21 20:09:56 2012 Child 12 will create 13221 files. Sat Jan 21 20:09:56 2012 Child 13 finished creating 433 directories. Sat Jan 21 20:09:56 2012 Child 13 will create 13221 files. Sat Jan 21 20:09:59 2012 Child 11 finished creating 433 directories. Sat Jan 21 20:09:59 2012 Child 11 will create 13221 files. Sat Jan 21 20:10:01 2012 Child 15 finished creating 433 directories. Sat Jan 21 20:10:01 2012 Child 15 will create 13221 files. Sat Jan 21 20:10:06 2012 Child 0 finished creating 433 directories. Sat Jan 21 20:10:06 2012 Child 0 will create 13221 files. Sat Jan 21 20:10:10 2012 Child 4 finished creating 433 directories. Sat Jan 21 20:10:10 2012 Child 4 will create 13221 files. Sat Jan 21 20:10:14 2012 Child 2 finished creating 433 directories. Sat Jan 21 20:10:14 2012 Child 2 will create 13221 files. Sat Jan 21 20:10:14 2012 Child 6 finished creating 433 directories. Sat Jan 21 20:10:14 2012 Child 6 will create 13221 files. Sat Jan 21 20:10:15 2012 Child 9 finished creating 433 directories. Sat Jan 21 20:10:15 2012 Child 9 will create 13221 files. Sat Jan 21 20:10:15 2012 Child 14 finished creating 433 directories. Sat Jan 21 20:10:15 2012 Child 14 will create 13221 files. Sat Jan 21 20:12:42 2012 Child 10 verified 13221 files, created 1612 Sat Jan 21 20:12:42 2012 Child 10 will create 2 symlinks. Sat Jan 21 20:12:42 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 20:13:02 2012 Child 5 verified 13221 files, created 1612 Sat Jan 21 20:13:02 2012 Child 5 will create 2 symlinks. Sat Jan 21 20:13:02 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 20:14:05 2012 Child 7 verified 13221 files, created 1612 Sat Jan 21 20:14:05 2012 Child 7 will create 2 symlinks. Sat Jan 21 20:14:05 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 20:14:09 2012 Child 3 verified 13221 files, created 1612 Sat Jan 21 20:14:09 2012 Child 3 will create 2 symlinks. Sat Jan 21 20:14:09 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 20:23:08 2012 Child 2 verified 12241 files (761 creates). Sat Jan 21 20:23:08 2012 Child 12 verified 12723 files (1243 creates). Sat Jan 21 20:23:08 2012 Child 14 verified 12204 files (724 creates). Sat Jan 21 20:23:08 2012 Child 9 verified 12204 files (724 creates). Sat Jan 21 20:23:08 2012 Child 8 verified 12951 files (1471 creates). Sat Jan 21 20:23:08 2012 Child 1 verified 12731 files (1251 creates). Sat Jan 21 20:23:08 2012 Child 0 verified 12313 files (833 creates). Sat Jan 21 20:23:08 2012 Child 13 verified 12723 files (1243 creates). Sat Jan 21 20:23:08 2012 Child 6 verified 12204 files (724 creates). Sat Jan 21 20:23:08 2012 Child 4 verified 12241 files (761 creates). Sat Jan 21 20:23:08 2012 Child 11 verified 12540 files (1060 creates). Sat Jan 21 20:23:08 2012 Child 15 verified 12540 files (1060 creates). Sat Jan 21 20:23:49 2012 Child 8 verified 13221 files, created 1612 Sat Jan 21 20:23:49 2012 Child 8 will create 2 symlinks. Sat Jan 21 20:23:49 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 20:24:18 2012 Child 1 verified 13221 files, created 1612 Sat Jan 21 20:24:18 2012 Child 1 will create 2 symlinks. Sat Jan 21 20:24:19 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 20:24:37 2012 Child 11 verified 13221 files, created 1612 Sat Jan 21 20:24:37 2012 Child 11 will create 2 symlinks. Sat Jan 21 20:24:37 2012 Child 13 verified 13221 files, created 1612 Sat Jan 21 20:24:37 2012 Child 13 will create 2 symlinks. Sat Jan 21 20:24:37 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 20:24:37 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 20:24:37 2012 Child 15 verified 13221 files, created 1612 Sat Jan 21 20:24:37 2012 Child 15 will create 2 symlinks. Sat Jan 21 20:24:37 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 20:24:38 2012 Child 12 verified 13221 files, created 1612 Sat Jan 21 20:24:38 2012 Child 12 will create 2 symlinks. Sat Jan 21 20:24:39 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 20:24:55 2012 Child 2 verified 13221 files, created 1612 Sat Jan 21 20:24:55 2012 Child 2 will create 2 symlinks. Sat Jan 21 20:24:55 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 20:24:55 2012 Child 4 verified 13221 files, created 1612 Sat Jan 21 20:24:55 2012 Child 4 will create 2 symlinks. Sat Jan 21 20:24:55 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 20:24:57 2012 Child 0 verified 13221 files, created 1612 Sat Jan 21 20:24:57 2012 Child 0 will create 2 symlinks. Sat Jan 21 20:24:57 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 20:25:05 2012 Child 14 verified 13221 files, created 1612 Sat Jan 21 20:25:05 2012 Child 14 will create 2 symlinks. Sat Jan 21 20:25:05 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 20:25:05 2012 Child 9 verified 13221 files, created 1612 Sat Jan 21 20:25:05 2012 Child 9 will create 2 symlinks. Sat Jan 21 20:25:05 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 20:25:05 2012 Child 6 verified 13221 files, created 1612 Sat Jan 21 20:25:05 2012 Child 6 will create 2 symlinks. Sat Jan 21 20:25:05 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 20:25:06 2012 Completed. Sat Jan 21 20:25:06 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:25:06 2012 Completed. Sat Jan 21 20:25:06 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:25:07 2012 Received. Sat Jan 21 20:25:07 2012 Performing 300 seconds pretest warmup. Sat Jan 21 20:30:07 2012 Completed. Sat Jan 21 20:30:07 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:30:07 2012 Completed. Sat Jan 21 20:30:07 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:30:08 2012 Received. Sat Jan 21 20:30:08 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 20:35:23 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.9% 53449 53449 0 4.11 125.31 0.09 3.6% setattr 4.0% 4.0% 8167 8167 0 57.54 85.49 0.20 7.6% lookup 24.0% 24.0% 49555 49555 0 3.67 64.19 0.07 3.0% readlink 1.0% 1.0% 2053 2053 0 3.29 8.74 0.13 0.1% read 18.0% 18.0% 37038 45399 0 72.47 591.09 0.25 43.7% write 10.0% 10.2% 20944 25333 0 76.35 694.04 0.36 26.0% create 1.0% 1.0% 2065 2065 0 106.16 129.46 0.49 3.6% remove 1.0% 1.0% 2068 2068 0 68.93 109.31 0.45 2.3% readdir 1.0% 1.0% 2073 2073 0 3.44 8.81 0.13 0.1% fsstat 1.0% 1.0% 2065 2065 0 3.46 9.30 0.13 0.1% access 11.0% 10.9% 22483 22483 0 3.49 10.81 0.04 1.3% commit 0.0% 0.0% 0 7966 0 0.00 0.00 0.00 8.4% readdirplus 2.0% 2.0% 4117 4117 0 3.98 9.42 0.09 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 675.66 Ops/Sec AVG. RESPONSE TIME: 29.83 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 900 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 206077 TEST TIME: 305 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 226793 PHYSICAL NFS V3 THROUGHPUT: 743.58 Ops/Sec FILE SET SIZE CREATED: 110116656.0 KB FILE SET SIZE ACCESSED: 33040944.0 - 33378996.0 KB (100.00% to 101.02% of Base) ------------------------------------------------------------------------ Sat Jan 21 20:35:23 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 900 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 207376 Files created for I/O operations 62208 Files accessed for I/O operations 4160 Files for non-I/O operations 32 Symlinks 6912 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 20:35:23 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:35:23 2012 Completed. Sat Jan 21 20:35:23 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 20:35:24 2012 Received. Sat Jan 21 20:35:24 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 20:35:24 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 20:35:26 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 20:35:26 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 20:35:40 2012 Completed. Sat Jan 21 20:35:40 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:35:40 2012 Completed. Sat Jan 21 20:35:40 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:35:42 2012 Received. Sat Jan 21 20:35:42 2012 Initializing test directories. Sat Jan 21 20:35:42 2012 Child 14 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 11 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 6 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 8 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 10 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 15 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 5 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 12 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 7 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 9 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 1 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 2 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 0 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 13 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 3 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 4 will create 482 directories. Sat Jan 21 20:35:42 2012 Child 6 finished creating 482 directories. Sat Jan 21 20:35:42 2012 Child 6 will create 14732 files. Sat Jan 21 20:35:46 2012 Child 9 finished creating 482 directories. Sat Jan 21 20:35:46 2012 Child 9 will create 14732 files. Sat Jan 21 20:35:47 2012 Child 3 finished creating 482 directories. Sat Jan 21 20:35:47 2012 Child 3 will create 14732 files. Sat Jan 21 20:35:49 2012 Child 8 finished creating 482 directories. Sat Jan 21 20:35:49 2012 Child 8 will create 14732 files. Sat Jan 21 20:35:49 2012 Child 1 finished creating 482 directories. Sat Jan 21 20:35:49 2012 Child 1 will create 14732 files. Sat Jan 21 20:35:52 2012 Child 0 finished creating 482 directories. Sat Jan 21 20:35:52 2012 Child 0 will create 14732 files. Sat Jan 21 20:35:53 2012 Child 10 finished creating 482 directories. Sat Jan 21 20:35:53 2012 Child 10 will create 14732 files. Sat Jan 21 20:35:55 2012 Child 11 finished creating 482 directories. Sat Jan 21 20:35:55 2012 Child 11 will create 14732 files. Sat Jan 21 20:35:55 2012 Child 7 finished creating 482 directories. Sat Jan 21 20:35:55 2012 Child 7 will create 14732 files. Sat Jan 21 20:36:01 2012 Child 15 finished creating 482 directories. Sat Jan 21 20:36:01 2012 Child 15 will create 14732 files. Sat Jan 21 20:36:02 2012 Child 13 finished creating 482 directories. Sat Jan 21 20:36:02 2012 Child 13 will create 14732 files. Sat Jan 21 20:36:03 2012 Child 2 finished creating 482 directories. Sat Jan 21 20:36:03 2012 Child 2 will create 14732 files. Sat Jan 21 20:36:06 2012 Child 4 finished creating 482 directories. Sat Jan 21 20:36:06 2012 Child 4 will create 14732 files. Sat Jan 21 20:36:06 2012 Child 5 finished creating 482 directories. Sat Jan 21 20:36:06 2012 Child 5 will create 14732 files. Sat Jan 21 20:36:27 2012 Child 14 finished creating 482 directories. Sat Jan 21 20:36:27 2012 Child 14 will create 14732 files. Sat Jan 21 20:36:28 2012 Child 12 finished creating 482 directories. Sat Jan 21 20:36:28 2012 Child 12 will create 14732 files. Sat Jan 21 20:41:36 2012 Child 12 verified 12963 files (2 creates). Sat Jan 21 20:41:36 2012 Child 5 verified 13124 files (163 creates). Sat Jan 21 20:41:36 2012 Child 14 verified 12963 files (2 creates). Sat Jan 21 20:41:44 2012 Child 0 verified 13384 files (423 creates). Sat Jan 21 20:41:44 2012 Child 10 verified 13384 files (423 creates). Sat Jan 21 20:41:44 2012 Child 4 verified 13130 files (169 creates). Sat Jan 21 20:41:44 2012 Child 7 verified 13353 files (392 creates). Sat Jan 21 20:41:59 2012 Child 8 verified 13544 files (583 creates). Sat Jan 21 20:42:03 2012 Child 2 verified 13129 files (168 creates). Sat Jan 21 20:42:03 2012 Child 15 verified 13154 files (193 creates). Sat Jan 21 20:42:03 2012 Child 1 verified 13544 files (583 creates). Sat Jan 21 20:42:03 2012 Child 6 verified 14065 files (1104 creates). Sat Jan 21 20:42:03 2012 Child 13 verified 13130 files (169 creates). Sat Jan 21 20:42:03 2012 Child 9 verified 13761 files (800 creates). Sat Jan 21 20:42:03 2012 Child 3 verified 13629 files (668 creates). Sat Jan 21 20:42:03 2012 Child 11 verified 13335 files (374 creates). Sat Jan 21 20:46:43 2012 Child 12 verified 13277 files (316 creates). Sat Jan 21 20:46:43 2012 Child 14 verified 13277 files (316 creates). Sat Jan 21 20:46:43 2012 Child 0 verified 13718 files (757 creates). Sat Jan 21 20:46:43 2012 Child 4 verified 13369 files (408 creates). Sat Jan 21 20:46:43 2012 Child 2 verified 13369 files (408 creates). Sat Jan 21 20:46:43 2012 Child 5 verified 13369 files (408 creates). Sat Jan 21 20:46:43 2012 Child 10 verified 13717 files (756 creates). Sat Jan 21 20:46:43 2012 Child 7 verified 13629 files (668 creates). Sat Jan 21 20:46:48 2012 Child 8 verified 13881 files (920 creates). Sat Jan 21 20:46:51 2012 Child 6 verified 14732 files, created 1627 Sat Jan 21 20:46:51 2012 Child 6 will create 2 symlinks. Sat Jan 21 20:46:51 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 20:47:29 2012 Child 15 verified 13422 files (461 creates). Sat Jan 21 20:48:02 2012 Child 1 verified 13903 files (942 creates). Sat Jan 21 20:48:02 2012 Child 9 verified 14065 files (1104 creates). Sat Jan 21 20:48:03 2012 Child 13 verified 13383 files (422 creates). Sat Jan 21 20:48:12 2012 Child 3 verified 13953 files (992 creates). Sat Jan 21 20:48:12 2012 Child 11 verified 13629 files (668 creates). Sat Jan 21 20:51:57 2012 Child 0 verified 13992 files (1031 creates). Sat Jan 21 20:51:57 2012 Child 8 verified 14097 files (1136 creates). Sat Jan 21 20:51:57 2012 Child 4 verified 13629 files (668 creates). Sat Jan 21 20:51:57 2012 Child 12 verified 13525 files (564 creates). Sat Jan 21 20:51:57 2012 Child 2 verified 13629 files (668 creates). Sat Jan 21 20:51:57 2012 Child 10 verified 13989 files (1028 creates). Sat Jan 21 20:51:57 2012 Child 5 verified 13629 files (668 creates). Sat Jan 21 20:51:57 2012 Child 7 verified 13953 files (992 creates). Sat Jan 21 20:51:57 2012 Child 14 verified 13544 files (583 creates). Sat Jan 21 20:52:17 2012 Child 9 verified 14732 files, created 1627 Sat Jan 21 20:52:17 2012 Child 9 will create 2 symlinks. Sat Jan 21 20:52:17 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 20:53:53 2012 Child 15 verified 13789 files (828 creates). Sat Jan 21 20:53:53 2012 Child 3 verified 14346 files (1385 creates). Sat Jan 21 20:53:53 2012 Child 13 verified 13761 files (800 creates). Sat Jan 21 20:53:53 2012 Child 1 verified 14277 files (1316 creates). Sat Jan 21 20:53:53 2012 Child 11 verified 14001 files (1040 creates). Sat Jan 21 20:54:04 2012 Child 3 verified 14732 files, created 1627 Sat Jan 21 20:54:04 2012 Child 3 will create 2 symlinks. Sat Jan 21 20:54:04 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 20:55:50 2012 Child 1 verified 14732 files, created 1627 Sat Jan 21 20:55:50 2012 Child 1 will create 2 symlinks. Sat Jan 21 20:55:50 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 20:55:56 2012 Child 8 verified 14732 files, created 1627 Sat Jan 21 20:55:56 2012 Child 8 will create 2 symlinks. Sat Jan 21 20:55:56 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 20:57:20 2012 Child 7 verified 14327 files (1366 creates). Sat Jan 21 20:57:33 2012 Child 12 verified 13903 files (942 creates). Sat Jan 21 20:57:33 2012 Child 0 verified 14346 files (1385 creates). Sat Jan 21 20:57:33 2012 Child 4 verified 14001 files (1040 creates). Sat Jan 21 20:57:33 2012 Child 10 verified 14346 files (1385 creates). Sat Jan 21 20:57:33 2012 Child 2 verified 13953 files (992 creates). Sat Jan 21 20:57:33 2012 Child 5 verified 13953 files (992 creates). Sat Jan 21 20:57:33 2012 Child 14 verified 13953 files (992 creates). Sat Jan 21 20:58:32 2012 Child 7 verified 14732 files, created 1627 Sat Jan 21 20:58:32 2012 Child 7 will create 2 symlinks. Sat Jan 21 20:58:34 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 20:58:36 2012 Child 11 verified 14732 files, created 1627 Sat Jan 21 20:58:36 2012 Child 11 will create 2 symlinks. Sat Jan 21 20:58:37 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 20:58:50 2012 Child 0 verified 14732 files, created 1627 Sat Jan 21 20:58:50 2012 Child 0 will create 2 symlinks. Sat Jan 21 20:58:52 2012 Child 15 verified 14346 files (1385 creates). Sat Jan 21 20:58:52 2012 Child 13 verified 14283 files (1322 creates). Sat Jan 21 20:58:55 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 20:58:57 2012 Child 4 verified 14732 files, created 1627 Sat Jan 21 20:58:57 2012 Child 4 will create 2 symlinks. Sat Jan 21 20:59:06 2012 Child 15 verified 14732 files, created 1627 Sat Jan 21 20:59:06 2012 Child 15 will create 2 symlinks. Sat Jan 21 20:59:06 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 20:59:06 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 20:59:08 2012 Child 14 verified 14732 files, created 1627 Sat Jan 21 20:59:08 2012 Child 14 will create 2 symlinks. Sat Jan 21 20:59:08 2012 Child 12 verified 14732 files, created 1627 Sat Jan 21 20:59:08 2012 Child 12 will create 2 symlinks. Sat Jan 21 20:59:09 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 20:59:09 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 20:59:09 2012 Child 2 verified 14732 files, created 1627 Sat Jan 21 20:59:09 2012 Child 2 will create 2 symlinks. Sat Jan 21 20:59:09 2012 Child 5 verified 14732 files, created 1627 Sat Jan 21 20:59:09 2012 Child 5 will create 2 symlinks. Sat Jan 21 20:59:09 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 20:59:09 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 20:59:09 2012 Child 13 verified 14732 files, created 1627 Sat Jan 21 20:59:09 2012 Child 13 will create 2 symlinks. Sat Jan 21 20:59:09 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 20:59:13 2012 Child 10 verified 14732 files, created 1627 Sat Jan 21 20:59:13 2012 Child 10 will create 2 symlinks. Sat Jan 21 20:59:13 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 20:59:13 2012 Completed. Sat Jan 21 20:59:13 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:59:13 2012 Completed. Sat Jan 21 20:59:13 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 20:59:13 2012 Received. Sat Jan 21 20:59:13 2012 Performing 300 seconds pretest warmup. Sat Jan 21 21:04:13 2012 Completed. Sat Jan 21 21:04:13 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:04:13 2012 Completed. Sat Jan 21 21:04:13 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:04:15 2012 Received. Sat Jan 21 21:04:15 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 21:09:25 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 26.0% 51629 51629 0 5.05 48.15 0.06 4.2% setattr 4.0% 4.0% 7928 7928 0 73.73 151.68 0.27 9.4% lookup 24.0% 24.1% 47989 47989 0 5.74 109.98 0.09 4.4% readlink 1.0% 1.0% 2012 2012 0 3.88 11.45 0.15 0.1% read 18.0% 17.9% 35595 43372 0 80.26 495.79 0.23 45.9% write 10.0% 10.1% 20012 24140 0 52.00 330.26 0.25 16.7% create 1.0% 1.0% 1963 1963 0 133.33 172.43 0.58 4.2% remove 1.0% 1.0% 2020 2020 0 88.60 198.43 0.61 2.9% readdir 1.0% 1.0% 1988 1988 0 5.88 41.42 0.28 0.2% fsstat 1.0% 1.0% 2051 2051 0 5.56 26.49 0.22 0.2% access 11.0% 10.9% 21665 21665 0 5.32 81.04 0.12 1.9% commit 0.0% 0.0% 0 7500 0 0.00 0.00 0.00 9.6% readdirplus 2.0% 2.0% 4000 4000 0 5.48 19.25 0.14 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 667.28 Ops/Sec AVG. RESPONSE TIME: 31.28 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1000 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 198852 TEST TIME: 298 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 218257 PHYSICAL NFS V3 THROUGHPUT: 732.40 Ops/Sec FILE SET SIZE CREATED: 122707728.0 KB FILE SET SIZE ACCESSED: 36813168.0 - 37140664.0 KB (100.00% to 100.89% of Base) ------------------------------------------------------------------------ Sat Jan 21 21:09:25 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 1000 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 231088 Files created for I/O operations 69312 Files accessed for I/O operations 4624 Files for non-I/O operations 32 Symlinks 7696 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 21:09:25 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:09:25 2012 Completed. Sat Jan 21 21:09:25 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 21:09:25 2012 Received. Sat Jan 21 21:09:25 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 21:09:25 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 21:09:27 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 21:09:27 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 21:09:41 2012 Completed. Sat Jan 21 21:09:41 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:09:41 2012 Completed. Sat Jan 21 21:09:41 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:09:43 2012 Received. Sat Jan 21 21:09:43 2012 Initializing test directories. Sat Jan 21 21:09:43 2012 Child 15 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 13 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 6 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 0 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 8 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 12 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 9 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 5 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 2 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 4 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 11 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 1 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 10 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 3 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 14 will create 527 directories. Sat Jan 21 21:09:43 2012 Child 7 will create 527 directories. Sat Jan 21 21:09:48 2012 Child 4 finished creating 527 directories. Sat Jan 21 21:09:48 2012 Child 4 will create 16118 files. Sat Jan 21 21:09:49 2012 Child 14 finished creating 527 directories. Sat Jan 21 21:09:49 2012 Child 14 will create 16118 files. Sat Jan 21 21:09:52 2012 Child 2 finished creating 527 directories. Sat Jan 21 21:09:52 2012 Child 2 will create 16118 files. Sat Jan 21 21:09:55 2012 Child 1 finished creating 527 directories. Sat Jan 21 21:09:55 2012 Child 1 will create 16118 files. Sat Jan 21 21:09:57 2012 Child 6 finished creating 527 directories. Sat Jan 21 21:09:57 2012 Child 6 will create 16118 files. Sat Jan 21 21:09:58 2012 Child 7 finished creating 527 directories. Sat Jan 21 21:09:58 2012 Child 7 will create 16118 files. Sat Jan 21 21:10:01 2012 Child 8 finished creating 527 directories. Sat Jan 21 21:10:01 2012 Child 8 will create 16118 files. Sat Jan 21 21:10:09 2012 Child 12 finished creating 527 directories. Sat Jan 21 21:10:09 2012 Child 12 will create 16118 files. Sat Jan 21 21:10:10 2012 Child 15 finished creating 527 directories. Sat Jan 21 21:10:10 2012 Child 15 will create 16118 files. Sat Jan 21 21:10:14 2012 Child 5 finished creating 527 directories. Sat Jan 21 21:10:14 2012 Child 5 will create 16118 files. Sat Jan 21 21:10:14 2012 Child 13 finished creating 527 directories. Sat Jan 21 21:10:14 2012 Child 13 will create 16118 files. Sat Jan 21 21:10:15 2012 Child 11 finished creating 527 directories. Sat Jan 21 21:10:15 2012 Child 11 will create 16118 files. Sat Jan 21 21:10:15 2012 Child 9 finished creating 527 directories. Sat Jan 21 21:10:15 2012 Child 9 will create 16118 files. Sat Jan 21 21:10:26 2012 Child 0 finished creating 527 directories. Sat Jan 21 21:10:26 2012 Child 0 will create 16118 files. Sat Jan 21 21:10:27 2012 Child 3 finished creating 527 directories. Sat Jan 21 21:10:27 2012 Child 3 will create 16118 files. Sat Jan 21 21:10:34 2012 Child 10 finished creating 527 directories. Sat Jan 21 21:10:34 2012 Child 10 will create 16118 files. Sat Jan 21 21:15:13 2012 Child 14 verified 15582 files (1139 creates). Sat Jan 21 21:15:13 2012 Child 2 verified 15316 files (873 creates). Sat Jan 21 21:15:13 2012 Child 8 verified 14885 files (442 creates). Sat Jan 21 21:15:13 2012 Child 6 verified 14980 files (537 creates). Sat Jan 21 21:15:13 2012 Child 4 verified 15632 files (1189 creates). Sat Jan 21 21:15:13 2012 Child 1 verified 15030 files (587 creates). Sat Jan 21 21:15:13 2012 Child 12 verified 14733 files (290 creates). Sat Jan 21 21:15:13 2012 Child 15 verified 14733 files (290 creates). Sat Jan 21 21:15:13 2012 Child 7 verified 14980 files (537 creates). Sat Jan 21 21:15:16 2012 Child 13 verified 14667 files (224 creates). Sat Jan 21 21:15:16 2012 Child 5 verified 14667 files (224 creates). Sat Jan 21 21:15:16 2012 Child 11 verified 14706 files (263 creates). Sat Jan 21 21:15:16 2012 Child 9 verified 14650 files (207 creates). Sat Jan 21 21:15:27 2012 Child 0 verified 14609 files (166 creates). Sat Jan 21 21:15:30 2012 Child 3 verified 14562 files (119 creates). Sat Jan 21 21:16:54 2012 Child 10 verified 14578 files (135 creates). Sat Jan 21 21:17:39 2012 Child 4 verified 16118 files, created 1517 Sat Jan 21 21:17:39 2012 Child 4 will create 2 symlinks. Sat Jan 21 21:17:39 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 21:17:39 2012 Child 14 verified 16118 files, created 1517 Sat Jan 21 21:17:39 2012 Child 14 will create 2 symlinks. Sat Jan 21 21:17:39 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 21:20:14 2012 Child 7 verified 15267 files (824 creates). Sat Jan 21 21:20:14 2012 Child 6 verified 15273 files (830 creates). Sat Jan 21 21:20:14 2012 Child 8 verified 15162 files (719 creates). Sat Jan 21 21:20:24 2012 Child 1 verified 15279 files (836 creates). Sat Jan 21 21:20:24 2012 Child 12 verified 15030 files (587 creates). Sat Jan 21 21:20:24 2012 Child 11 verified 14980 files (537 creates). Sat Jan 21 21:20:24 2012 Child 9 verified 14916 files (473 creates). Sat Jan 21 21:20:24 2012 Child 15 verified 15030 files (587 creates). Sat Jan 21 21:20:24 2012 Child 5 verified 14916 files (473 creates). Sat Jan 21 21:20:24 2012 Child 13 verified 14916 files (473 creates). Sat Jan 21 21:20:24 2012 Child 2 verified 15672 files (1229 creates). Sat Jan 21 21:20:28 2012 Child 0 verified 14853 files (410 creates). Sat Jan 21 21:20:31 2012 Child 3 verified 14792 files (349 creates). Sat Jan 21 21:20:53 2012 Child 2 verified 16118 files, created 1517 Sat Jan 21 21:20:53 2012 Child 2 will create 2 symlinks. Sat Jan 21 21:20:53 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 21:22:49 2012 Child 10 verified 14930 files (487 creates). Sat Jan 21 21:24:46 2012 Child 7 verified 16118 files, created 1517 Sat Jan 21 21:24:46 2012 Child 7 will create 2 symlinks. Sat Jan 21 21:24:46 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 21:24:47 2012 Child 6 verified 16118 files, created 1517 Sat Jan 21 21:24:47 2012 Child 6 will create 2 symlinks. Sat Jan 21 21:24:47 2012 Child 1 verified 16118 files, created 1517 Sat Jan 21 21:24:47 2012 Child 1 will create 2 symlinks. Sat Jan 21 21:24:48 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 21:24:48 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 21:24:57 2012 Child 8 verified 16118 files, created 1517 Sat Jan 21 21:24:57 2012 Child 8 will create 2 symlinks. Sat Jan 21 21:24:59 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 21:25:17 2012 Child 15 verified 16118 files, created 1517 Sat Jan 21 21:25:17 2012 Child 15 will create 2 symlinks. Sat Jan 21 21:25:19 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 21:25:20 2012 Child 11 verified 16118 files, created 1517 Sat Jan 21 21:25:20 2012 Child 11 will create 2 symlinks. Sat Jan 21 21:25:20 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 21:25:21 2012 Child 12 verified 16118 files, created 1517 Sat Jan 21 21:25:21 2012 Child 12 will create 2 symlinks. Sat Jan 21 21:25:21 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 21:25:23 2012 Child 13 verified 16118 files, created 1517 Sat Jan 21 21:25:23 2012 Child 13 will create 2 symlinks. Sat Jan 21 21:25:23 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 21:25:24 2012 Child 5 verified 16118 files, created 1517 Sat Jan 21 21:25:24 2012 Child 5 will create 2 symlinks. Sat Jan 21 21:25:24 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 21:25:25 2012 Child 9 verified 16118 files, created 1517 Sat Jan 21 21:25:25 2012 Child 9 will create 2 symlinks. Sat Jan 21 21:25:25 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 21:25:29 2012 Child 0 verified 16118 files, created 1517 Sat Jan 21 21:25:29 2012 Child 0 will create 2 symlinks. Sat Jan 21 21:25:29 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 21:25:32 2012 Child 10 verified 16118 files, created 1517 Sat Jan 21 21:25:32 2012 Child 10 will create 2 symlinks. Sat Jan 21 21:25:32 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 21:25:32 2012 Child 3 verified 16118 files, created 1517 Sat Jan 21 21:25:32 2012 Child 3 will create 2 symlinks. Sat Jan 21 21:25:32 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 21:25:32 2012 Completed. Sat Jan 21 21:25:32 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:25:32 2012 Completed. Sat Jan 21 21:25:32 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:25:33 2012 Received. Sat Jan 21 21:25:33 2012 Performing 300 seconds pretest warmup. Sat Jan 21 21:30:33 2012 Completed. Sat Jan 21 21:30:33 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:30:33 2012 Completed. Sat Jan 21 21:30:33 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:30:34 2012 Received. Sat Jan 21 21:30:34 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 21:35:48 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 26.1% 42459 42459 0 5.79 34.36 0.06 4.1% setattr 4.0% 4.0% 6446 6446 0 96.80 162.17 0.31 10.4% lookup 24.0% 24.1% 39288 39288 0 5.77 34.04 0.06 3.8% readlink 1.0% 1.0% 1598 1598 0 4.82 12.56 0.17 0.1% read 18.0% 17.9% 29088 35439 0 88.81 271.63 0.19 43.0% write 10.0% 10.0% 16304 19653 0 66.45 221.21 0.23 18.0% create 1.0% 1.0% 1575 1575 0 179.64 182.61 0.67 4.7% remove 1.0% 1.0% 1638 1638 0 107.92 152.15 0.60 2.9% readdir 1.0% 1.0% 1696 1696 0 5.62 12.41 0.17 0.2% fsstat 1.0% 1.0% 1625 1625 0 6.17 34.68 0.29 0.2% access 11.0% 11.0% 17890 17890 0 5.59 21.87 0.07 1.7% commit 0.0% 0.0% 0 5877 0 0.00 0.00 0.00 10.6% readdirplus 2.0% 2.0% 3322 3322 0 6.14 19.51 0.15 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 543.09 Ops/Sec AVG. RESPONSE TIME: 36.85 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1100 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 162929 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 178506 PHYSICAL NFS V3 THROUGHPUT: 595.02 Ops/Sec FILE SET SIZE CREATED: 134245296.0 KB FILE SET SIZE ACCESSED: 40279536.0 - 40522920.0 KB (100.00% to 100.60% of Base) ------------------------------------------------------------------------ Sat Jan 21 21:35:48 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 1100 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 252816 Files created for I/O operations 75840 Files accessed for I/O operations 5072 Files for non-I/O operations 32 Symlinks 8416 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 21:35:48 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:35:48 2012 Completed. Sat Jan 21 21:35:48 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 21:35:48 2012 Received. Sat Jan 21 21:35:48 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 21:35:48 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 21:35:50 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 21:35:50 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 21:36:04 2012 Completed. Sat Jan 21 21:36:04 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:36:04 2012 Completed. Sat Jan 21 21:36:04 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:36:06 2012 Received. Sat Jan 21 21:36:06 2012 Initializing test directories. Sat Jan 21 21:36:06 2012 Child 10 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 5 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 0 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 13 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 12 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 4 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 6 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 1 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 14 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 3 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 15 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 2 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 8 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 7 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 9 will create 577 directories. Sat Jan 21 21:36:06 2012 Child 11 will create 577 directories. Sat Jan 21 21:36:07 2012 Child 6 finished creating 577 directories. Sat Jan 21 21:36:07 2012 Child 6 will create 17628 files. Sat Jan 21 21:36:10 2012 Child 9 finished creating 577 directories. Sat Jan 21 21:36:10 2012 Child 9 will create 17628 files. Sat Jan 21 21:36:13 2012 Child 10 finished creating 577 directories. Sat Jan 21 21:36:13 2012 Child 10 will create 17628 files. Sat Jan 21 21:36:16 2012 Child 13 finished creating 577 directories. Sat Jan 21 21:36:16 2012 Child 13 will create 17628 files. Sat Jan 21 21:36:17 2012 Child 0 finished creating 577 directories. Sat Jan 21 21:36:17 2012 Child 0 will create 17628 files. Sat Jan 21 21:36:18 2012 Child 14 finished creating 577 directories. Sat Jan 21 21:36:18 2012 Child 14 will create 17628 files. Sat Jan 21 21:36:21 2012 Child 1 finished creating 577 directories. Sat Jan 21 21:36:21 2012 Child 1 will create 17628 files. Sat Jan 21 21:36:28 2012 Child 3 finished creating 577 directories. Sat Jan 21 21:36:28 2012 Child 3 will create 17628 files. Sat Jan 21 21:36:32 2012 Child 4 finished creating 577 directories. Sat Jan 21 21:36:32 2012 Child 4 will create 17628 files. Sat Jan 21 21:36:36 2012 Child 7 finished creating 577 directories. Sat Jan 21 21:36:36 2012 Child 7 will create 17628 files. Sat Jan 21 21:36:39 2012 Child 8 finished creating 577 directories. Sat Jan 21 21:36:39 2012 Child 8 will create 17628 files. Sat Jan 21 21:36:41 2012 Child 11 finished creating 577 directories. Sat Jan 21 21:36:41 2012 Child 11 will create 17628 files. Sat Jan 21 21:36:41 2012 Child 2 finished creating 577 directories. Sat Jan 21 21:36:41 2012 Child 2 will create 17628 files. Sat Jan 21 21:36:43 2012 Child 12 finished creating 577 directories. Sat Jan 21 21:36:43 2012 Child 12 will create 17628 files. Sat Jan 21 21:36:50 2012 Child 15 finished creating 577 directories. Sat Jan 21 21:36:50 2012 Child 15 will create 17628 files. Sat Jan 21 21:37:02 2012 Child 5 finished creating 577 directories. Sat Jan 21 21:37:02 2012 Child 5 will create 17628 files. Sat Jan 21 21:38:36 2012 Child 6 verified 17628 files, created 1655 Sat Jan 21 21:38:36 2012 Child 6 will create 2 symlinks. Sat Jan 21 21:38:36 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 21:39:38 2012 Child 9 verified 17628 files, created 1655 Sat Jan 21 21:39:38 2012 Child 9 will create 2 symlinks. Sat Jan 21 21:39:38 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 21:40:26 2012 Child 13 verified 17628 files, created 1655 Sat Jan 21 21:40:26 2012 Child 13 will create 2 symlinks. Sat Jan 21 21:40:26 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 21:40:44 2012 Child 14 verified 17628 files, created 1655 Sat Jan 21 21:40:44 2012 Child 14 will create 2 symlinks. Sat Jan 21 21:40:44 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 21:40:44 2012 Child 0 verified 17628 files, created 1655 Sat Jan 21 21:40:44 2012 Child 0 will create 2 symlinks. Sat Jan 21 21:40:44 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 21:40:46 2012 Child 10 verified 17628 files, created 1655 Sat Jan 21 21:40:46 2012 Child 10 will create 2 symlinks. Sat Jan 21 21:40:50 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 21:41:05 2012 Child 1 verified 17628 files, created 1655 Sat Jan 21 21:41:05 2012 Child 1 will create 2 symlinks. Sat Jan 21 21:41:14 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 21:41:37 2012 Child 7 verified 17268 files (1467 creates). Sat Jan 21 21:41:40 2012 Child 8 verified 17272 files (1471 creates). Sat Jan 21 21:41:43 2012 Child 11 verified 17236 files (1435 creates). Sat Jan 21 21:41:43 2012 Child 2 verified 17207 files (1406 creates). Sat Jan 21 21:41:44 2012 Child 12 verified 17230 files (1429 creates). Sat Jan 21 21:41:48 2012 Child 7 verified 17628 files, created 1655 Sat Jan 21 21:41:48 2012 Child 7 will create 2 symlinks. Sat Jan 21 21:41:48 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 21:41:49 2012 Child 8 verified 17628 files, created 1655 Sat Jan 21 21:41:49 2012 Child 8 will create 2 symlinks. Sat Jan 21 21:41:49 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 21:41:55 2012 Child 4 verified 17628 files, created 1655 Sat Jan 21 21:41:55 2012 Child 4 will create 2 symlinks. Sat Jan 21 21:41:56 2012 Child 3 verified 17628 files, created 1655 Sat Jan 21 21:41:56 2012 Child 3 will create 2 symlinks. Sat Jan 21 21:41:56 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 21:41:56 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 21:41:57 2012 Child 12 verified 17628 files, created 1655 Sat Jan 21 21:41:57 2012 Child 12 will create 2 symlinks. Sat Jan 21 21:41:57 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 21:41:57 2012 Child 2 verified 17628 files, created 1655 Sat Jan 21 21:41:57 2012 Child 2 will create 2 symlinks. Sat Jan 21 21:41:57 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 21:41:57 2012 Child 15 verified 17628 files, created 1655 Sat Jan 21 21:41:57 2012 Child 15 will create 2 symlinks. Sat Jan 21 21:41:58 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 21:42:02 2012 Child 5 verified 17628 files, created 1655 Sat Jan 21 21:42:02 2012 Child 5 will create 2 symlinks. Sat Jan 21 21:42:02 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 21:42:02 2012 Child 11 verified 17628 files, created 1655 Sat Jan 21 21:42:02 2012 Child 11 will create 2 symlinks. Sat Jan 21 21:42:03 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 21:42:03 2012 Completed. Sat Jan 21 21:42:03 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:42:03 2012 Completed. Sat Jan 21 21:42:03 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:42:03 2012 Received. Sat Jan 21 21:42:03 2012 Performing 300 seconds pretest warmup. Sat Jan 21 21:47:03 2012 Completed. Sat Jan 21 21:47:03 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:47:03 2012 Completed. Sat Jan 21 21:47:03 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:47:04 2012 Received. Sat Jan 21 21:47:04 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 21:52:14 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.6% 8150 8150 0 58.22 203.45 0.31 7.3% setattr 4.0% 4.1% 1307 1307 0 180.60 208.80 0.78 3.6% lookup 24.0% 23.9% 7620 7620 0 64.83 215.77 0.33 7.6% readlink 1.0% 1.0% 321 321 0 44.04 103.94 1.12 0.2% read 18.0% 18.2% 5792 7163 0 640.37 2134.46 1.19 57.0% write 10.0% 10.2% 3238 3933 0 288.54 804.57 0.98 14.4% create 1.0% 1.0% 316 316 0 267.22 270.40 1.81 1.3% remove 1.0% 0.9% 302 302 0 186.51 181.49 1.52 0.9% readdir 1.0% 0.9% 278 278 0 65.88 240.73 1.82 0.3% fsstat 1.0% 1.1% 349 349 0 72.41 254.78 1.67 0.4% access 11.0% 11.1% 3535 3535 0 57.79 204.09 0.47 3.1% commit 0.0% 0.0% 0 1106 0 0.00 0.00 0.00 3.2% readdirplus 2.0% 2.0% 640 640 0 72.30 230.87 1.18 0.7% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 107.23 Ops/Sec AVG. RESPONSE TIME: 204.26 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1200 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 31848 TEST TIME: 297 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 35020 PHYSICAL NFS V3 THROUGHPUT: 117.91 Ops/Sec FILE SET SIZE CREATED: 146827872.0 KB FILE SET SIZE ACCESSED: 44051760.0 - 44110516.0 KB (100.00% to 100.13% of Base) ------------------------------------------------------------------------ Sat Jan 21 21:52:14 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 1200 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 276512 Files created for I/O operations 82944 Files accessed for I/O operations 5536 Files for non-I/O operations 32 Symlinks 9216 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 21:52:14 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:52:14 2012 Completed. Sat Jan 21 21:52:14 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 21:52:15 2012 Received. Sat Jan 21 21:52:15 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 21:52:15 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 21:52:17 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 21:52:17 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 21:52:33 2012 Completed. Sat Jan 21 21:52:33 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:52:33 2012 Completed. Sat Jan 21 21:52:33 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 21:52:33 2012 Received. Sat Jan 21 21:52:33 2012 Initializing test directories. Sat Jan 21 21:52:33 2012 Child 13 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 7 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 11 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 4 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 2 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 9 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 6 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 1 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 10 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 5 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 15 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 0 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 12 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 14 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 3 will create 626 directories. Sat Jan 21 21:52:33 2012 Child 8 will create 626 directories. Sat Jan 21 21:52:36 2012 Child 12 finished creating 626 directories. Sat Jan 21 21:52:36 2012 Child 12 will create 19139 files. Sat Jan 21 21:52:37 2012 Child 5 finished creating 626 directories. Sat Jan 21 21:52:37 2012 Child 5 will create 19139 files. Sat Jan 21 21:52:40 2012 Child 13 finished creating 626 directories. Sat Jan 21 21:52:40 2012 Child 13 will create 19139 files. Sat Jan 21 21:52:42 2012 Child 7 finished creating 626 directories. Sat Jan 21 21:52:42 2012 Child 7 will create 19139 files. Sat Jan 21 21:52:43 2012 Child 4 finished creating 626 directories. Sat Jan 21 21:52:43 2012 Child 4 will create 19139 files. Sat Jan 21 21:52:45 2012 Child 1 finished creating 626 directories. Sat Jan 21 21:52:45 2012 Child 1 will create 19139 files. Sat Jan 21 21:53:28 2012 Child 10 finished creating 626 directories. Sat Jan 21 21:53:28 2012 Child 10 will create 19139 files. Sat Jan 21 21:53:32 2012 Child 6 finished creating 626 directories. Sat Jan 21 21:53:32 2012 Child 6 will create 19139 files. Sat Jan 21 21:53:35 2012 Child 14 finished creating 626 directories. Sat Jan 21 21:53:35 2012 Child 14 will create 19139 files. Sat Jan 21 21:53:37 2012 Child 0 finished creating 626 directories. Sat Jan 21 21:53:37 2012 Child 0 will create 19139 files. Sat Jan 21 21:53:42 2012 Child 3 finished creating 626 directories. Sat Jan 21 21:53:42 2012 Child 3 will create 19139 files. Sat Jan 21 21:53:56 2012 Child 11 finished creating 626 directories. Sat Jan 21 21:53:56 2012 Child 11 will create 19139 files. Sat Jan 21 21:53:56 2012 Child 8 finished creating 626 directories. Sat Jan 21 21:53:56 2012 Child 8 will create 19139 files. Sat Jan 21 21:54:03 2012 Child 2 finished creating 626 directories. Sat Jan 21 21:54:03 2012 Child 2 will create 19139 files. Sat Jan 21 21:54:03 2012 Child 15 finished creating 626 directories. Sat Jan 21 21:54:03 2012 Child 15 will create 19139 files. Sat Jan 21 21:54:05 2012 Child 9 finished creating 626 directories. Sat Jan 21 21:54:05 2012 Child 9 will create 19139 files. Sat Jan 21 21:57:38 2012 Child 12 verified 17632 files (350 creates). Sat Jan 21 21:57:39 2012 Child 5 verified 17764 files (482 creates). Sat Jan 21 21:57:41 2012 Child 13 verified 17670 files (388 creates). Sat Jan 21 21:57:45 2012 Child 7 verified 17697 files (415 creates). Sat Jan 21 21:57:45 2012 Child 4 verified 17698 files (416 creates). Sat Jan 21 21:57:46 2012 Child 1 verified 17713 files (431 creates). Sat Jan 21 22:01:03 2012 Child 6 verified 17610 files (328 creates). Sat Jan 21 22:01:03 2012 Child 10 verified 17582 files (300 creates). Sat Jan 21 22:01:03 2012 Child 8 verified 17582 files (300 creates). Sat Jan 21 22:01:07 2012 Child 15 verified 17581 files (299 creates). Sat Jan 21 22:01:12 2012 Child 14 verified 17598 files (316 creates). Sat Jan 21 22:01:13 2012 Child 11 verified 17580 files (298 creates). Sat Jan 21 22:01:18 2012 Child 2 verified 17551 files (269 creates). Sat Jan 21 22:01:21 2012 Child 9 verified 17551 files (269 creates). Sat Jan 21 22:01:21 2012 Child 0 verified 17551 files (269 creates). Sat Jan 21 22:01:21 2012 Child 3 verified 17551 files (269 creates). Sat Jan 21 22:03:01 2012 Child 4 verified 17866 files (584 creates). Sat Jan 21 22:03:01 2012 Child 13 verified 17866 files (584 creates). Sat Jan 21 22:03:01 2012 Child 7 verified 17866 files (584 creates). Sat Jan 21 22:03:01 2012 Child 12 verified 17866 files (584 creates). Sat Jan 21 22:03:01 2012 Child 5 verified 17958 files (676 creates). Sat Jan 21 22:03:01 2012 Child 1 verified 17866 files (584 creates). Sat Jan 21 22:06:28 2012 Child 3 verified 17848 files (566 creates). Sat Jan 21 22:06:28 2012 Child 6 verified 17902 files (620 creates). Sat Jan 21 22:06:28 2012 Child 8 verified 17798 files (516 creates). Sat Jan 21 22:06:33 2012 Child 9 verified 17798 files (516 creates). Sat Jan 21 22:06:36 2012 Child 2 verified 17798 files (516 creates). Sat Jan 21 22:06:36 2012 Child 11 verified 17857 files (575 creates). Sat Jan 21 22:06:36 2012 Child 14 verified 17866 files (584 creates). Sat Jan 21 22:06:36 2012 Child 10 verified 17857 files (575 creates). Sat Jan 21 22:06:36 2012 Child 15 verified 17857 files (575 creates). Sat Jan 21 22:06:36 2012 Child 0 verified 17798 files (516 creates). Sat Jan 21 22:08:23 2012 Child 5 verified 18204 files (922 creates). Sat Jan 21 22:08:23 2012 Child 4 verified 18077 files (795 creates). Sat Jan 21 22:08:23 2012 Child 12 verified 18071 files (789 creates). Sat Jan 21 22:08:23 2012 Child 13 verified 18063 files (781 creates). Sat Jan 21 22:08:27 2012 Child 1 verified 18039 files (757 creates). Sat Jan 21 22:08:27 2012 Child 7 verified 18035 files (753 creates). Sat Jan 21 22:12:17 2012 Child 11 verified 18165 files (883 creates). Sat Jan 21 22:12:17 2012 Child 6 verified 18134 files (852 creates). Sat Jan 21 22:12:17 2012 Child 0 verified 18039 files (757 creates). Sat Jan 21 22:12:17 2012 Child 3 verified 18134 files (852 creates). Sat Jan 21 22:12:17 2012 Child 9 verified 18039 files (757 creates). Sat Jan 21 22:12:17 2012 Child 8 verified 18039 files (757 creates). Sat Jan 21 22:12:17 2012 Child 2 verified 18039 files (757 creates). Sat Jan 21 22:12:17 2012 Child 15 verified 18120 files (838 creates). Sat Jan 21 22:12:17 2012 Child 14 verified 18111 files (829 creates). Sat Jan 21 22:12:17 2012 Child 10 verified 18120 files (838 creates). Sat Jan 21 22:15:57 2012 Child 5 verified 18734 files (1452 creates). Sat Jan 21 22:15:57 2012 Child 12 verified 18598 files (1316 creates). Sat Jan 21 22:15:57 2012 Child 4 verified 18607 files (1325 creates). Sat Jan 21 22:15:57 2012 Child 1 verified 18558 files (1276 creates). Sat Jan 21 22:15:57 2012 Child 13 verified 18558 files (1276 creates). Sat Jan 21 22:15:57 2012 Child 7 verified 18558 files (1276 creates). Sat Jan 21 22:16:16 2012 Child 5 verified 19139 files, created 1670 Sat Jan 21 22:16:16 2012 Child 5 will create 2 symlinks. Sat Jan 21 22:16:16 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 22:16:27 2012 Child 4 verified 19139 files, created 1670 Sat Jan 21 22:16:27 2012 Child 4 will create 2 symlinks. Sat Jan 21 22:16:27 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 22:16:28 2012 Child 7 verified 19139 files, created 1670 Sat Jan 21 22:16:28 2012 Child 7 will create 2 symlinks. Sat Jan 21 22:16:28 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 22:16:28 2012 Child 12 verified 19139 files, created 1670 Sat Jan 21 22:16:28 2012 Child 12 will create 2 symlinks. Sat Jan 21 22:16:28 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 22:16:28 2012 Child 1 verified 19139 files, created 1670 Sat Jan 21 22:16:28 2012 Child 1 will create 2 symlinks. Sat Jan 21 22:16:28 2012 Child 13 verified 19139 files, created 1670 Sat Jan 21 22:16:28 2012 Child 13 will create 2 symlinks. Sat Jan 21 22:16:28 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 22:16:28 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 22:18:18 2012 Child 8 verified 18558 files (1276 creates). Sat Jan 21 22:18:18 2012 Child 9 verified 18558 files (1276 creates). Sat Jan 21 22:18:18 2012 Child 2 verified 18558 files (1276 creates). Sat Jan 21 22:18:18 2012 Child 6 verified 18654 files (1372 creates). Sat Jan 21 22:18:18 2012 Child 10 verified 18654 files (1372 creates). Sat Jan 21 22:18:18 2012 Child 15 verified 18654 files (1372 creates). Sat Jan 21 22:18:18 2012 Child 0 verified 18558 files (1276 creates). Sat Jan 21 22:18:18 2012 Child 3 verified 18654 files (1372 creates). Sat Jan 21 22:18:18 2012 Child 11 verified 18698 files (1416 creates). Sat Jan 21 22:18:18 2012 Child 14 verified 18654 files (1372 creates). Sat Jan 21 22:18:37 2012 Child 11 verified 19139 files, created 1670 Sat Jan 21 22:18:37 2012 Child 11 will create 2 symlinks. Sat Jan 21 22:18:37 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 22:18:38 2012 Child 6 verified 19139 files, created 1670 Sat Jan 21 22:18:38 2012 Child 6 will create 2 symlinks. Sat Jan 21 22:18:38 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 22:18:38 2012 Child 15 verified 19139 files, created 1670 Sat Jan 21 22:18:38 2012 Child 15 will create 2 symlinks. Sat Jan 21 22:18:38 2012 Child 3 verified 19139 files, created 1670 Sat Jan 21 22:18:38 2012 Child 3 will create 2 symlinks. Sat Jan 21 22:18:38 2012 Child 10 verified 19139 files, created 1670 Sat Jan 21 22:18:38 2012 Child 10 will create 2 symlinks. Sat Jan 21 22:18:38 2012 Child 14 verified 19139 files, created 1670 Sat Jan 21 22:18:38 2012 Child 14 will create 2 symlinks. Sat Jan 21 22:18:38 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 22:18:38 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 22:18:38 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 22:18:38 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 22:18:40 2012 Child 2 verified 19139 files, created 1670 Sat Jan 21 22:18:40 2012 Child 2 will create 2 symlinks. Sat Jan 21 22:18:40 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 22:18:40 2012 Child 0 verified 19139 files, created 1670 Sat Jan 21 22:18:40 2012 Child 0 will create 2 symlinks. Sat Jan 21 22:18:40 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 22:18:40 2012 Child 9 verified 19139 files, created 1670 Sat Jan 21 22:18:40 2012 Child 9 will create 2 symlinks. Sat Jan 21 22:18:40 2012 Child 8 verified 19139 files, created 1670 Sat Jan 21 22:18:40 2012 Child 8 will create 2 symlinks. Sat Jan 21 22:18:40 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 22:18:40 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 22:18:40 2012 Completed. Sat Jan 21 22:18:40 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:18:40 2012 Completed. Sat Jan 21 22:18:40 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:18:41 2012 Received. Sat Jan 21 22:18:41 2012 Performing 300 seconds pretest warmup. Sat Jan 21 22:23:41 2012 Completed. Sat Jan 21 22:23:41 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:23:41 2012 Completed. Sat Jan 21 22:23:41 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:23:42 2012 Received. Sat Jan 21 22:23:42 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 22:28:52 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.9% 41490 41490 0 7.88 30.17 0.05 5.1% setattr 4.0% 3.9% 6320 6320 0 89.09 187.63 0.34 8.7% lookup 24.0% 24.2% 38757 38757 0 7.76 26.16 0.05 4.7% readlink 1.0% 1.0% 1531 1531 0 7.72 26.41 0.26 0.2% read 18.0% 17.9% 28612 35379 0 107.38 262.48 0.19 47.7% write 10.0% 10.0% 15992 19168 0 60.36 127.30 0.17 15.0% create 1.0% 1.0% 1600 1600 0 165.73 276.31 0.81 4.1% remove 1.0% 1.0% 1540 1540 0 101.78 194.03 0.70 2.4% readdir 1.0% 1.0% 1594 1594 0 7.48 21.53 0.23 0.2% fsstat 1.0% 1.0% 1633 1633 0 7.50 24.58 0.24 0.2% access 11.0% 11.1% 17845 17845 0 7.77 25.17 0.07 2.2% commit 0.0% 0.0% 0 5768 0 0.00 0.00 0.00 9.1% readdirplus 2.0% 2.1% 3287 3287 0 8.92 25.40 0.17 0.5% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 535.78 Ops/Sec AVG. RESPONSE TIME: 40.21 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1300 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 160201 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 175912 PHYSICAL NFS V3 THROUGHPUT: 588.33 Ops/Sec FILE SET SIZE CREATED: 159410448.0 KB FILE SET SIZE ACCESSED: 47823984.0 - 48103036.0 KB (100.00% to 100.58% of Base) ------------------------------------------------------------------------ Sat Jan 21 22:28:52 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 1300 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 300208 Files created for I/O operations 90048 Files accessed for I/O operations 6016 Files for non-I/O operations 32 Symlinks 10000 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 22:28:52 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:28:52 2012 Completed. Sat Jan 21 22:28:52 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 22:28:53 2012 Received. Sat Jan 21 22:28:53 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 22:28:53 2012 Completed. 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 NFS V3 Protocol Version ************************************************************************ ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 22:28:55 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 22:28:55 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 22:29:09 2012 Completed. Sat Jan 21 22:29:09 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:29:09 2012 Completed. Sat Jan 21 22:29:09 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:29:11 2012 Received. Sat Jan 21 22:29:11 2012 Initializing test directories. Sat Jan 21 22:29:11 2012 Child 14 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 10 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 13 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 5 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 12 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 1 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 2 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 11 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 6 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 4 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 0 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 7 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 8 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 15 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 3 will create 675 directories. Sat Jan 21 22:29:11 2012 Child 9 will create 675 directories. Sat Jan 21 22:29:12 2012 Child 0 finished creating 675 directories. Sat Jan 21 22:29:12 2012 Child 0 will create 20649 files. Sat Jan 21 22:29:15 2012 Child 13 finished creating 675 directories. Sat Jan 21 22:29:15 2012 Child 13 will create 20649 files. Sat Jan 21 22:29:18 2012 Child 14 finished creating 675 directories. Sat Jan 21 22:29:18 2012 Child 14 will create 20649 files. Sat Jan 21 22:29:19 2012 Child 10 finished creating 675 directories. Sat Jan 21 22:29:19 2012 Child 10 will create 20649 files. Sat Jan 21 22:29:25 2012 Child 12 finished creating 675 directories. Sat Jan 21 22:29:25 2012 Child 12 will create 20649 files. Sat Jan 21 22:29:25 2012 Child 4 finished creating 675 directories. Sat Jan 21 22:29:25 2012 Child 4 will create 20649 files. Sat Jan 21 22:29:26 2012 Child 8 finished creating 675 directories. Sat Jan 21 22:29:26 2012 Child 8 will create 20649 files. Sat Jan 21 22:29:29 2012 Child 7 finished creating 675 directories. Sat Jan 21 22:29:29 2012 Child 7 will create 20649 files. Sat Jan 21 22:29:35 2012 Child 11 finished creating 675 directories. Sat Jan 21 22:29:35 2012 Child 11 will create 20649 files. Sat Jan 21 22:29:37 2012 Child 1 finished creating 675 directories. Sat Jan 21 22:29:37 2012 Child 1 will create 20649 files. Sat Jan 21 22:29:43 2012 Child 2 finished creating 675 directories. Sat Jan 21 22:29:43 2012 Child 2 will create 20649 files. Sat Jan 21 22:29:46 2012 Child 15 finished creating 675 directories. Sat Jan 21 22:29:46 2012 Child 15 will create 20649 files. Sat Jan 21 22:30:09 2012 Child 5 finished creating 675 directories. Sat Jan 21 22:30:09 2012 Child 5 will create 20649 files. Sat Jan 21 22:30:12 2012 Child 3 finished creating 675 directories. Sat Jan 21 22:30:12 2012 Child 3 will create 20649 files. Sat Jan 21 22:30:14 2012 Child 9 finished creating 675 directories. Sat Jan 21 22:30:14 2012 Child 9 will create 20649 files. Sat Jan 21 22:30:15 2012 Child 6 finished creating 675 directories. Sat Jan 21 22:30:15 2012 Child 6 will create 20649 files. Sat Jan 21 22:32:08 2012 Child 0 verified 20649 files, created 1684 Sat Jan 21 22:32:08 2012 Child 0 will create 2 symlinks. Sat Jan 21 22:32:08 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 22:32:56 2012 Child 13 verified 20649 files, created 1684 Sat Jan 21 22:32:56 2012 Child 13 will create 2 symlinks. Sat Jan 21 22:32:56 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 22:33:28 2012 Child 14 verified 20649 files, created 1684 Sat Jan 21 22:33:28 2012 Child 14 will create 2 symlinks. Sat Jan 21 22:33:29 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 22:33:43 2012 Child 10 verified 20649 files, created 1684 Sat Jan 21 22:33:43 2012 Child 10 will create 2 symlinks. Sat Jan 21 22:33:43 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 22:34:36 2012 Child 11 verified 20147 files (1384 creates). Sat Jan 21 22:34:36 2012 Child 4 verified 20649 files, created 1684 Sat Jan 21 22:34:36 2012 Child 4 will create 2 symlinks. Sat Jan 21 22:34:37 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 22:34:37 2012 Child 12 verified 20649 files, created 1684 Sat Jan 21 22:34:37 2012 Child 12 will create 2 symlinks. Sat Jan 21 22:34:37 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 22:34:41 2012 Child 1 verified 20152 files (1389 creates). Sat Jan 21 22:34:47 2012 Child 2 verified 20056 files (1293 creates). Sat Jan 21 22:34:47 2012 Child 15 verified 20009 files (1246 creates). Sat Jan 21 22:34:50 2012 Child 7 verified 20649 files, created 1684 Sat Jan 21 22:34:50 2012 Child 7 will create 2 symlinks. Sat Jan 21 22:34:51 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 22:34:58 2012 Child 11 verified 20649 files, created 1684 Sat Jan 21 22:34:58 2012 Child 11 will create 2 symlinks. Sat Jan 21 22:34:58 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 22:34:59 2012 Child 1 verified 20649 files, created 1684 Sat Jan 21 22:34:59 2012 Child 1 will create 2 symlinks. Sat Jan 21 22:35:00 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 22:35:01 2012 Child 8 verified 20649 files, created 1684 Sat Jan 21 22:35:01 2012 Child 8 will create 2 symlinks. Sat Jan 21 22:35:04 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 22:35:06 2012 Child 2 verified 20649 files, created 1684 Sat Jan 21 22:35:06 2012 Child 2 will create 2 symlinks. Sat Jan 21 22:35:07 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 22:35:10 2012 Child 15 verified 20649 files, created 1684 Sat Jan 21 22:35:10 2012 Child 15 will create 2 symlinks. Sat Jan 21 22:35:10 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 22:35:14 2012 Child 5 verified 20056 files (1293 creates). Sat Jan 21 22:35:14 2012 Child 3 verified 20056 files (1293 creates). Sat Jan 21 22:35:15 2012 Child 9 verified 20108 files (1345 creates). Sat Jan 21 22:35:16 2012 Child 6 verified 20148 files (1385 creates). Sat Jan 21 22:35:32 2012 Child 9 verified 20649 files, created 1684 Sat Jan 21 22:35:32 2012 Child 9 will create 2 symlinks. Sat Jan 21 22:35:32 2012 Child 5 verified 20649 files, created 1684 Sat Jan 21 22:35:32 2012 Child 5 will create 2 symlinks. Sat Jan 21 22:35:32 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 22:35:32 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 22:35:32 2012 Child 6 verified 20649 files, created 1684 Sat Jan 21 22:35:32 2012 Child 6 will create 2 symlinks. Sat Jan 21 22:35:32 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 22:35:40 2012 Child 3 verified 20649 files, created 1684 Sat Jan 21 22:35:40 2012 Child 3 will create 2 symlinks. Sat Jan 21 22:35:40 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 22:35:41 2012 Completed. Sat Jan 21 22:35:41 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:35:41 2012 Completed. Sat Jan 21 22:35:41 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:35:42 2012 Received. Sat Jan 21 22:35:42 2012 Performing 300 seconds pretest warmup. Sat Jan 21 22:40:42 2012 Completed. Sat Jan 21 22:40:42 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:40:42 2012 Completed. Sat Jan 21 22:40:42 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:40:43 2012 Received. Sat Jan 21 22:40:43 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 22:45:54 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.7% 12947 12947 0 33.93 75.66 0.15 7.5% setattr 4.0% 4.0% 2010 2010 0 186.72 244.52 0.68 6.4% lookup 24.0% 24.1% 12124 12124 0 39.28 85.80 0.16 8.2% readlink 1.0% 1.0% 517 517 0 27.96 56.71 0.65 0.2% read 18.0% 17.9% 9002 11109 0 286.04 501.12 0.46 44.2% write 10.0% 10.0% 5020 6159 0 221.01 224.24 0.41 19.0% create 1.0% 1.0% 489 489 0 305.57 354.18 1.67 2.6% remove 1.0% 1.1% 531 531 0 188.46 250.11 1.34 1.7% readdir 1.0% 1.1% 557 557 0 26.47 54.80 0.61 0.3% fsstat 1.0% 1.0% 498 498 0 32.81 52.35 0.64 0.3% access 11.0% 11.2% 5649 5649 0 31.47 84.70 0.24 3.1% commit 0.0% 0.0% 0 1716 0 0.00 0.00 0.00 5.6% readdirplus 2.0% 2.1% 1042 1042 0 49.65 95.60 0.59 0.9% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 169.08 Ops/Sec AVG. RESPONSE TIME: 115.66 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1400 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 50386 TEST TIME: 298 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 55348 PHYSICAL NFS V3 THROUGHPUT: 185.73 Ops/Sec FILE SET SIZE CREATED: 171993024.0 KB FILE SET SIZE ACCESSED: 51604704.0 - 51676224.0 KB (100.00% to 100.14% of Base) ------------------------------------------------------------------------ Sat Jan 21 22:45:54 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 1400 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 323904 Files created for I/O operations 97168 Files accessed for I/O operations 6480 Files for non-I/O operations 32 Symlinks 10784 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 22:45:54 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:45:54 2012 Completed. Sat Jan 21 22:45:54 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 22:45:55 2012 Received. Sat Jan 21 22:45:55 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 22:45:55 2012 Completed. 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 NFS V3 Protocol Version ../binaries/linux-x86/sfsnfs3: NFS User ID only supported on Windows client ../binaries/linux-x86/sfsnfs3: NFS Group ID only supported on Windows client c09-44: Good clock resolution [ 1.000000 ] Micro seconds. SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 ************************************************************************ SFS NFS V3 Benchmark Client Logfile, Sat Jan 21 22:45:57 2012 Client hostname = c09-44 Prime Client hostname = c09-44.sysnet.ucsd.edu SPEC SFS Benchmark Version 2008, Creation - 1 February 2008 NFS V3 Protocol Sat Jan 21 22:45:57 2012 Mounting 16 remote test directories. Child[000]: NFS negotiated block size 32768 Child[001]: NFS negotiated block size 32768 Child[002]: NFS negotiated block size 32768 Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[005]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: NFS negotiated block size 32768 Child[008]: NFS negotiated block size 32768 Child[009]: NFS negotiated block size 32768 Child[010]: NFS negotiated block size 32768 Child[011]: NFS negotiated block size 32768 Child[012]: NFS negotiated block size 32768 Child[013]: NFS negotiated block size 32768 Child[014]: NFS negotiated block size 32768 Child[015]: NFS negotiated block size 32768 Sat Jan 21 22:46:11 2012 Completed. Sat Jan 21 22:46:11 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:46:11 2012 Completed. Sat Jan 21 22:46:11 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 22:46:13 2012 Received. Sat Jan 21 22:46:13 2012 Initializing test directories. Sat Jan 21 22:46:13 2012 Child 15 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 11 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 14 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 0 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 2 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 8 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 13 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 1 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 4 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 12 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 3 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 10 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 5 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 9 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 6 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 7 will create 721 directories. Sat Jan 21 22:46:13 2012 Child 9 finished creating 721 directories. Sat Jan 21 22:46:13 2012 Child 9 will create 22035 files. Sat Jan 21 22:46:32 2012 Child 5 finished creating 721 directories. Sat Jan 21 22:46:32 2012 Child 5 will create 22035 files. Sat Jan 21 22:46:32 2012 Child 11 finished creating 721 directories. Sat Jan 21 22:46:32 2012 Child 11 will create 22035 files. Sat Jan 21 22:46:32 2012 Child 15 finished creating 721 directories. Sat Jan 21 22:46:32 2012 Child 15 will create 22035 files. Sat Jan 21 22:46:34 2012 Child 1 finished creating 721 directories. Sat Jan 21 22:46:34 2012 Child 1 will create 22035 files. Sat Jan 21 22:46:40 2012 Child 3 finished creating 721 directories. Sat Jan 21 22:46:40 2012 Child 3 will create 22035 files. Sat Jan 21 22:46:41 2012 Child 8 finished creating 721 directories. Sat Jan 21 22:46:41 2012 Child 8 will create 22035 files. Sat Jan 21 22:46:44 2012 Child 0 finished creating 721 directories. Sat Jan 21 22:46:44 2012 Child 0 will create 22035 files. Sat Jan 21 22:46:45 2012 Child 4 finished creating 721 directories. Sat Jan 21 22:46:45 2012 Child 4 will create 22035 files. Sat Jan 21 22:46:48 2012 Child 7 finished creating 721 directories. Sat Jan 21 22:46:48 2012 Child 7 will create 22035 files. Sat Jan 21 22:46:50 2012 Child 10 finished creating 721 directories. Sat Jan 21 22:46:50 2012 Child 10 will create 22035 files. Sat Jan 21 22:46:56 2012 Child 6 finished creating 721 directories. Sat Jan 21 22:46:56 2012 Child 6 will create 22035 files. Sat Jan 21 22:47:04 2012 Child 13 finished creating 721 directories. Sat Jan 21 22:47:04 2012 Child 13 will create 22035 files. Sat Jan 21 22:47:20 2012 Child 14 finished creating 721 directories. Sat Jan 21 22:47:20 2012 Child 14 will create 22035 files. Sat Jan 21 22:47:55 2012 Child 12 finished creating 721 directories. Sat Jan 21 22:47:55 2012 Child 12 will create 22035 files. Sat Jan 21 22:47:55 2012 Child 2 finished creating 721 directories. Sat Jan 21 22:47:55 2012 Child 2 will create 22035 files. Sat Jan 21 22:55:49 2012 Child 9 verified 21076 files (832 creates). Sat Jan 21 22:55:49 2012 Child 3 verified 20525 files (281 creates). Sat Jan 21 22:55:51 2012 Child 14 verified 20246 files (2 creates). Sat Jan 21 22:55:51 2012 Child 5 verified 20618 files (374 creates). Sat Jan 21 22:55:52 2012 Child 13 verified 20389 files (145 creates). Sat Jan 21 22:55:52 2012 Child 2 verified 20246 files (2 creates). Sat Jan 21 22:55:52 2012 Child 12 verified 20246 files (2 creates). Sat Jan 21 22:55:52 2012 Child 0 verified 20456 files (212 creates). Sat Jan 21 22:55:52 2012 Child 11 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 6 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 7 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 8 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 15 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 1 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 4 verified 20487 files (243 creates). Sat Jan 21 22:55:52 2012 Child 10 verified 20456 files (212 creates). Sat Jan 21 23:00:53 2012 Child 12 verified 20588 files (344 creates). Sat Jan 21 23:00:54 2012 Child 0 verified 20821 files (577 creates). Sat Jan 21 23:00:54 2012 Child 9 verified 21467 files (1223 creates). Sat Jan 21 23:00:55 2012 Child 14 verified 20626 files (382 creates). Sat Jan 21 23:00:58 2012 Child 2 verified 20588 files (344 creates). Sat Jan 21 23:00:58 2012 Child 5 verified 20916 files (672 creates). Sat Jan 21 23:00:58 2012 Child 7 verified 20890 files (646 creates). Sat Jan 21 23:00:58 2012 Child 8 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 3 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 13 verified 20750 files (506 creates). Sat Jan 21 23:00:58 2012 Child 11 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 4 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 1 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 6 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 15 verified 20827 files (583 creates). Sat Jan 21 23:00:58 2012 Child 10 verified 20827 files (583 creates). Sat Jan 21 23:03:37 2012 Child 9 verified 22035 files, created 1575 Sat Jan 21 23:03:37 2012 Child 9 will create 2 symlinks. Sat Jan 21 23:03:37 2012 Child 9 finished creating 2 symlinks. Sat Jan 21 23:07:02 2012 Child 13 verified 21149 files (905 creates). Sat Jan 21 23:07:02 2012 Child 7 verified 21307 files (1063 creates). Sat Jan 21 23:07:02 2012 Child 14 verified 20992 files (748 creates). Sat Jan 21 23:07:08 2012 Child 0 verified 21226 files (982 creates). Sat Jan 21 23:07:17 2012 Child 5 verified 21395 files (1151 creates). Sat Jan 21 23:07:22 2012 Child 6 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 10 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 11 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 15 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 8 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 1 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 4 verified 21185 files (941 creates). Sat Jan 21 23:07:22 2012 Child 12 verified 20944 files (700 creates). Sat Jan 21 23:07:22 2012 Child 2 verified 20944 files (700 creates). Sat Jan 21 23:07:22 2012 Child 3 verified 21185 files (941 creates). Sat Jan 21 23:07:53 2012 Child 5 verified 22035 files, created 1575 Sat Jan 21 23:07:53 2012 Child 5 will create 2 symlinks. Sat Jan 21 23:07:54 2012 Child 5 finished creating 2 symlinks. Sat Jan 21 23:08:01 2012 Child 7 verified 22035 files, created 1575 Sat Jan 21 23:08:01 2012 Child 7 will create 2 symlinks. Sat Jan 21 23:08:01 2012 Child 7 finished creating 2 symlinks. Sat Jan 21 23:08:06 2012 Child 0 verified 22035 files, created 1575 Sat Jan 21 23:08:06 2012 Child 0 will create 2 symlinks. Sat Jan 21 23:08:06 2012 Child 0 finished creating 2 symlinks. Sat Jan 21 23:08:07 2012 Child 6 verified 22035 files, created 1575 Sat Jan 21 23:08:07 2012 Child 6 will create 2 symlinks. Sat Jan 21 23:08:08 2012 Child 6 finished creating 2 symlinks. Sat Jan 21 23:08:08 2012 Child 4 verified 22035 files, created 1575 Sat Jan 21 23:08:08 2012 Child 4 will create 2 symlinks. Sat Jan 21 23:08:08 2012 Child 4 finished creating 2 symlinks. Sat Jan 21 23:08:09 2012 Child 8 verified 22035 files, created 1575 Sat Jan 21 23:08:09 2012 Child 8 will create 2 symlinks. Sat Jan 21 23:08:09 2012 Child 8 finished creating 2 symlinks. Sat Jan 21 23:08:09 2012 Child 10 verified 22035 files, created 1575 Sat Jan 21 23:08:09 2012 Child 10 will create 2 symlinks. Sat Jan 21 23:08:09 2012 Child 10 finished creating 2 symlinks. Sat Jan 21 23:08:10 2012 Child 1 verified 22035 files, created 1575 Sat Jan 21 23:08:10 2012 Child 1 will create 2 symlinks. Sat Jan 21 23:08:10 2012 Child 1 finished creating 2 symlinks. Sat Jan 21 23:08:10 2012 Child 15 verified 22035 files, created 1575 Sat Jan 21 23:08:10 2012 Child 15 will create 2 symlinks. Sat Jan 21 23:08:10 2012 Child 15 finished creating 2 symlinks. Sat Jan 21 23:08:11 2012 Child 13 verified 22035 files, created 1575 Sat Jan 21 23:08:11 2012 Child 13 will create 2 symlinks. Sat Jan 21 23:08:11 2012 Child 3 verified 22035 files, created 1575 Sat Jan 21 23:08:11 2012 Child 3 will create 2 symlinks. Sat Jan 21 23:08:11 2012 Child 11 verified 22035 files, created 1575 Sat Jan 21 23:08:11 2012 Child 11 will create 2 symlinks. Sat Jan 21 23:08:11 2012 Child 13 finished creating 2 symlinks. Sat Jan 21 23:08:11 2012 Child 3 finished creating 2 symlinks. Sat Jan 21 23:08:11 2012 Child 11 finished creating 2 symlinks. Sat Jan 21 23:08:13 2012 Child 14 verified 22035 files, created 1575 Sat Jan 21 23:08:13 2012 Child 14 will create 2 symlinks. Sat Jan 21 23:08:13 2012 Child 14 finished creating 2 symlinks. Sat Jan 21 23:08:14 2012 Child 12 verified 22035 files, created 1575 Sat Jan 21 23:08:14 2012 Child 12 will create 2 symlinks. Sat Jan 21 23:08:14 2012 Child 12 finished creating 2 symlinks. Sat Jan 21 23:08:14 2012 Child 2 verified 22035 files, created 1575 Sat Jan 21 23:08:14 2012 Child 2 will create 2 symlinks. Sat Jan 21 23:08:14 2012 Child 2 finished creating 2 symlinks. Sat Jan 21 23:08:15 2012 Completed. Sat Jan 21 23:08:15 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 23:08:15 2012 Completed. Sat Jan 21 23:08:15 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 23:08:16 2012 Received. Sat Jan 21 23:08:16 2012 Performing 300 seconds pretest warmup. Sat Jan 21 23:13:16 2012 Completed. Sat Jan 21 23:13:16 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 23:13:16 2012 Completed. Sat Jan 21 23:13:16 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 23:13:17 2012 Received. Sat Jan 21 23:13:17 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Sat Jan 21 23:18:27 2012 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.8% 29181 29181 0 9.39 26.42 0.06 4.4% setattr 4.0% 4.0% 4555 4555 0 139.53 267.74 0.48 10.3% lookup 24.0% 24.1% 27239 27239 0 9.68 28.45 0.06 4.3% readlink 1.0% 1.0% 1122 1122 0 9.48 28.83 0.31 0.2% read 18.0% 18.2% 20562 25077 0 142.65 290.60 0.23 47.5% write 10.0% 9.8% 11101 13534 0 75.66 119.13 0.20 13.6% create 1.0% 1.0% 1145 1145 0 261.35 386.93 1.14 4.8% remove 1.0% 1.0% 1087 1087 0 151.10 272.71 0.98 2.7% readdir 1.0% 1.0% 1110 1110 0 8.61 19.95 0.26 0.2% fsstat 1.0% 1.0% 1155 1155 0 11.10 30.49 0.32 0.2% access 11.0% 11.0% 12454 12454 0 9.89 30.96 0.10 2.0% commit 0.0% 0.0% 0 3849 0 0.00 0.00 0.00 9.6% readdirplus 2.0% 2.0% 2262 2262 0 10.70 33.26 0.24 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 377.83 Ops/Sec AVG. RESPONSE TIME: 54.70 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1500 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 112973 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 123770 PHYSICAL NFS V3 THROUGHPUT: 413.94 Ops/Sec FILE SET SIZE CREATED: 183530592.0 KB FILE SET SIZE ACCESSED: 55062576.0 - 55250980.0 KB (100.00% to 100.34% of Base) ------------------------------------------------------------------------ Sat Jan 21 23:18:27 2012 Completed. Client Test Parameters: Number of processes = 16 Requested Load (NFS V3 operations/second) = 1500 Maximum number of outstanding biod writes = 8 Maximum number of outstanding biod reads = 8 Warm-up time (seconds) = 300 Run time (seconds) = 300 File Set = 345632 Files created for I/O operations 103680 Files accessed for I/O operations 6928 Files for non-I/O operations 32 Symlinks 11520 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Sat Jan 21 23:18:27 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Sat Jan 21 23:18:27 2012 Completed. Sat Jan 21 23:18:27 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Sat Jan 21 23:18:28 2012 Received. Sat Jan 21 23:18:28 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Sat Jan 21 23:18:28 2012 Completed.