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, Mon Jan 9 21:55: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 Mon Jan 9 21:55:50 2012 Mounting 16 remote test directories. Child[009]: NFS negotiated block size 32768 Child[010]: 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[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[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 Mon Jan 9 21:56:08 2012 Completed. Mon Jan 9 21:56:08 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 21:56:08 2012 Completed. Mon Jan 9 21:56:08 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 21:56:08 2012 Received. Mon Jan 9 21:56:08 2012 Initializing test directories. Mon Jan 9 21:56:08 2012 Child 15 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 9 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 3 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 10 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 8 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 0 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 14 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 2 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 12 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 4 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 7 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 5 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 1 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 6 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 11 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 13 will create 46 directories. Mon Jan 9 21:56:08 2012 Child 13 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 13 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 8 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 8 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 12 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 12 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 9 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 9 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 11 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 11 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 5 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 5 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 4 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 4 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 1 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 1 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 6 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 6 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 3 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 3 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 10 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 10 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 0 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 0 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 2 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 2 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 15 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 15 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 7 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 7 will create 1386 files. Mon Jan 9 21:56:08 2012 Child 14 finished creating 46 directories. Mon Jan 9 21:56:08 2012 Child 14 will create 1386 files. Mon Jan 9 22:01:21 2012 Child 8 verified 426 files (426 creates). Mon Jan 9 22:01:21 2012 Child 15 verified 460 files (460 creates). Mon Jan 9 22:01:21 2012 Child 5 verified 457 files (457 creates). Mon Jan 9 22:01:21 2012 Child 13 verified 426 files (426 creates). Mon Jan 9 22:01:21 2012 Child 14 verified 461 files (461 creates). Mon Jan 9 22:01:22 2012 Child 11 verified 425 files (425 creates). Mon Jan 9 22:01:24 2012 Child 0 verified 456 files (456 creates). Mon Jan 9 22:01:24 2012 Child 12 verified 424 files (424 creates). Mon Jan 9 22:01:24 2012 Child 3 verified 420 files (420 creates). Mon Jan 9 22:01:24 2012 Child 2 verified 420 files (420 creates). Mon Jan 9 22:01:24 2012 Child 10 verified 420 files (420 creates). Mon Jan 9 22:01:24 2012 Child 7 verified 420 files (420 creates). Mon Jan 9 22:01:24 2012 Child 9 verified 453 files (453 creates). Mon Jan 9 22:01:24 2012 Child 4 verified 420 files (420 creates). Mon Jan 9 22:01:24 2012 Child 6 verified 420 files (420 creates). Mon Jan 9 22:01:24 2012 Child 1 verified 420 files (420 creates). Mon Jan 9 22:06:40 2012 Child 9 verified 873 files (873 creates). Mon Jan 9 22:06:42 2012 Child 5 verified 872 files (872 creates). Mon Jan 9 22:06:42 2012 Child 15 verified 872 files (872 creates). Mon Jan 9 22:06:48 2012 Child 6 verified 856 files (856 creates). Mon Jan 9 22:06:48 2012 Child 1 verified 856 files (856 creates). Mon Jan 9 22:06:49 2012 Child 14 verified 872 files (872 creates). Mon Jan 9 22:06:49 2012 Child 0 verified 872 files (872 creates). Mon Jan 9 22:06:50 2012 Child 10 verified 856 files (856 creates). Mon Jan 9 22:06:50 2012 Child 3 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 4 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 2 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 13 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 11 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 8 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 12 verified 856 files (856 creates). Mon Jan 9 22:06:51 2012 Child 7 verified 856 files (856 creates). Mon Jan 9 22:11:58 2012 Child 4 verified 1220 files (1220 creates). Mon Jan 9 22:11:58 2012 Child 6 verified 1220 files (1220 creates). Mon Jan 9 22:11:58 2012 Child 1 verified 1211 files (1211 creates). Mon Jan 9 22:12:01 2012 Child 9 verified 1247 files (1247 creates). Mon Jan 9 22:12:01 2012 Child 15 verified 1247 files (1247 creates). Mon Jan 9 22:12:01 2012 Child 5 verified 1247 files (1247 creates). Mon Jan 9 22:12:01 2012 Child 3 verified 1118 files (1118 creates). Mon Jan 9 22:12:01 2012 Child 10 verified 1118 files (1118 creates). Mon Jan 9 22:12:01 2012 Child 0 verified 1247 files (1247 creates). Mon Jan 9 22:12:01 2012 Child 8 verified 1118 files (1118 creates). Mon Jan 9 22:12:01 2012 Child 14 verified 1247 files (1247 creates). Mon Jan 9 22:12:01 2012 Child 7 verified 1118 files (1118 creates). Mon Jan 9 22:12:01 2012 Child 2 verified 1118 files (1118 creates). Mon Jan 9 22:12:01 2012 Child 13 verified 1118 files (1118 creates). Mon Jan 9 22:12:01 2012 Child 12 verified 1155 files (1155 creates). Mon Jan 9 22:12:01 2012 Child 11 verified 1118 files (1118 creates). Mon Jan 9 22:12:09 2012 Child 9 verified 1386 files, created 1373 Mon Jan 9 22:12:09 2012 Child 9 will create 2 symlinks. Mon Jan 9 22:12:09 2012 Child 9 finished creating 2 symlinks. Mon Jan 9 22:12:10 2012 Child 0 verified 1386 files, created 1373 Mon Jan 9 22:12:10 2012 Child 0 will create 2 symlinks. Mon Jan 9 22:12:10 2012 Child 0 finished creating 2 symlinks. Mon Jan 9 22:12:10 2012 Child 15 verified 1386 files, created 1373 Mon Jan 9 22:12:10 2012 Child 15 will create 2 symlinks. Mon Jan 9 22:12:10 2012 Child 14 verified 1386 files, created 1373 Mon Jan 9 22:12:10 2012 Child 14 will create 2 symlinks. Mon Jan 9 22:12:10 2012 Child 15 finished creating 2 symlinks. Mon Jan 9 22:12:10 2012 Child 14 finished creating 2 symlinks. Mon Jan 9 22:12:10 2012 Child 5 verified 1386 files, created 1373 Mon Jan 9 22:12:10 2012 Child 5 will create 2 symlinks. Mon Jan 9 22:12:10 2012 Child 5 finished creating 2 symlinks. Mon Jan 9 22:12:11 2012 Child 6 verified 1386 files, created 1373 Mon Jan 9 22:12:11 2012 Child 6 will create 2 symlinks. Mon Jan 9 22:12:11 2012 Child 6 finished creating 2 symlinks. Mon Jan 9 22:12:12 2012 Child 4 verified 1386 files, created 1373 Mon Jan 9 22:12:12 2012 Child 4 will create 2 symlinks. Mon Jan 9 22:12:12 2012 Child 4 finished creating 2 symlinks. Mon Jan 9 22:12:12 2012 Child 1 verified 1386 files, created 1373 Mon Jan 9 22:12:12 2012 Child 1 will create 2 symlinks. Mon Jan 9 22:12:12 2012 Child 1 finished creating 2 symlinks. Mon Jan 9 22:12:13 2012 Child 12 verified 1386 files, created 1373 Mon Jan 9 22:12:13 2012 Child 12 will create 2 symlinks. Mon Jan 9 22:12:13 2012 Child 12 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 2 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 2 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 2 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 13 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 13 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 13 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 8 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 8 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 3 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 3 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 7 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 7 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 8 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 7 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 3 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 10 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 10 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 11 verified 1386 files, created 1373 Mon Jan 9 22:12:15 2012 Child 11 will create 2 symlinks. Mon Jan 9 22:12:15 2012 Child 10 finished creating 2 symlinks. Mon Jan 9 22:12:15 2012 Child 11 finished creating 2 symlinks. Mon Jan 9 22:12:16 2012 Completed. Mon Jan 9 22:12:16 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:12:16 2012 Completed. Mon Jan 9 22:12:16 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:12:17 2012 Received. Mon Jan 9 22:12:17 2012 Performing 300 seconds pretest warmup. Mon Jan 9 22:17:17 2012 Completed. Mon Jan 9 22:17:17 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:17:17 2012 Completed. Mon Jan 9 22:17:17 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:17:18 2012 Received. Mon Jan 9 22:17:18 2012 Starting 300 seconds test run. Child 4 - 50 aborted ops Child 4 - 100 aborted ops SFS Single Client (c09-44) Results, Mon Jan 9 22:22:28 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% 7748 7748 0 0.19 0.03 0.00 5.3% setattr 4.0% 4.0% 1190 1190 0 0.93 0.94 0.05 4.0% lookup 24.0% 23.7% 7103 7103 0 0.20 0.03 0.00 5.0% readlink 1.0% 1.0% 302 302 0 0.19 0.04 0.02 0.2% read 18.0% 18.1% 5439 6642 0 1.27 2.95 0.05 24.7% write 10.0% 10.2% 3052 3705 0 3.91 14.73 0.14 42.7% create 1.0% 1.0% 303 303 0 1.91 1.25 0.13 2.1% remove 1.0% 1.0% 297 297 0 1.21 1.11 0.12 1.3% readdir 1.0% 1.0% 288 288 0 0.35 0.06 0.03 0.4% fsstat 1.0% 1.0% 293 293 0 0.19 0.02 0.02 0.2% access 11.0% 11.1% 3338 3338 0 0.19 0.04 0.01 2.3% commit 0.0% 0.0% 0 1343 0 0.00 0.00 0.00 10.7% readdirplus 2.0% 2.1% 629 629 0 0.55 0.05 0.02 1.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 99.94 Ops/Sec AVG. RESPONSE TIME: 0.93 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 100 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 29982 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 33181 PHYSICAL NFS V3 THROUGHPUT: 110.60 Ops/Sec FILE SET SIZE CREATED: 11537568.0 KB FILE SET SIZE ACCESSED: 3466368.0 - 3536575.0 KB (100.00% to 102.03% of Base) ------------------------------------------------------------------------ Mon Jan 9 22:22:28 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 Mon Jan 9 22:22:28 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:22:28 2012 Completed. Mon Jan 9 22:22:28 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Mon Jan 9 22:22:29 2012 Received. Mon Jan 9 22:22:29 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Mon Jan 9 22:22:29 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, Mon Jan 9 22:22:31 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 Mon Jan 9 22:22:31 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 Mon Jan 9 22:22:45 2012 Completed. Mon Jan 9 22:22:45 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:22:45 2012 Completed. Mon Jan 9 22:22:45 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:22:47 2012 Received. Mon Jan 9 22:22:47 2012 Initializing test directories. Mon Jan 9 22:22:47 2012 Child 12 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 7 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 5 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 11 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 0 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 8 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 4 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 10 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 13 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 6 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 3 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 9 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 14 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 1 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 15 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 2 will create 95 directories. Mon Jan 9 22:22:47 2012 Child 15 finished creating 95 directories. Mon Jan 9 22:22:47 2012 Child 15 will create 2897 files. Mon Jan 9 22:22:47 2012 Child 1 finished creating 95 directories. Mon Jan 9 22:22:47 2012 Child 1 will create 2897 files. Mon Jan 9 22:22:47 2012 Child 0 finished creating 95 directories. Mon Jan 9 22:22:47 2012 Child 0 will create 2897 files. Mon Jan 9 22:22:48 2012 Child 3 finished creating 95 directories. Mon Jan 9 22:22:48 2012 Child 3 will create 2897 files. Mon Jan 9 22:22:48 2012 Child 12 finished creating 95 directories. Mon Jan 9 22:22:48 2012 Child 12 will create 2897 files. Mon Jan 9 22:22:48 2012 Child 5 finished creating 95 directories. Mon Jan 9 22:22:48 2012 Child 5 will create 2897 files. Mon Jan 9 22:22:49 2012 Child 11 finished creating 95 directories. Mon Jan 9 22:22:49 2012 Child 11 will create 2897 files. Mon Jan 9 22:22:49 2012 Child 13 finished creating 95 directories. Mon Jan 9 22:22:49 2012 Child 13 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 7 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 7 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 9 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 9 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 8 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 8 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 14 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 14 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 6 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 6 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 4 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 4 will create 2897 files. Mon Jan 9 22:22:52 2012 Child 10 finished creating 95 directories. Mon Jan 9 22:22:52 2012 Child 10 will create 2897 files. Mon Jan 9 22:22:53 2012 Child 2 finished creating 95 directories. Mon Jan 9 22:22:53 2012 Child 2 will create 2897 files. Mon Jan 9 22:29:20 2012 Child 13 verified 1769 files (411 creates). Mon Jan 9 22:29:41 2012 Child 0 verified 1823 files (465 creates). Mon Jan 9 22:29:41 2012 Child 15 verified 1780 files (422 creates). Mon Jan 9 22:29:41 2012 Child 9 verified 1687 files (329 creates). Mon Jan 9 22:29:41 2012 Child 2 verified 1687 files (329 creates). Mon Jan 9 22:29:51 2012 Child 1 verified 1784 files (426 creates). Mon Jan 9 22:29:51 2012 Child 12 verified 1780 files (422 creates). Mon Jan 9 22:29:51 2012 Child 7 verified 1687 files (329 creates). Mon Jan 9 22:29:51 2012 Child 3 verified 1784 files (426 creates). Mon Jan 9 22:29:51 2012 Child 11 verified 1774 files (416 creates). Mon Jan 9 22:29:51 2012 Child 5 verified 1774 files (416 creates). Mon Jan 9 22:29:51 2012 Child 14 verified 1687 files (329 creates). Mon Jan 9 22:29:51 2012 Child 6 verified 1687 files (329 creates). Mon Jan 9 22:29:51 2012 Child 8 verified 1687 files (329 creates). Mon Jan 9 22:29:51 2012 Child 4 verified 1687 files (329 creates). Mon Jan 9 22:29:51 2012 Child 10 verified 1687 files (329 creates). Mon Jan 9 22:34:46 2012 Child 0 verified 2050 files (692 creates). Mon Jan 9 22:34:46 2012 Child 2 verified 1852 files (494 creates). Mon Jan 9 22:34:46 2012 Child 15 verified 1990 files (632 creates). Mon Jan 9 22:34:50 2012 Child 13 verified 1907 files (549 creates). Mon Jan 9 22:34:50 2012 Child 11 verified 1961 files (603 creates). Mon Jan 9 22:34:50 2012 Child 9 verified 1823 files (465 creates). Mon Jan 9 22:34:50 2012 Child 1 verified 1993 files (635 creates). Mon Jan 9 22:34:50 2012 Child 10 verified 1823 files (465 creates). Mon Jan 9 22:34:50 2012 Child 7 verified 1824 files (466 creates). Mon Jan 9 22:34:50 2012 Child 12 verified 1993 files (635 creates). Mon Jan 9 22:34:50 2012 Child 3 verified 1993 files (635 creates). Mon Jan 9 22:34:50 2012 Child 8 verified 1841 files (483 creates). Mon Jan 9 22:34:51 2012 Child 5 verified 1980 files (622 creates). Mon Jan 9 22:34:52 2012 Child 6 verified 1836 files (478 creates). Mon Jan 9 22:34:52 2012 Child 4 verified 1838 files (480 creates). Mon Jan 9 22:34:52 2012 Child 14 verified 1849 files (491 creates). Mon Jan 9 22:39:42 2012 Child 15 verified 2394 files (1036 creates). Mon Jan 9 22:39:42 2012 Child 2 verified 2300 files (942 creates). Mon Jan 9 22:39:42 2012 Child 0 verified 2467 files (1109 creates). Mon Jan 9 22:40:46 2012 Child 4 verified 2265 files (907 creates). Mon Jan 9 22:40:46 2012 Child 14 verified 2327 files (969 creates). Mon Jan 9 22:40:56 2012 Child 6 verified 2265 files (907 creates). Mon Jan 9 22:41:06 2012 Child 11 verified 2364 files (1006 creates). Mon Jan 9 22:41:10 2012 Child 12 verified 2370 files (1012 creates). Mon Jan 9 22:41:10 2012 Child 1 verified 2364 files (1006 creates). Mon Jan 9 22:41:10 2012 Child 9 verified 2203 files (845 creates). Mon Jan 9 22:41:10 2012 Child 5 verified 2378 files (1020 creates). Mon Jan 9 22:41:10 2012 Child 7 verified 2203 files (845 creates). Mon Jan 9 22:41:10 2012 Child 8 verified 2203 files (845 creates). Mon Jan 9 22:41:10 2012 Child 10 verified 2203 files (845 creates). Mon Jan 9 22:41:10 2012 Child 13 verified 2364 files (1006 creates). Mon Jan 9 22:41:10 2012 Child 3 verified 2404 files (1046 creates). Mon Jan 9 22:45:11 2012 Child 0 verified 2652 files (1294 creates). Mon Jan 9 22:45:16 2012 Child 2 verified 2448 files (1090 creates). Mon Jan 9 22:45:16 2012 Child 15 verified 2542 files (1184 creates). Mon Jan 9 22:45:56 2012 Child 14 verified 2482 files (1124 creates). Mon Jan 9 22:46:21 2012 Child 6 verified 2378 files (1020 creates). Mon Jan 9 22:46:24 2012 Child 3 verified 2561 files (1203 creates). Mon Jan 9 22:46:24 2012 Child 7 verified 2384 files (1026 creates). Mon Jan 9 22:46:24 2012 Child 9 verified 2379 files (1021 creates). Mon Jan 9 22:46:24 2012 Child 4 verified 2448 files (1090 creates). Mon Jan 9 22:46:24 2012 Child 11 verified 2501 files (1143 creates). Mon Jan 9 22:46:24 2012 Child 13 verified 2501 files (1143 creates). Mon Jan 9 22:46:24 2012 Child 12 verified 2501 files (1143 creates). Mon Jan 9 22:46:24 2012 Child 1 verified 2501 files (1143 creates). Mon Jan 9 22:46:24 2012 Child 8 verified 2379 files (1021 creates). Mon Jan 9 22:46:24 2012 Child 5 verified 2539 files (1181 creates). Mon Jan 9 22:46:24 2012 Child 10 verified 2404 files (1046 creates). Mon Jan 9 22:49:29 2012 Child 0 verified 2897 files, created 1511 Mon Jan 9 22:49:29 2012 Child 0 will create 2 symlinks. Mon Jan 9 22:49:29 2012 Child 0 finished creating 2 symlinks. Mon Jan 9 22:51:41 2012 Child 4 verified 2703 files (1345 creates). Mon Jan 9 22:51:42 2012 Child 10 verified 2680 files (1322 creates). Mon Jan 9 22:51:42 2012 Child 5 verified 2801 files (1443 creates). Mon Jan 9 22:51:45 2012 Child 9 verified 2560 files (1202 creates). Mon Jan 9 22:51:45 2012 Child 11 verified 2713 files (1355 creates). Mon Jan 9 22:51:45 2012 Child 8 verified 2560 files (1202 creates). Mon Jan 9 22:51:45 2012 Child 6 verified 2560 files (1202 creates). Mon Jan 9 22:51:45 2012 Child 7 verified 2560 files (1202 creates). Mon Jan 9 22:51:45 2012 Child 1 verified 2741 files (1383 creates). Mon Jan 9 22:51:49 2012 Child 3 verified 2897 files, created 1511 Mon Jan 9 22:51:49 2012 Child 3 will create 2 symlinks. Mon Jan 9 22:51:49 2012 Child 3 finished creating 2 symlinks. Mon Jan 9 22:51:52 2012 Child 15 verified 2897 files, created 1511 Mon Jan 9 22:51:52 2012 Child 15 will create 2 symlinks. Mon Jan 9 22:51:52 2012 Child 15 finished creating 2 symlinks. Mon Jan 9 22:52:15 2012 Child 2 verified 2741 files (1383 creates). Mon Jan 9 22:52:15 2012 Child 13 verified 2741 files (1383 creates). Mon Jan 9 22:52:15 2012 Child 14 verified 2741 files (1383 creates). Mon Jan 9 22:52:15 2012 Child 12 verified 2741 files (1383 creates). Mon Jan 9 22:52:21 2012 Child 5 verified 2897 files, created 1511 Mon Jan 9 22:52:21 2012 Child 5 will create 2 symlinks. Mon Jan 9 22:52:21 2012 Child 5 finished creating 2 symlinks. Mon Jan 9 22:52:24 2012 Child 1 verified 2897 files, created 1511 Mon Jan 9 22:52:24 2012 Child 1 will create 2 symlinks. Mon Jan 9 22:52:31 2012 Child 1 finished creating 2 symlinks. Mon Jan 9 22:52:31 2012 Child 14 verified 2897 files, created 1511 Mon Jan 9 22:52:31 2012 Child 14 will create 2 symlinks. Mon Jan 9 22:52:32 2012 Child 14 finished creating 2 symlinks. Mon Jan 9 22:52:32 2012 Child 10 verified 2897 files, created 1511 Mon Jan 9 22:52:32 2012 Child 10 will create 2 symlinks. Mon Jan 9 22:52:33 2012 Child 2 verified 2897 files, created 1511 Mon Jan 9 22:52:33 2012 Child 2 will create 2 symlinks. Mon Jan 9 22:52:34 2012 Child 13 verified 2897 files, created 1511 Mon Jan 9 22:52:34 2012 Child 13 will create 2 symlinks. Mon Jan 9 22:52:34 2012 Child 11 verified 2897 files, created 1511 Mon Jan 9 22:52:34 2012 Child 11 will create 2 symlinks. Mon Jan 9 22:52:34 2012 Child 4 verified 2897 files, created 1511 Mon Jan 9 22:52:34 2012 Child 12 verified 2897 files, created 1511 Mon Jan 9 22:52:34 2012 Child 4 will create 2 symlinks. Mon Jan 9 22:52:34 2012 Child 12 will create 2 symlinks. Mon Jan 9 22:52:35 2012 Child 11 finished creating 2 symlinks. Mon Jan 9 22:52:35 2012 Child 4 finished creating 2 symlinks. Mon Jan 9 22:52:35 2012 Child 13 finished creating 2 symlinks. Mon Jan 9 22:52:35 2012 Child 12 finished creating 2 symlinks. Mon Jan 9 22:52:36 2012 Child 2 finished creating 2 symlinks. Mon Jan 9 22:52:37 2012 Child 10 finished creating 2 symlinks. Mon Jan 9 22:52:37 2012 Child 6 verified 2897 files, created 1511 Mon Jan 9 22:52:37 2012 Child 6 will create 2 symlinks. Mon Jan 9 22:52:37 2012 Child 6 finished creating 2 symlinks. Mon Jan 9 22:52:38 2012 Child 7 verified 2897 files, created 1511 Mon Jan 9 22:52:38 2012 Child 7 will create 2 symlinks. Mon Jan 9 22:52:38 2012 Child 7 finished creating 2 symlinks. Mon Jan 9 22:52:38 2012 Child 9 verified 2897 files, created 1511 Mon Jan 9 22:52:38 2012 Child 9 will create 2 symlinks. Mon Jan 9 22:52:38 2012 Child 9 finished creating 2 symlinks. Mon Jan 9 22:52:40 2012 Child 8 verified 2897 files, created 1511 Mon Jan 9 22:52:40 2012 Child 8 will create 2 symlinks. Mon Jan 9 22:52:40 2012 Child 8 finished creating 2 symlinks. Mon Jan 9 22:52:40 2012 Completed. Mon Jan 9 22:52:40 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:52:40 2012 Completed. Mon Jan 9 22:52:40 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:52:40 2012 Received. Mon Jan 9 22:52:40 2012 Performing 300 seconds pretest warmup. Mon Jan 9 22:57:40 2012 Completed. Mon Jan 9 22:57:40 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:57:40 2012 Completed. Mon Jan 9 22:57:40 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 22:57:41 2012 Received. Mon Jan 9 22:57:41 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Mon Jan 9 23:02:51 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% 15630 15630 0 0.19 0.05 0.00 4.7% setattr 4.0% 4.1% 2444 2444 0 1.18 1.63 0.05 4.5% lookup 24.0% 23.9% 14314 14314 0 0.20 0.05 0.00 4.5% readlink 1.0% 1.0% 619 619 0 0.19 0.03 0.01 0.2% read 18.0% 18.1% 10843 13426 0 2.14 4.73 0.04 36.2% write 10.0% 9.9% 5965 7274 0 3.27 11.10 0.08 30.5% create 1.0% 1.0% 599 599 0 3.03 2.99 0.14 2.8% remove 1.0% 1.0% 622 622 0 1.93 2.18 0.12 1.9% readdir 1.0% 1.0% 604 604 0 0.39 0.08 0.02 0.4% fsstat 1.0% 1.0% 616 616 0 0.20 0.06 0.02 0.2% access 11.0% 10.9% 6543 6543 0 0.19 0.05 0.01 2.0% commit 0.0% 0.0% 0 2600 0 0.00 0.00 0.00 11.0% readdirplus 2.0% 2.0% 1207 1207 0 0.64 0.07 0.02 1.2% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 200.02 Ops/Sec AVG. RESPONSE TIME: 1.06 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 200 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 60006 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 66498 PHYSICAL NFS V3 THROUGHPUT: 221.66 Ops/Sec FILE SET SIZE CREATED: 24128640.0 KB FILE SET SIZE ACCESSED: 7247088.0 - 7391739.0 KB (100.00% to 102.00% of Base) ------------------------------------------------------------------------ Mon Jan 9 23:02:51 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 Mon Jan 9 23:02:51 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:02:51 2012 Completed. Mon Jan 9 23:02:51 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Mon Jan 9 23:02:52 2012 Received. Mon Jan 9 23:02:52 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Mon Jan 9 23:02:52 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, Mon Jan 9 23:02:54 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 Mon Jan 9 23:02:54 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 Mon Jan 9 23:03:08 2012 Completed. Mon Jan 9 23:03:08 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:03:08 2012 Completed. Mon Jan 9 23:03:08 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:03:10 2012 Received. Mon Jan 9 23:03:10 2012 Initializing test directories. Mon Jan 9 23:03:10 2012 Child 13 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 5 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 3 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 11 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 0 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 7 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 1 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 6 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 2 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 4 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 9 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 8 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 10 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 15 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 12 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 14 will create 145 directories. Mon Jan 9 23:03:10 2012 Child 4 finished creating 145 directories. Mon Jan 9 23:03:10 2012 Child 4 will create 4408 files. Mon Jan 9 23:03:11 2012 Child 5 finished creating 145 directories. Mon Jan 9 23:03:11 2012 Child 5 will create 4408 files. Mon Jan 9 23:03:11 2012 Child 1 finished creating 145 directories. Mon Jan 9 23:03:11 2012 Child 1 will create 4408 files. Mon Jan 9 23:03:11 2012 Child 3 finished creating 145 directories. Mon Jan 9 23:03:11 2012 Child 3 will create 4408 files. Mon Jan 9 23:03:12 2012 Child 10 finished creating 145 directories. Mon Jan 9 23:03:12 2012 Child 10 will create 4408 files. Mon Jan 9 23:03:13 2012 Child 13 finished creating 145 directories. Mon Jan 9 23:03:13 2012 Child 13 will create 4408 files. Mon Jan 9 23:03:13 2012 Child 15 finished creating 145 directories. Mon Jan 9 23:03:13 2012 Child 15 will create 4408 files. Mon Jan 9 23:03:13 2012 Child 6 finished creating 145 directories. Mon Jan 9 23:03:13 2012 Child 6 will create 4408 files. Mon Jan 9 23:03:15 2012 Child 9 finished creating 145 directories. Mon Jan 9 23:03:15 2012 Child 9 will create 4408 files. Mon Jan 9 23:03:16 2012 Child 7 finished creating 145 directories. Mon Jan 9 23:03:16 2012 Child 7 will create 4408 files. Mon Jan 9 23:03:17 2012 Child 12 finished creating 145 directories. Mon Jan 9 23:03:17 2012 Child 12 will create 4408 files. Mon Jan 9 23:03:21 2012 Child 2 finished creating 145 directories. Mon Jan 9 23:03:21 2012 Child 2 will create 4408 files. Mon Jan 9 23:03:21 2012 Child 11 finished creating 145 directories. Mon Jan 9 23:03:21 2012 Child 11 will create 4408 files. Mon Jan 9 23:03:21 2012 Child 14 finished creating 145 directories. Mon Jan 9 23:03:21 2012 Child 14 will create 4408 files. Mon Jan 9 23:03:21 2012 Child 8 finished creating 145 directories. Mon Jan 9 23:03:21 2012 Child 8 will create 4408 files. Mon Jan 9 23:03:21 2012 Child 0 finished creating 145 directories. Mon Jan 9 23:03:21 2012 Child 0 will create 4408 files. Mon Jan 9 23:08:24 2012 Child 1 verified 3178 files (338 creates). Mon Jan 9 23:08:24 2012 Child 5 verified 3213 files (373 creates). Mon Jan 9 23:08:24 2012 Child 4 verified 3213 files (373 creates). Mon Jan 9 23:08:24 2012 Child 14 verified 3018 files (178 creates). Mon Jan 9 23:08:24 2012 Child 6 verified 3075 files (235 creates). Mon Jan 9 23:08:24 2012 Child 8 verified 3018 files (178 creates). Mon Jan 9 23:08:24 2012 Child 2 verified 3018 files (178 creates). Mon Jan 9 23:08:24 2012 Child 11 verified 3009 files (169 creates). Mon Jan 9 23:08:24 2012 Child 10 verified 3107 files (267 creates). Mon Jan 9 23:08:24 2012 Child 12 verified 3036 files (196 creates). Mon Jan 9 23:08:24 2012 Child 3 verified 3146 files (306 creates). Mon Jan 9 23:08:24 2012 Child 13 verified 3047 files (207 creates). Mon Jan 9 23:08:24 2012 Child 9 verified 3036 files (196 creates). Mon Jan 9 23:08:24 2012 Child 7 verified 3047 files (207 creates). Mon Jan 9 23:08:25 2012 Child 0 verified 3016 files (176 creates). Mon Jan 9 23:08:56 2012 Child 15 verified 3051 files (211 creates). Mon Jan 9 23:14:24 2012 Child 14 verified 3209 files (369 creates). Mon Jan 9 23:14:24 2012 Child 2 verified 3209 files (369 creates). Mon Jan 9 23:14:24 2012 Child 10 verified 3456 files (616 creates). Mon Jan 9 23:14:24 2012 Child 9 verified 3227 files (387 creates). Mon Jan 9 23:14:24 2012 Child 5 verified 3525 files (685 creates). Mon Jan 9 23:14:24 2012 Child 13 verified 3247 files (407 creates). Mon Jan 9 23:14:24 2012 Child 3 verified 3456 files (616 creates). Mon Jan 9 23:14:24 2012 Child 11 verified 3209 files (369 creates). Mon Jan 9 23:14:24 2012 Child 12 verified 3238 files (398 creates). Mon Jan 9 23:14:24 2012 Child 7 verified 3227 files (387 creates). Mon Jan 9 23:14:24 2012 Child 15 verified 3275 files (435 creates). Mon Jan 9 23:14:24 2012 Child 0 verified 3192 files (352 creates). Mon Jan 9 23:14:24 2012 Child 1 verified 3505 files (665 creates). Mon Jan 9 23:14:24 2012 Child 8 verified 3209 files (369 creates). Mon Jan 9 23:14:24 2012 Child 6 verified 3370 files (530 creates). Mon Jan 9 23:14:24 2012 Child 4 verified 3505 files (665 creates). Mon Jan 9 23:19:23 2012 Child 15 verified 3568 files (728 creates). Mon Jan 9 23:19:51 2012 Child 12 verified 3539 files (699 creates). Mon Jan 9 23:19:51 2012 Child 1 verified 3864 files (1024 creates). Mon Jan 9 23:19:51 2012 Child 10 verified 3865 files (1025 creates). Mon Jan 9 23:19:56 2012 Child 6 verified 3721 files (881 creates). Mon Jan 9 23:20:07 2012 Child 3 verified 3805 files (965 creates). Mon Jan 9 23:20:17 2012 Child 11 verified 3508 files (668 creates). Mon Jan 9 23:20:20 2012 Child 4 verified 3816 files (976 creates). Mon Jan 9 23:20:20 2012 Child 7 verified 3544 files (704 creates). Mon Jan 9 23:20:20 2012 Child 13 verified 3544 files (704 creates). Mon Jan 9 23:20:20 2012 Child 9 verified 3544 files (704 creates). Mon Jan 9 23:20:20 2012 Child 5 verified 3879 files (1039 creates). Mon Jan 9 23:20:20 2012 Child 14 verified 3508 files (668 creates). Mon Jan 9 23:20:20 2012 Child 8 verified 3508 files (668 creates). Mon Jan 9 23:20:20 2012 Child 2 verified 3508 files (668 creates). Mon Jan 9 23:20:20 2012 Child 0 verified 3508 files (668 creates). Mon Jan 9 23:24:45 2012 Child 12 verified 3817 files (977 creates). Mon Jan 9 23:24:45 2012 Child 15 verified 3858 files (1018 creates). Mon Jan 9 23:24:52 2012 Child 1 verified 4161 files (1321 creates). Mon Jan 9 23:24:52 2012 Child 10 verified 4161 files (1321 creates). Mon Jan 9 23:24:52 2012 Child 6 verified 3952 files (1112 creates). Mon Jan 9 23:24:52 2012 Child 4 verified 4115 files (1275 creates). Mon Jan 9 23:24:58 2012 Child 3 verified 3968 files (1128 creates). Mon Jan 9 23:27:06 2012 Child 5 verified 4256 files (1416 creates). Mon Jan 9 23:27:06 2012 Child 13 verified 3879 files (1039 creates). Mon Jan 9 23:27:06 2012 Child 2 verified 3859 files (1019 creates). Mon Jan 9 23:27:06 2012 Child 14 verified 3740 files (900 creates). Mon Jan 9 23:27:06 2012 Child 11 verified 3859 files (1019 creates). Mon Jan 9 23:27:06 2012 Child 0 verified 3743 files (903 creates). Mon Jan 9 23:27:06 2012 Child 8 verified 3873 files (1033 creates). Mon Jan 9 23:27:06 2012 Child 9 verified 3879 files (1039 creates). Mon Jan 9 23:27:06 2012 Child 7 verified 3886 files (1046 creates). Mon Jan 9 23:27:53 2012 Child 5 verified 4408 files, created 1525 Mon Jan 9 23:27:53 2012 Child 5 will create 2 symlinks. Mon Jan 9 23:27:53 2012 Child 5 finished creating 2 symlinks. Mon Jan 9 23:28:01 2012 Child 10 verified 4408 files, created 1525 Mon Jan 9 23:28:01 2012 Child 10 will create 2 symlinks. Mon Jan 9 23:28:01 2012 Child 10 finished creating 2 symlinks. Mon Jan 9 23:28:01 2012 Child 1 verified 4408 files, created 1525 Mon Jan 9 23:28:01 2012 Child 1 will create 2 symlinks. Mon Jan 9 23:28:01 2012 Child 1 finished creating 2 symlinks. Mon Jan 9 23:28:05 2012 Child 4 verified 4408 files, created 1525 Mon Jan 9 23:28:05 2012 Child 4 will create 2 symlinks. Mon Jan 9 23:28:05 2012 Child 4 finished creating 2 symlinks. Mon Jan 9 23:28:25 2012 Child 6 verified 4408 files, created 1525 Mon Jan 9 23:28:25 2012 Child 6 will create 2 symlinks. Mon Jan 9 23:28:25 2012 Child 6 finished creating 2 symlinks. Mon Jan 9 23:28:26 2012 Child 3 verified 4408 files, created 1525 Mon Jan 9 23:28:26 2012 Child 3 will create 2 symlinks. Mon Jan 9 23:28:26 2012 Child 3 finished creating 2 symlinks. Mon Jan 9 23:28:31 2012 Child 15 verified 4408 files, created 1525 Mon Jan 9 23:28:31 2012 Child 15 will create 2 symlinks. Mon Jan 9 23:28:31 2012 Child 15 finished creating 2 symlinks. Mon Jan 9 23:28:34 2012 Child 9 verified 4408 files, created 1525 Mon Jan 9 23:28:34 2012 Child 9 will create 2 symlinks. Mon Jan 9 23:28:34 2012 Child 7 verified 4408 files, created 1525 Mon Jan 9 23:28:34 2012 Child 7 will create 2 symlinks. Mon Jan 9 23:28:34 2012 Child 13 verified 4408 files, created 1525 Mon Jan 9 23:28:34 2012 Child 13 will create 2 symlinks. Mon Jan 9 23:28:34 2012 Child 9 finished creating 2 symlinks. Mon Jan 9 23:28:34 2012 Child 7 finished creating 2 symlinks. Mon Jan 9 23:28:34 2012 Child 13 finished creating 2 symlinks. Mon Jan 9 23:28:34 2012 Child 11 verified 4408 files, created 1525 Mon Jan 9 23:28:34 2012 Child 11 will create 2 symlinks. Mon Jan 9 23:28:34 2012 Child 11 finished creating 2 symlinks. Mon Jan 9 23:28:34 2012 Child 8 verified 4408 files, created 1525 Mon Jan 9 23:28:34 2012 Child 8 will create 2 symlinks. Mon Jan 9 23:28:34 2012 Child 12 verified 4408 files, created 1525 Mon Jan 9 23:28:34 2012 Child 12 will create 2 symlinks. Mon Jan 9 23:28:34 2012 Child 8 finished creating 2 symlinks. Mon Jan 9 23:28:35 2012 Child 12 finished creating 2 symlinks. Mon Jan 9 23:28:35 2012 Child 2 verified 4408 files, created 1525 Mon Jan 9 23:28:35 2012 Child 2 will create 2 symlinks. Mon Jan 9 23:28:35 2012 Child 2 finished creating 2 symlinks. Mon Jan 9 23:28:38 2012 Child 0 verified 4408 files, created 1525 Mon Jan 9 23:28:38 2012 Child 0 will create 2 symlinks. Mon Jan 9 23:28:38 2012 Child 0 finished creating 2 symlinks. Mon Jan 9 23:28:38 2012 Child 14 verified 4408 files, created 1525 Mon Jan 9 23:28:38 2012 Child 14 will create 2 symlinks. Mon Jan 9 23:28:38 2012 Child 14 finished creating 2 symlinks. Mon Jan 9 23:28:39 2012 Completed. Mon Jan 9 23:28:39 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:28:39 2012 Completed. Mon Jan 9 23:28:39 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:28:39 2012 Received. Mon Jan 9 23:28:39 2012 Performing 300 seconds pretest warmup. Mon Jan 9 23:33:39 2012 Completed. Mon Jan 9 23:33:39 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:33:39 2012 Completed. Mon Jan 9 23:33:39 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:33:40 2012 Received. Mon Jan 9 23:33:40 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Mon Jan 9 23:38:50 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% 23368 23368 0 0.33 10.29 0.04 3.6% setattr 4.0% 4.0% 3596 3596 0 6.23 71.20 0.28 10.7% lookup 24.0% 24.1% 21693 21693 0 0.36 10.20 0.04 3.7% readlink 1.0% 1.0% 915 915 0 0.19 0.06 0.02 0.1% read 18.0% 17.9% 16138 19800 0 3.99 34.15 0.09 30.7% write 10.0% 10.1% 9135 11148 0 6.00 42.53 0.13 26.2% create 1.0% 1.1% 946 946 0 10.49 88.88 0.60 4.7% remove 1.0% 1.0% 864 864 0 11.81 103.15 0.68 4.9% readdir 1.0% 1.0% 857 857 0 0.40 0.06 0.02 0.2% fsstat 1.0% 1.0% 917 917 0 0.20 0.07 0.02 0.1% access 11.0% 11.0% 9879 9879 0 0.40 11.56 0.07 1.9% commit 0.0% 0.0% 0 4054 0 0.00 0.00 0.00 11.9% readdirplus 2.0% 2.0% 1787 1787 0 1.64 29.33 0.25 1.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 301.32 Ops/Sec AVG. RESPONSE TIME: 2.32 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 300 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 90095 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 99824 PHYSICAL NFS V3 THROUGHPUT: 333.85 Ops/Sec FILE SET SIZE CREATED: 36711216.0 KB FILE SET SIZE ACCESSED: 11019312.0 - 11237464.0 KB (100.00% to 101.98% of Base) ------------------------------------------------------------------------ Mon Jan 9 23:38:50 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 Mon Jan 9 23:38:50 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:38:50 2012 Completed. Mon Jan 9 23:38:50 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Mon Jan 9 23:38:51 2012 Received. Mon Jan 9 23:38:51 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Mon Jan 9 23:38:51 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, Mon Jan 9 23:38:53 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 Mon Jan 9 23:38:53 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 Mon Jan 9 23:39:07 2012 Completed. Mon Jan 9 23:39:07 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:39:07 2012 Completed. Mon Jan 9 23:39:07 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:39:09 2012 Received. Mon Jan 9 23:39:09 2012 Initializing test directories. Mon Jan 9 23:39:09 2012 Child 15 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 3 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 14 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 10 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 4 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 0 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 13 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 9 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 5 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 1 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 12 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 2 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 11 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 6 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 8 will create 190 directories. Mon Jan 9 23:39:09 2012 Child 7 will create 190 directories. Mon Jan 9 23:39:10 2012 Child 2 finished creating 190 directories. Mon Jan 9 23:39:10 2012 Child 2 will create 5793 files. Mon Jan 9 23:39:11 2012 Child 15 finished creating 190 directories. Mon Jan 9 23:39:11 2012 Child 15 will create 5793 files. Mon Jan 9 23:39:11 2012 Child 6 finished creating 190 directories. Mon Jan 9 23:39:11 2012 Child 6 will create 5793 files. Mon Jan 9 23:39:13 2012 Child 10 finished creating 190 directories. Mon Jan 9 23:39:13 2012 Child 10 will create 5793 files. Mon Jan 9 23:39:13 2012 Child 7 finished creating 190 directories. Mon Jan 9 23:39:13 2012 Child 7 will create 5793 files. Mon Jan 9 23:39:14 2012 Child 0 finished creating 190 directories. Mon Jan 9 23:39:14 2012 Child 0 will create 5793 files. Mon Jan 9 23:39:15 2012 Child 8 finished creating 190 directories. Mon Jan 9 23:39:15 2012 Child 8 will create 5793 files. Mon Jan 9 23:39:15 2012 Child 13 finished creating 190 directories. Mon Jan 9 23:39:15 2012 Child 13 will create 5793 files. Mon Jan 9 23:39:21 2012 Child 14 finished creating 190 directories. Mon Jan 9 23:39:21 2012 Child 14 will create 5793 files. Mon Jan 9 23:39:21 2012 Child 12 finished creating 190 directories. Mon Jan 9 23:39:21 2012 Child 12 will create 5793 files. Mon Jan 9 23:39:21 2012 Child 4 finished creating 190 directories. Mon Jan 9 23:39:21 2012 Child 4 will create 5793 files. Mon Jan 9 23:39:22 2012 Child 5 finished creating 190 directories. Mon Jan 9 23:39:22 2012 Child 5 will create 5793 files. Mon Jan 9 23:39:23 2012 Child 9 finished creating 190 directories. Mon Jan 9 23:39:23 2012 Child 9 will create 5793 files. Mon Jan 9 23:39:23 2012 Child 11 finished creating 190 directories. Mon Jan 9 23:39:23 2012 Child 11 will create 5793 files. Mon Jan 9 23:39:24 2012 Child 3 finished creating 190 directories. Mon Jan 9 23:39:24 2012 Child 3 will create 5793 files. Mon Jan 9 23:39:24 2012 Child 1 finished creating 190 directories. Mon Jan 9 23:39:24 2012 Child 1 will create 5793 files. Mon Jan 9 23:44:18 2012 Child 0 verified 4780 files (459 creates). Mon Jan 9 23:44:18 2012 Child 13 verified 4703 files (382 creates). Mon Jan 9 23:44:18 2012 Child 8 verified 4669 files (348 creates). Mon Jan 9 23:44:18 2012 Child 10 verified 4777 files (456 creates). Mon Jan 9 23:44:18 2012 Child 2 verified 4901 files (580 creates). Mon Jan 9 23:44:18 2012 Child 15 verified 4864 files (543 creates). Mon Jan 9 23:44:18 2012 Child 6 verified 4886 files (565 creates). Mon Jan 9 23:44:35 2012 Child 5 verified 4514 files (193 creates). Mon Jan 9 23:44:35 2012 Child 7 verified 4777 files (456 creates). Mon Jan 9 23:44:35 2012 Child 4 verified 4571 files (250 creates). Mon Jan 9 23:44:35 2012 Child 11 verified 4514 files (193 creates). Mon Jan 9 23:44:35 2012 Child 3 verified 4514 files (193 creates). Mon Jan 9 23:44:35 2012 Child 12 verified 4571 files (250 creates). Mon Jan 9 23:44:35 2012 Child 14 verified 4571 files (250 creates). Mon Jan 9 23:44:35 2012 Child 1 verified 4479 files (158 creates). Mon Jan 9 23:44:35 2012 Child 9 verified 4514 files (193 creates). Mon Jan 9 23:50:32 2012 Child 4 verified 4935 files (614 creates). Mon Jan 9 23:50:32 2012 Child 13 verified 5089 files (768 creates). Mon Jan 9 23:50:32 2012 Child 15 verified 5257 files (936 creates). Mon Jan 9 23:50:32 2012 Child 12 verified 4947 files (626 creates). Mon Jan 9 23:50:32 2012 Child 7 verified 5163 files (842 creates). Mon Jan 9 23:50:32 2012 Child 0 verified 5163 files (842 creates). Mon Jan 9 23:50:32 2012 Child 14 verified 4936 files (615 creates). Mon Jan 9 23:50:33 2012 Child 10 verified 5163 files (842 creates). Mon Jan 9 23:50:36 2012 Child 9 verified 4886 files (565 creates). Mon Jan 9 23:50:36 2012 Child 1 verified 4879 files (558 creates). Mon Jan 9 23:50:36 2012 Child 11 verified 4886 files (565 creates). Mon Jan 9 23:50:36 2012 Child 3 verified 4896 files (575 creates). Mon Jan 9 23:50:36 2012 Child 8 verified 4991 files (670 creates). Mon Jan 9 23:50:36 2012 Child 6 verified 5275 files (954 creates). Mon Jan 9 23:50:36 2012 Child 2 verified 5275 files (954 creates). Mon Jan 9 23:50:36 2012 Child 5 verified 4896 files (575 creates). Mon Jan 9 23:55:47 2012 Child 0 verified 5482 files (1161 creates). Mon Jan 9 23:55:47 2012 Child 13 verified 5436 files (1115 creates). Mon Jan 9 23:55:47 2012 Child 10 verified 5481 files (1160 creates). Mon Jan 9 23:55:47 2012 Child 7 verified 5488 files (1167 creates). Mon Jan 9 23:55:47 2012 Child 15 verified 5625 files (1304 creates). Mon Jan 9 23:55:48 2012 Child 9 verified 5204 files (883 creates). Mon Jan 9 23:55:48 2012 Child 11 verified 5204 files (883 creates). Mon Jan 9 23:55:58 2012 Child 12 verified 5275 files (954 creates). Mon Jan 9 23:56:01 2012 Child 14 verified 5275 files (954 creates). Mon Jan 9 23:56:01 2012 Child 3 verified 5269 files (948 creates). Mon Jan 9 23:56:01 2012 Child 6 verified 5642 files (1321 creates). Mon Jan 9 23:56:01 2012 Child 1 verified 5191 files (870 creates). Mon Jan 9 23:56:01 2012 Child 4 verified 5269 files (948 creates). Mon Jan 9 23:56:01 2012 Child 2 verified 5642 files (1321 creates). Mon Jan 9 23:56:01 2012 Child 5 verified 5269 files (948 creates). Mon Jan 9 23:56:01 2012 Child 8 verified 5362 files (1041 creates). Mon Jan 9 23:56:08 2012 Child 6 verified 5793 files, created 1416 Mon Jan 9 23:56:08 2012 Child 6 will create 2 symlinks. Mon Jan 9 23:56:08 2012 Child 2 verified 5793 files, created 1416 Mon Jan 9 23:56:08 2012 Child 2 will create 2 symlinks. Mon Jan 9 23:56:09 2012 Child 2 finished creating 2 symlinks. Mon Jan 9 23:56:09 2012 Child 6 finished creating 2 symlinks. Mon Jan 9 23:56:10 2012 Child 15 verified 5793 files, created 1416 Mon Jan 9 23:56:10 2012 Child 15 will create 2 symlinks. Mon Jan 9 23:56:10 2012 Child 15 finished creating 2 symlinks. Mon Jan 9 23:57:27 2012 Child 7 verified 5793 files, created 1416 Mon Jan 9 23:57:27 2012 Child 7 will create 2 symlinks. Mon Jan 9 23:57:27 2012 Child 7 finished creating 2 symlinks. Mon Jan 9 23:57:28 2012 Child 0 verified 5793 files, created 1416 Mon Jan 9 23:57:28 2012 Child 0 will create 2 symlinks. Mon Jan 9 23:57:28 2012 Child 0 finished creating 2 symlinks. Mon Jan 9 23:57:31 2012 Child 10 verified 5793 files, created 1416 Mon Jan 9 23:57:31 2012 Child 10 will create 2 symlinks. Mon Jan 9 23:57:31 2012 Child 10 finished creating 2 symlinks. Mon Jan 9 23:57:33 2012 Child 13 verified 5793 files, created 1416 Mon Jan 9 23:57:33 2012 Child 13 will create 2 symlinks. Mon Jan 9 23:57:33 2012 Child 13 finished creating 2 symlinks. Mon Jan 9 23:57:37 2012 Child 8 verified 5793 files, created 1416 Mon Jan 9 23:57:37 2012 Child 8 will create 2 symlinks. Mon Jan 9 23:57:37 2012 Child 8 finished creating 2 symlinks. Mon Jan 9 23:57:44 2012 Child 4 verified 5793 files, created 1416 Mon Jan 9 23:57:44 2012 Child 4 will create 2 symlinks. Mon Jan 9 23:57:45 2012 Child 4 finished creating 2 symlinks. Mon Jan 9 23:57:45 2012 Child 14 verified 5793 files, created 1416 Mon Jan 9 23:57:45 2012 Child 14 will create 2 symlinks. Mon Jan 9 23:57:45 2012 Child 5 verified 5793 files, created 1416 Mon Jan 9 23:57:45 2012 Child 5 will create 2 symlinks. Mon Jan 9 23:57:45 2012 Child 14 finished creating 2 symlinks. Mon Jan 9 23:57:45 2012 Child 5 finished creating 2 symlinks. Mon Jan 9 23:57:45 2012 Child 3 verified 5793 files, created 1416 Mon Jan 9 23:57:45 2012 Child 3 will create 2 symlinks. Mon Jan 9 23:57:45 2012 Child 3 finished creating 2 symlinks. Mon Jan 9 23:57:45 2012 Child 12 verified 5793 files, created 1416 Mon Jan 9 23:57:45 2012 Child 12 will create 2 symlinks. Mon Jan 9 23:57:45 2012 Child 12 finished creating 2 symlinks. Mon Jan 9 23:57:46 2012 Child 9 verified 5793 files, created 1416 Mon Jan 9 23:57:46 2012 Child 9 will create 2 symlinks. Mon Jan 9 23:57:46 2012 Child 9 finished creating 2 symlinks. Mon Jan 9 23:57:46 2012 Child 11 verified 5793 files, created 1416 Mon Jan 9 23:57:46 2012 Child 11 will create 2 symlinks. Mon Jan 9 23:57:46 2012 Child 11 finished creating 2 symlinks. Mon Jan 9 23:57:47 2012 Child 1 verified 5793 files, created 1416 Mon Jan 9 23:57:47 2012 Child 1 will create 2 symlinks. Mon Jan 9 23:57:47 2012 Child 1 finished creating 2 symlinks. Mon Jan 9 23:57:47 2012 Completed. Mon Jan 9 23:57:47 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:57:47 2012 Completed. Mon Jan 9 23:57:47 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Mon Jan 9 23:57:48 2012 Received. Mon Jan 9 23:57:48 2012 Performing 300 seconds pretest warmup. Tue Jan 10 00:02:48 2012 Completed. Tue Jan 10 00:02:48 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:02:48 2012 Completed. Tue Jan 10 00:02:48 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:02:49 2012 Received. Tue Jan 10 00:02:49 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 00:07:59 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% 31129 31129 0 0.22 1.71 0.01 2.1% setattr 4.0% 4.0% 4801 4801 0 3.07 25.26 0.14 4.5% lookup 24.0% 23.9% 28667 28667 0 0.33 13.94 0.04 2.9% readlink 1.0% 1.0% 1219 1219 0 0.20 0.08 0.02 0.1% read 18.0% 18.1% 21783 26795 0 5.62 44.99 0.09 37.5% write 10.0% 10.1% 12135 14915 0 9.81 62.75 0.14 36.4% create 1.0% 1.0% 1169 1169 0 5.99 11.87 0.20 2.1% remove 1.0% 1.0% 1211 1211 0 4.44 6.91 0.15 1.6% readdir 1.0% 1.0% 1163 1163 0 0.41 0.07 0.01 0.1% fsstat 1.0% 1.0% 1253 1253 0 0.20 0.07 0.01 0.1% access 11.0% 11.0% 13208 13208 0 0.34 14.57 0.07 1.4% commit 0.0% 0.0% 0 5308 0 0.00 0.00 0.00 10.1% readdirplus 2.0% 2.0% 2396 2396 0 1.41 34.29 0.23 1.0% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 400.44 Ops/Sec AVG. RESPONSE TIME: 2.71 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 400 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 120134 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 133234 PHYSICAL NFS V3 THROUGHPUT: 444.11 Ops/Sec FILE SET SIZE CREATED: 48248784.0 KB FILE SET SIZE ACCESSED: 14477184.0 - 14764099.0 KB (100.00% to 101.98% of Base) ------------------------------------------------------------------------ Tue Jan 10 00:07:59 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 Tue Jan 10 00:07:59 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:07:59 2012 Completed. Tue Jan 10 00:07:59 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 00:08:00 2012 Received. Tue Jan 10 00:08:00 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 00:08:00 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, Tue Jan 10 00:08:02 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 Tue Jan 10 00:08:02 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 Tue Jan 10 00:08:16 2012 Completed. Tue Jan 10 00:08:16 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:08:16 2012 Completed. Tue Jan 10 00:08:16 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:08:18 2012 Received. Tue Jan 10 00:08:18 2012 Initializing test directories. Tue Jan 10 00:08:18 2012 Child 5 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 14 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 7 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 0 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 10 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 13 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 6 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 2 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 11 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 4 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 8 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 3 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 12 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 1 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 9 will create 239 directories. Tue Jan 10 00:08:18 2012 Child 15 will create 239 directories. Tue Jan 10 00:08:19 2012 Child 2 finished creating 239 directories. Tue Jan 10 00:08:19 2012 Child 2 will create 7304 files. Tue Jan 10 00:08:20 2012 Child 3 finished creating 239 directories. Tue Jan 10 00:08:20 2012 Child 3 will create 7304 files. Tue Jan 10 00:08:20 2012 Child 6 finished creating 239 directories. Tue Jan 10 00:08:20 2012 Child 6 will create 7304 files. Tue Jan 10 00:08:24 2012 Child 10 finished creating 239 directories. Tue Jan 10 00:08:24 2012 Child 10 will create 7304 files. Tue Jan 10 00:08:24 2012 Child 12 finished creating 239 directories. Tue Jan 10 00:08:24 2012 Child 12 will create 7304 files. Tue Jan 10 00:08:27 2012 Child 15 finished creating 239 directories. Tue Jan 10 00:08:27 2012 Child 15 will create 7304 files. Tue Jan 10 00:08:27 2012 Child 9 finished creating 239 directories. Tue Jan 10 00:08:27 2012 Child 9 will create 7304 files. Tue Jan 10 00:08:30 2012 Child 11 finished creating 239 directories. Tue Jan 10 00:08:30 2012 Child 11 will create 7304 files. Tue Jan 10 00:08:30 2012 Child 4 finished creating 239 directories. Tue Jan 10 00:08:30 2012 Child 4 will create 7304 files. Tue Jan 10 00:08:30 2012 Child 0 finished creating 239 directories. Tue Jan 10 00:08:30 2012 Child 0 will create 7304 files. Tue Jan 10 00:08:31 2012 Child 1 finished creating 239 directories. Tue Jan 10 00:08:31 2012 Child 1 will create 7304 files. Tue Jan 10 00:08:34 2012 Child 13 finished creating 239 directories. Tue Jan 10 00:08:34 2012 Child 13 will create 7304 files. Tue Jan 10 00:08:34 2012 Child 7 finished creating 239 directories. Tue Jan 10 00:08:34 2012 Child 7 will create 7304 files. Tue Jan 10 00:08:35 2012 Child 5 finished creating 239 directories. Tue Jan 10 00:08:35 2012 Child 5 will create 7304 files. Tue Jan 10 00:08:37 2012 Child 14 finished creating 239 directories. Tue Jan 10 00:08:37 2012 Child 14 will create 7304 files. Tue Jan 10 00:08:39 2012 Child 8 finished creating 239 directories. Tue Jan 10 00:08:39 2012 Child 8 will create 7304 files. Tue Jan 10 00:14:52 2012 Child 12 verified 6250 files (571 creates). Tue Jan 10 00:14:52 2012 Child 5 verified 6019 files (340 creates). Tue Jan 10 00:14:52 2012 Child 4 verified 6128 files (449 creates). Tue Jan 10 00:14:52 2012 Child 10 verified 6320 files (641 creates). Tue Jan 10 00:14:52 2012 Child 3 verified 6493 files (814 creates). Tue Jan 10 00:14:52 2012 Child 14 verified 6020 files (341 creates). Tue Jan 10 00:14:52 2012 Child 2 verified 6510 files (831 creates). Tue Jan 10 00:14:52 2012 Child 9 verified 6175 files (496 creates). Tue Jan 10 00:14:52 2012 Child 15 verified 6092 files (413 creates). Tue Jan 10 00:14:52 2012 Child 6 verified 6500 files (821 creates). Tue Jan 10 00:14:52 2012 Child 13 verified 5981 files (302 creates). Tue Jan 10 00:14:52 2012 Child 1 verified 6092 files (413 creates). Tue Jan 10 00:14:52 2012 Child 8 verified 5981 files (302 creates). Tue Jan 10 00:14:52 2012 Child 7 verified 5981 files (302 creates). Tue Jan 10 00:14:52 2012 Child 11 verified 6092 files (413 creates). Tue Jan 10 00:14:52 2012 Child 0 verified 6092 files (413 creates). Tue Jan 10 00:19:52 2012 Child 13 verified 6422 files (743 creates). Tue Jan 10 00:19:52 2012 Child 6 verified 6742 files (1063 creates). Tue Jan 10 00:19:52 2012 Child 7 verified 6402 files (723 creates). Tue Jan 10 00:19:57 2012 Child 3 verified 6747 files (1068 creates). Tue Jan 10 00:20:12 2012 Child 10 verified 6547 files (868 creates). Tue Jan 10 00:20:12 2012 Child 2 verified 6769 files (1090 creates). Tue Jan 10 00:20:16 2012 Child 1 verified 6500 files (821 creates). Tue Jan 10 00:20:16 2012 Child 8 verified 6353 files (674 creates). Tue Jan 10 00:20:16 2012 Child 15 verified 6456 files (777 creates). Tue Jan 10 00:20:16 2012 Child 0 verified 6456 files (777 creates). Tue Jan 10 00:20:16 2012 Child 9 verified 6510 files (831 creates). Tue Jan 10 00:20:16 2012 Child 5 verified 6353 files (674 creates). Tue Jan 10 00:20:16 2012 Child 12 verified 6543 files (864 creates). Tue Jan 10 00:20:16 2012 Child 11 verified 6500 files (821 creates). Tue Jan 10 00:20:16 2012 Child 4 verified 6510 files (831 creates). Tue Jan 10 00:20:16 2012 Child 14 verified 6456 files (777 creates). Tue Jan 10 00:25:16 2012 Child 10 verified 6853 files (1174 creates). Tue Jan 10 00:25:16 2012 Child 6 verified 7036 files (1357 creates). Tue Jan 10 00:25:16 2012 Child 13 verified 6547 files (868 creates). Tue Jan 10 00:25:16 2012 Child 7 verified 6547 files (868 creates). Tue Jan 10 00:25:16 2012 Child 2 verified 7046 files (1367 creates). Tue Jan 10 00:25:16 2012 Child 4 verified 6696 files (1017 creates). Tue Jan 10 00:25:16 2012 Child 15 verified 6591 files (912 creates). Tue Jan 10 00:25:16 2012 Child 0 verified 6591 files (912 creates). Tue Jan 10 00:25:16 2012 Child 3 verified 7036 files (1357 creates). Tue Jan 10 00:25:16 2012 Child 8 verified 6548 files (869 creates). Tue Jan 10 00:25:16 2012 Child 9 verified 6688 files (1009 creates). Tue Jan 10 00:25:16 2012 Child 1 verified 6696 files (1017 creates). Tue Jan 10 00:25:16 2012 Child 5 verified 6548 files (869 creates). Tue Jan 10 00:25:17 2012 Child 11 verified 6737 files (1058 creates). Tue Jan 10 00:25:17 2012 Child 12 verified 6789 files (1110 creates). Tue Jan 10 00:25:17 2012 Child 14 verified 6600 files (921 creates). Tue Jan 10 00:25:38 2012 Child 2 verified 7304 files, created 1554 Tue Jan 10 00:25:38 2012 Child 2 will create 2 symlinks. Tue Jan 10 00:25:38 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 00:25:39 2012 Child 6 verified 7304 files, created 1554 Tue Jan 10 00:25:39 2012 Child 6 will create 2 symlinks. Tue Jan 10 00:25:39 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 00:25:39 2012 Child 3 verified 7304 files, created 1554 Tue Jan 10 00:25:39 2012 Child 3 will create 2 symlinks. Tue Jan 10 00:25:39 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 00:25:57 2012 Child 10 verified 7304 files, created 1554 Tue Jan 10 00:25:57 2012 Child 10 will create 2 symlinks. Tue Jan 10 00:25:57 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 00:26:09 2012 Child 12 verified 7304 files, created 1554 Tue Jan 10 00:26:09 2012 Child 12 will create 2 symlinks. Tue Jan 10 00:26:09 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 00:26:15 2012 Child 11 verified 7304 files, created 1554 Tue Jan 10 00:26:15 2012 Child 11 will create 2 symlinks. Tue Jan 10 00:26:15 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 00:26:17 2012 Child 4 verified 7304 files, created 1554 Tue Jan 10 00:26:17 2012 Child 4 will create 2 symlinks. Tue Jan 10 00:26:17 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 00:29:43 2012 Child 1 verified 7304 files, created 1554 Tue Jan 10 00:29:43 2012 Child 1 will create 2 symlinks. Tue Jan 10 00:29:43 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 00:29:44 2012 Child 9 verified 7304 files, created 1554 Tue Jan 10 00:29:44 2012 Child 9 will create 2 symlinks. Tue Jan 10 00:29:49 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 00:29:49 2012 Child 0 verified 7304 files, created 1554 Tue Jan 10 00:29:49 2012 Child 0 will create 2 symlinks. Tue Jan 10 00:29:49 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 00:29:49 2012 Child 15 verified 7304 files, created 1554 Tue Jan 10 00:29:49 2012 Child 15 will create 2 symlinks. Tue Jan 10 00:29:49 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 00:29:57 2012 Child 8 verified 7304 files, created 1554 Tue Jan 10 00:29:57 2012 Child 8 will create 2 symlinks. Tue Jan 10 00:29:57 2012 Child 5 verified 7304 files, created 1554 Tue Jan 10 00:29:57 2012 Child 5 will create 2 symlinks. Tue Jan 10 00:29:57 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 00:29:57 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 00:29:57 2012 Child 14 verified 7304 files, created 1554 Tue Jan 10 00:29:57 2012 Child 14 will create 2 symlinks. Tue Jan 10 00:29:57 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 00:29:57 2012 Child 7 verified 7304 files, created 1554 Tue Jan 10 00:29:57 2012 Child 7 will create 2 symlinks. Tue Jan 10 00:29:57 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 00:29:57 2012 Child 13 verified 7304 files, created 1554 Tue Jan 10 00:29:57 2012 Child 13 will create 2 symlinks. Tue Jan 10 00:29:57 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 00:29:57 2012 Completed. Tue Jan 10 00:29:57 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:29:57 2012 Completed. Tue Jan 10 00:29:57 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:29:57 2012 Received. Tue Jan 10 00:29:57 2012 Performing 300 seconds pretest warmup. Tue Jan 10 00:34:57 2012 Completed. Tue Jan 10 00:34:57 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:34:57 2012 Completed. Tue Jan 10 00:34:57 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:34:58 2012 Received. Tue Jan 10 00:34:58 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 00:40:08 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% 39126 39126 0 0.25 9.08 0.03 1.8% setattr 4.0% 3.9% 5926 5926 0 6.13 67.54 0.21 6.8% lookup 24.0% 23.8% 35811 35811 0 0.32 14.59 0.04 2.1% readlink 1.0% 1.0% 1477 1477 0 0.21 0.34 0.03 0.1% read 18.0% 18.1% 27243 33590 0 7.97 64.62 0.10 40.6% write 10.0% 10.0% 15084 18303 0 11.14 49.84 0.11 31.4% create 1.0% 1.0% 1526 1526 0 9.01 55.22 0.37 2.6% remove 1.0% 1.0% 1483 1483 0 6.33 9.15 0.15 1.8% readdir 1.0% 1.0% 1516 1516 0 0.42 0.11 0.02 0.1% fsstat 1.0% 1.0% 1494 1494 0 0.20 0.07 0.01 0.1% access 11.0% 11.0% 16499 16499 0 0.33 16.40 0.06 1.0% commit 0.0% 0.0% 0 6582 0 0.00 0.00 0.00 11.3% readdirplus 2.0% 2.0% 3050 3050 0 0.72 0.11 0.01 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 500.78 Ops/Sec AVG. RESPONSE TIME: 3.56 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 500 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 150235 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 166383 PHYSICAL NFS V3 THROUGHPUT: 554.61 Ops/Sec FILE SET SIZE CREATED: 60831360.0 KB FILE SET SIZE ACCESSED: 18257904.0 - 18611764.0 KB (100.00% to 101.94% of Base) ------------------------------------------------------------------------ Tue Jan 10 00:40:08 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 Tue Jan 10 00:40:08 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:40:08 2012 Completed. Tue Jan 10 00:40:08 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 00:40:09 2012 Received. Tue Jan 10 00:40:09 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 00:40:09 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, Tue Jan 10 00:40:11 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 Tue Jan 10 00:40:11 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 Tue Jan 10 00:40:25 2012 Completed. Tue Jan 10 00:40:25 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:40:25 2012 Completed. Tue Jan 10 00:40:25 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 00:40:27 2012 Received. Tue Jan 10 00:40:27 2012 Initializing test directories. Tue Jan 10 00:40:27 2012 Child 8 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 12 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 4 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 2 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 5 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 9 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 11 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 7 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 1 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 10 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 0 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 3 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 13 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 15 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 6 will create 289 directories. Tue Jan 10 00:40:27 2012 Child 14 will create 289 directories. Tue Jan 10 00:40:31 2012 Child 2 finished creating 289 directories. Tue Jan 10 00:40:31 2012 Child 2 will create 8814 files. Tue Jan 10 00:40:33 2012 Child 13 finished creating 289 directories. Tue Jan 10 00:40:33 2012 Child 13 will create 8814 files. Tue Jan 10 00:40:34 2012 Child 3 finished creating 289 directories. Tue Jan 10 00:40:34 2012 Child 3 will create 8814 files. Tue Jan 10 00:40:35 2012 Child 9 finished creating 289 directories. Tue Jan 10 00:40:35 2012 Child 9 will create 8814 files. Tue Jan 10 00:40:39 2012 Child 5 finished creating 289 directories. Tue Jan 10 00:40:39 2012 Child 5 will create 8814 files. Tue Jan 10 00:40:41 2012 Child 10 finished creating 289 directories. Tue Jan 10 00:40:41 2012 Child 10 will create 8814 files. Tue Jan 10 00:40:41 2012 Child 14 finished creating 289 directories. Tue Jan 10 00:40:41 2012 Child 14 will create 8814 files. Tue Jan 10 00:40:44 2012 Child 15 finished creating 289 directories. Tue Jan 10 00:40:44 2012 Child 15 will create 8814 files. Tue Jan 10 00:40:44 2012 Child 8 finished creating 289 directories. Tue Jan 10 00:40:44 2012 Child 8 will create 8814 files. Tue Jan 10 00:40:47 2012 Child 12 finished creating 289 directories. Tue Jan 10 00:40:47 2012 Child 12 will create 8814 files. Tue Jan 10 00:40:47 2012 Child 1 finished creating 289 directories. Tue Jan 10 00:40:47 2012 Child 1 will create 8814 files. Tue Jan 10 00:40:47 2012 Child 4 finished creating 289 directories. Tue Jan 10 00:40:47 2012 Child 4 will create 8814 files. Tue Jan 10 00:40:47 2012 Child 7 finished creating 289 directories. Tue Jan 10 00:40:47 2012 Child 7 will create 8814 files. Tue Jan 10 00:40:51 2012 Child 6 finished creating 289 directories. Tue Jan 10 00:40:51 2012 Child 6 will create 8814 files. Tue Jan 10 00:40:51 2012 Child 0 finished creating 289 directories. Tue Jan 10 00:40:51 2012 Child 0 will create 8814 files. Tue Jan 10 00:40:51 2012 Child 11 finished creating 289 directories. Tue Jan 10 00:40:51 2012 Child 11 will create 8814 files. Tue Jan 10 00:46:14 2012 Child 2 verified 8128 files (968 creates). Tue Jan 10 00:46:14 2012 Child 11 verified 7290 files (130 creates). Tue Jan 10 00:46:14 2012 Child 10 verified 7455 files (295 creates). Tue Jan 10 00:46:14 2012 Child 14 verified 7454 files (294 creates). Tue Jan 10 00:46:14 2012 Child 3 verified 7696 files (536 creates). Tue Jan 10 00:46:14 2012 Child 8 verified 7455 files (295 creates). Tue Jan 10 00:46:14 2012 Child 9 verified 7697 files (537 creates). Tue Jan 10 00:46:14 2012 Child 0 verified 7287 files (127 creates). Tue Jan 10 00:46:14 2012 Child 13 verified 7877 files (717 creates). Tue Jan 10 00:46:14 2012 Child 15 verified 7379 files (219 creates). Tue Jan 10 00:46:14 2012 Child 6 verified 7287 files (127 creates). Tue Jan 10 00:46:50 2012 Child 4 verified 7370 files (210 creates). Tue Jan 10 00:46:53 2012 Child 12 verified 7370 files (210 creates). Tue Jan 10 00:46:53 2012 Child 7 verified 7370 files (210 creates). Tue Jan 10 00:46:53 2012 Child 1 verified 7370 files (210 creates). Tue Jan 10 00:46:53 2012 Child 5 verified 7609 files (449 creates). Tue Jan 10 00:51:13 2012 Child 2 verified 8462 files (1302 creates). Tue Jan 10 00:51:13 2012 Child 11 verified 7622 files (462 creates). Tue Jan 10 00:51:13 2012 Child 3 verified 8133 files (973 creates). Tue Jan 10 00:51:13 2012 Child 10 verified 7887 files (727 creates). Tue Jan 10 00:51:13 2012 Child 14 verified 7837 files (677 creates). Tue Jan 10 00:51:14 2012 Child 8 verified 7862 files (702 creates). Tue Jan 10 00:51:14 2012 Child 9 verified 8139 files (979 creates). Tue Jan 10 00:51:15 2012 Child 15 verified 7838 files (678 creates). Tue Jan 10 00:51:15 2012 Child 0 verified 7598 files (438 creates). Tue Jan 10 00:51:16 2012 Child 13 verified 8314 files (1154 creates). Tue Jan 10 00:51:16 2012 Child 6 verified 7616 files (456 creates). Tue Jan 10 00:51:44 2012 Child 2 verified 8814 files, created 1568 Tue Jan 10 00:51:44 2012 Child 2 will create 2 symlinks. Tue Jan 10 00:51:44 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 00:51:50 2012 Child 4 verified 7938 files (778 creates). Tue Jan 10 00:51:51 2012 Child 12 verified 7971 files (811 creates). Tue Jan 10 00:51:51 2012 Child 1 verified 7965 files (805 creates). Tue Jan 10 00:51:55 2012 Child 7 verified 7877 files (717 creates). Tue Jan 10 00:51:55 2012 Child 5 verified 8286 files (1126 creates). Tue Jan 10 00:52:20 2012 Child 13 verified 8814 files, created 1568 Tue Jan 10 00:52:20 2012 Child 13 will create 2 symlinks. Tue Jan 10 00:52:20 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 00:56:34 2012 Child 9 verified 8596 files (1436 creates). Tue Jan 10 00:56:35 2012 Child 8 verified 8357 files (1197 creates). Tue Jan 10 00:56:35 2012 Child 0 verified 8168 files (1008 creates). Tue Jan 10 00:56:38 2012 Child 3 verified 8613 files (1453 creates). Tue Jan 10 00:56:38 2012 Child 14 verified 8332 files (1172 creates). Tue Jan 10 00:56:38 2012 Child 11 verified 8233 files (1073 creates). Tue Jan 10 00:56:38 2012 Child 15 verified 8332 files (1172 creates). Tue Jan 10 00:56:38 2012 Child 10 verified 8385 files (1225 creates). Tue Jan 10 00:56:38 2012 Child 6 verified 8205 files (1045 creates). Tue Jan 10 00:56:45 2012 Child 3 verified 8814 files, created 1568 Tue Jan 10 00:56:45 2012 Child 3 will create 2 symlinks. Tue Jan 10 00:56:45 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 00:58:01 2012 Child 5 verified 8526 files (1366 creates). Tue Jan 10 00:58:01 2012 Child 1 verified 8233 files (1073 creates). Tue Jan 10 00:58:01 2012 Child 12 verified 8233 files (1073 creates). Tue Jan 10 00:58:01 2012 Child 7 verified 8205 files (1045 creates). Tue Jan 10 00:58:06 2012 Child 9 verified 8814 files, created 1568 Tue Jan 10 00:58:06 2012 Child 9 will create 2 symlinks. Tue Jan 10 00:58:06 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 00:58:41 2012 Child 4 verified 8233 files (1073 creates). Tue Jan 10 00:59:49 2012 Child 5 verified 8814 files, created 1568 Tue Jan 10 00:59:49 2012 Child 5 will create 2 symlinks. Tue Jan 10 00:59:49 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 01:01:10 2012 Child 8 verified 8814 files, created 1568 Tue Jan 10 01:01:10 2012 Child 8 will create 2 symlinks. Tue Jan 10 01:01:12 2012 Child 10 verified 8814 files, created 1568 Tue Jan 10 01:01:12 2012 Child 10 will create 2 symlinks. Tue Jan 10 01:01:12 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 01:01:13 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 01:01:18 2012 Child 15 verified 8814 files, created 1568 Tue Jan 10 01:01:18 2012 Child 15 will create 2 symlinks. Tue Jan 10 01:01:18 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 01:01:18 2012 Child 14 verified 8814 files, created 1568 Tue Jan 10 01:01:18 2012 Child 14 will create 2 symlinks. Tue Jan 10 01:01:19 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 01:01:25 2012 Child 11 verified 8814 files, created 1568 Tue Jan 10 01:01:25 2012 Child 11 will create 2 symlinks. Tue Jan 10 01:01:25 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 01:03:06 2012 Child 0 verified 8613 files (1453 creates). Tue Jan 10 01:03:06 2012 Child 7 verified 8633 files (1473 creates). Tue Jan 10 01:03:06 2012 Child 6 verified 8633 files (1473 creates). Tue Jan 10 01:03:06 2012 Child 1 verified 8633 files (1473 creates). Tue Jan 10 01:03:06 2012 Child 12 verified 8633 files (1473 creates). Tue Jan 10 01:03:08 2012 Child 6 verified 8814 files, created 1568 Tue Jan 10 01:03:08 2012 Child 6 will create 2 symlinks. Tue Jan 10 01:03:08 2012 Child 4 verified 8814 files, created 1568 Tue Jan 10 01:03:08 2012 Child 4 will create 2 symlinks. Tue Jan 10 01:03:08 2012 Child 1 verified 8814 files, created 1568 Tue Jan 10 01:03:08 2012 Child 1 will create 2 symlinks. Tue Jan 10 01:03:08 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 01:03:08 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 01:03:08 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 01:03:08 2012 Child 0 verified 8814 files, created 1568 Tue Jan 10 01:03:08 2012 Child 0 will create 2 symlinks. Tue Jan 10 01:03:08 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 01:03:08 2012 Child 7 verified 8814 files, created 1568 Tue Jan 10 01:03:08 2012 Child 7 will create 2 symlinks. Tue Jan 10 01:03:08 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 01:03:08 2012 Child 12 verified 8814 files, created 1568 Tue Jan 10 01:03:08 2012 Child 12 will create 2 symlinks. Tue Jan 10 01:03:08 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 01:03:09 2012 Completed. Tue Jan 10 01:03:09 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:03:09 2012 Completed. Tue Jan 10 01:03:09 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:03:09 2012 Received. Tue Jan 10 01:03:09 2012 Performing 300 seconds pretest warmup. Tue Jan 10 01:08:09 2012 Completed. Tue Jan 10 01:08:09 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:08:09 2012 Completed. Tue Jan 10 01:08:09 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:08:10 2012 Received. Tue Jan 10 01:08:10 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 01:13:20 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% 47199 47199 0 0.21 0.21 0.00 1.6% setattr 4.0% 4.0% 7192 7192 0 5.26 11.45 0.08 6.3% lookup 24.0% 23.9% 43027 43027 0 0.21 0.17 0.00 1.5% readlink 1.0% 1.0% 1827 1827 0 0.22 0.50 0.03 0.1% read 18.0% 18.0% 32410 39915 0 7.29 43.29 0.07 39.4% write 10.0% 9.9% 17923 21838 0 11.48 23.14 0.07 34.3% create 1.0% 1.0% 1872 1872 0 8.90 14.06 0.17 2.8% remove 1.0% 1.0% 1824 1824 0 7.81 13.56 0.17 2.4% readdir 1.0% 1.0% 1814 1814 0 0.43 0.12 0.02 0.1% fsstat 1.0% 1.0% 1776 1776 0 0.21 0.31 0.03 0.1% access 11.0% 10.9% 19727 19727 0 0.21 0.14 0.01 0.7% commit 0.0% 0.0% 0 7901 0 0.00 0.00 0.00 10.2% readdirplus 2.0% 2.1% 3697 3697 0 0.74 0.32 0.02 0.5% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 600.96 Ops/Sec AVG. RESPONSE TIME: 3.32 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 600 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 180288 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 199609 PHYSICAL NFS V3 THROUGHPUT: 665.36 Ops/Sec FILE SET SIZE CREATED: 73413936.0 KB FILE SET SIZE ACCESSED: 22030128.0 - 22448544.0 KB (100.00% to 101.90% of Base) ------------------------------------------------------------------------ Tue Jan 10 01:13:20 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 Tue Jan 10 01:13:20 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:13:20 2012 Completed. Tue Jan 10 01:13:20 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 01:13:21 2012 Received. Tue Jan 10 01:13:21 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 01:13:21 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, Tue Jan 10 01:13: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 Tue Jan 10 01:13: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 Tue Jan 10 01:13:37 2012 Completed. Tue Jan 10 01:13:37 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:13:37 2012 Completed. Tue Jan 10 01:13:37 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:13:39 2012 Received. Tue Jan 10 01:13:39 2012 Initializing test directories. Tue Jan 10 01:13:39 2012 Child 15 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 12 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 1 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 9 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 10 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 3 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 14 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 7 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 4 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 8 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 0 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 5 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 6 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 13 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 11 will create 338 directories. Tue Jan 10 01:13:39 2012 Child 2 will create 338 directories. Tue Jan 10 01:13:43 2012 Child 8 finished creating 338 directories. Tue Jan 10 01:13:43 2012 Child 8 will create 10325 files. Tue Jan 10 01:13:43 2012 Child 0 finished creating 338 directories. Tue Jan 10 01:13:43 2012 Child 0 will create 10325 files. Tue Jan 10 01:13:44 2012 Child 2 finished creating 338 directories. Tue Jan 10 01:13:44 2012 Child 2 will create 10325 files. Tue Jan 10 01:13:45 2012 Child 5 finished creating 338 directories. Tue Jan 10 01:13:45 2012 Child 5 will create 10325 files. Tue Jan 10 01:13:47 2012 Child 6 finished creating 338 directories. Tue Jan 10 01:13:47 2012 Child 6 will create 10325 files. Tue Jan 10 01:13:48 2012 Child 4 finished creating 338 directories. Tue Jan 10 01:13:48 2012 Child 4 will create 10325 files. Tue Jan 10 01:13:51 2012 Child 7 finished creating 338 directories. Tue Jan 10 01:13:51 2012 Child 7 will create 10325 files. Tue Jan 10 01:13:51 2012 Child 11 finished creating 338 directories. Tue Jan 10 01:13:51 2012 Child 11 will create 10325 files. Tue Jan 10 01:13:51 2012 Child 10 finished creating 338 directories. Tue Jan 10 01:13:51 2012 Child 10 will create 10325 files. Tue Jan 10 01:13:52 2012 Child 13 finished creating 338 directories. Tue Jan 10 01:13:52 2012 Child 13 will create 10325 files. Tue Jan 10 01:13:54 2012 Child 14 finished creating 338 directories. Tue Jan 10 01:13:54 2012 Child 14 will create 10325 files. Tue Jan 10 01:13:55 2012 Child 9 finished creating 338 directories. Tue Jan 10 01:13:55 2012 Child 9 will create 10325 files. Tue Jan 10 01:14:02 2012 Child 15 finished creating 338 directories. Tue Jan 10 01:14:02 2012 Child 15 will create 10325 files. Tue Jan 10 01:14:02 2012 Child 12 finished creating 338 directories. Tue Jan 10 01:14:02 2012 Child 12 will create 10325 files. Tue Jan 10 01:14:02 2012 Child 1 finished creating 338 directories. Tue Jan 10 01:14:02 2012 Child 1 will create 10325 files. Tue Jan 10 01:14:07 2012 Child 3 finished creating 338 directories. Tue Jan 10 01:14:07 2012 Child 3 will create 10325 files. Tue Jan 10 01:18:46 2012 Child 0 verified 9463 files (822 creates). Tue Jan 10 01:18:46 2012 Child 2 verified 9438 files (797 creates). Tue Jan 10 01:18:46 2012 Child 8 verified 9438 files (797 creates). Tue Jan 10 01:18:47 2012 Child 5 verified 9323 files (682 creates). Tue Jan 10 01:18:48 2012 Child 6 verified 9250 files (609 creates). Tue Jan 10 01:18:50 2012 Child 4 verified 9129 files (488 creates). Tue Jan 10 01:20:13 2012 Child 3 verified 8813 files (172 creates). Tue Jan 10 01:20:13 2012 Child 12 verified 8870 files (229 creates). Tue Jan 10 01:20:26 2012 Child 11 verified 9037 files (396 creates). Tue Jan 10 01:20:26 2012 Child 7 verified 9084 files (443 creates). Tue Jan 10 01:20:26 2012 Child 13 verified 9037 files (396 creates). Tue Jan 10 01:20:26 2012 Child 9 verified 9037 files (396 creates). Tue Jan 10 01:20:26 2012 Child 14 verified 9037 files (396 creates). Tue Jan 10 01:20:26 2012 Child 10 verified 9037 files (396 creates). Tue Jan 10 01:21:07 2012 Child 1 verified 8872 files (231 creates). Tue Jan 10 01:21:07 2012 Child 15 verified 8872 files (231 creates). Tue Jan 10 01:24:33 2012 Child 0 verified 9859 files (1218 creates). Tue Jan 10 01:24:33 2012 Child 5 verified 9708 files (1067 creates). Tue Jan 10 01:24:33 2012 Child 6 verified 9580 files (939 creates). Tue Jan 10 01:24:33 2012 Child 4 verified 9495 files (854 creates). Tue Jan 10 01:24:36 2012 Child 2 verified 9761 files (1120 creates). Tue Jan 10 01:24:36 2012 Child 8 verified 9761 files (1120 creates). Tue Jan 10 01:25:28 2012 Child 3 verified 9152 files (511 creates). Tue Jan 10 01:25:31 2012 Child 7 verified 9488 files (847 creates). Tue Jan 10 01:25:31 2012 Child 12 verified 9152 files (511 creates). Tue Jan 10 01:25:31 2012 Child 11 verified 9369 files (728 creates). Tue Jan 10 01:25:31 2012 Child 13 verified 9369 files (728 creates). Tue Jan 10 01:25:31 2012 Child 9 verified 9369 files (728 creates). Tue Jan 10 01:25:31 2012 Child 10 verified 9369 files (728 creates). Tue Jan 10 01:26:31 2012 Child 1 verified 9252 files (611 creates). Tue Jan 10 01:26:31 2012 Child 15 verified 9252 files (611 creates). Tue Jan 10 01:26:31 2012 Child 14 verified 9369 files (728 creates). Tue Jan 10 01:27:25 2012 Child 0 verified 10325 files, created 1583 Tue Jan 10 01:27:25 2012 Child 0 will create 2 symlinks. Tue Jan 10 01:27:25 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 01:27:47 2012 Child 2 verified 10325 files, created 1583 Tue Jan 10 01:27:47 2012 Child 2 will create 2 symlinks. Tue Jan 10 01:27:47 2012 Child 8 verified 10325 files, created 1583 Tue Jan 10 01:27:47 2012 Child 8 will create 2 symlinks. Tue Jan 10 01:27:49 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 01:27:49 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 01:28:05 2012 Child 5 verified 10325 files, created 1583 Tue Jan 10 01:28:05 2012 Child 5 will create 2 symlinks. Tue Jan 10 01:28:05 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 01:31:21 2012 Child 6 verified 10081 files (1440 creates). Tue Jan 10 01:31:22 2012 Child 4 verified 9958 files (1317 creates). Tue Jan 10 01:31:23 2012 Child 3 verified 9653 files (1012 creates). Tue Jan 10 01:31:23 2012 Child 7 verified 9918 files (1277 creates). Tue Jan 10 01:31:23 2012 Child 9 verified 9912 files (1271 creates). Tue Jan 10 01:31:23 2012 Child 13 verified 9818 files (1177 creates). Tue Jan 10 01:31:23 2012 Child 11 verified 9818 files (1177 creates). Tue Jan 10 01:31:23 2012 Child 10 verified 9818 files (1177 creates). Tue Jan 10 01:31:23 2012 Child 12 verified 9653 files (1012 creates). Tue Jan 10 01:31:29 2012 Child 6 verified 10325 files, created 1583 Tue Jan 10 01:31:29 2012 Child 6 will create 2 symlinks. Tue Jan 10 01:31:29 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 01:31:31 2012 Child 15 verified 9751 files (1110 creates). Tue Jan 10 01:31:34 2012 Child 4 verified 10325 files, created 1583 Tue Jan 10 01:31:34 2012 Child 4 will create 2 symlinks. Tue Jan 10 01:31:34 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 01:32:24 2012 Child 14 verified 9911 files (1270 creates). Tue Jan 10 01:32:26 2012 Child 1 verified 9761 files (1120 creates). Tue Jan 10 01:32:28 2012 Child 7 verified 10325 files, created 1583 Tue Jan 10 01:32:28 2012 Child 7 will create 2 symlinks. Tue Jan 10 01:32:28 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 01:33:02 2012 Child 9 verified 10325 files, created 1583 Tue Jan 10 01:33:02 2012 Child 9 will create 2 symlinks. Tue Jan 10 01:33:02 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 01:33:08 2012 Child 10 verified 10325 files, created 1583 Tue Jan 10 01:33:08 2012 Child 10 will create 2 symlinks. Tue Jan 10 01:33:08 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 01:33:11 2012 Child 13 verified 10325 files, created 1583 Tue Jan 10 01:33:11 2012 Child 13 will create 2 symlinks. Tue Jan 10 01:33:11 2012 Child 11 verified 10325 files, created 1583 Tue Jan 10 01:33:11 2012 Child 11 will create 2 symlinks. Tue Jan 10 01:33:11 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 01:33:11 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 01:33:12 2012 Child 14 verified 10325 files, created 1583 Tue Jan 10 01:33:12 2012 Child 14 will create 2 symlinks. Tue Jan 10 01:33:12 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 01:33:19 2012 Child 3 verified 10325 files, created 1583 Tue Jan 10 01:33:19 2012 Child 3 will create 2 symlinks. Tue Jan 10 01:33:19 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 01:33:19 2012 Child 12 verified 10325 files, created 1583 Tue Jan 10 01:33:19 2012 Child 12 will create 2 symlinks. Tue Jan 10 01:33:19 2012 Child 1 verified 10325 files, created 1583 Tue Jan 10 01:33:19 2012 Child 1 will create 2 symlinks. Tue Jan 10 01:33:19 2012 Child 15 verified 10325 files, created 1583 Tue Jan 10 01:33:19 2012 Child 15 will create 2 symlinks. Tue Jan 10 01:33:19 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 01:33:19 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 01:33:19 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 01:33:20 2012 Completed. Tue Jan 10 01:33:20 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:33:20 2012 Completed. Tue Jan 10 01:33:20 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:33:21 2012 Received. Tue Jan 10 01:33:21 2012 Performing 300 seconds pretest warmup. Tue Jan 10 01:38:21 2012 Completed. Tue Jan 10 01:38:21 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:38:21 2012 Completed. Tue Jan 10 01:38:21 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:38:22 2012 Received. Tue Jan 10 01:38:22 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 01:43:32 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% 54671 54671 0 0.33 1.76 0.01 1.4% setattr 4.0% 4.0% 8483 8483 0 12.51 26.70 0.11 8.3% lookup 24.0% 24.0% 50703 50703 0 0.43 9.65 0.03 1.7% readlink 1.0% 1.0% 2109 2109 0 0.30 1.23 0.05 0.0% read 18.0% 18.0% 38048 46989 0 14.94 117.66 0.11 44.5% write 10.0% 10.0% 20979 25401 0 15.81 48.18 0.09 26.0% create 1.0% 1.0% 2181 2181 0 18.80 31.32 0.23 3.2% remove 1.0% 1.0% 2106 2106 0 16.62 39.68 0.27 2.7% readdir 1.0% 1.0% 2126 2126 0 0.59 1.87 0.06 0.1% fsstat 1.0% 1.0% 2128 2128 0 0.33 1.84 0.06 0.1% access 11.0% 10.9% 23064 23064 0 0.40 8.38 0.04 0.7% commit 0.0% 0.0% 0 8913 0 0.00 0.00 0.00 10.8% readdirplus 2.0% 2.0% 4231 4231 0 1.15 18.24 0.13 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 705.11 Ops/Sec AVG. RESPONSE TIME: 6.05 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 700 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 210829 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 233105 PHYSICAL NFS V3 THROUGHPUT: 779.61 Ops/Sec FILE SET SIZE CREATED: 85996512.0 KB FILE SET SIZE ACCESSED: 25802352.0 - 26241984.0 KB (100.00% to 101.70% of Base) ------------------------------------------------------------------------ Tue Jan 10 01:43:32 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 Tue Jan 10 01:43:32 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:43:32 2012 Completed. Tue Jan 10 01:43:32 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 01:43:33 2012 Received. Tue Jan 10 01:43:33 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 01:43:33 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, Tue Jan 10 01:43:35 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 Tue Jan 10 01:43:35 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 Tue Jan 10 01:43:49 2012 Completed. Tue Jan 10 01:43:49 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:43:49 2012 Completed. Tue Jan 10 01:43:49 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 01:43:51 2012 Received. Tue Jan 10 01:43:51 2012 Initializing test directories. Tue Jan 10 01:43:51 2012 Child 10 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 5 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 4 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 2 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 9 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 8 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 1 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 7 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 6 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 12 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 0 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 3 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 11 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 13 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 15 will create 383 directories. Tue Jan 10 01:43:51 2012 Child 14 will create 383 directories. Tue Jan 10 01:43:52 2012 Child 14 finished creating 383 directories. Tue Jan 10 01:43:52 2012 Child 14 will create 11710 files. Tue Jan 10 01:43:52 2012 Child 7 finished creating 383 directories. Tue Jan 10 01:43:52 2012 Child 7 will create 11710 files. Tue Jan 10 01:43:53 2012 Child 12 finished creating 383 directories. Tue Jan 10 01:43:53 2012 Child 12 will create 11710 files. Tue Jan 10 01:44:00 2012 Child 15 finished creating 383 directories. Tue Jan 10 01:44:00 2012 Child 15 will create 11710 files. Tue Jan 10 01:44:02 2012 Child 13 finished creating 383 directories. Tue Jan 10 01:44:02 2012 Child 13 will create 11710 files. Tue Jan 10 01:44:03 2012 Child 3 finished creating 383 directories. Tue Jan 10 01:44:03 2012 Child 3 will create 11710 files. Tue Jan 10 01:44:05 2012 Child 9 finished creating 383 directories. Tue Jan 10 01:44:05 2012 Child 9 will create 11710 files. Tue Jan 10 01:44:05 2012 Child 1 finished creating 383 directories. Tue Jan 10 01:44:05 2012 Child 1 will create 11710 files. Tue Jan 10 01:44:05 2012 Child 6 finished creating 383 directories. Tue Jan 10 01:44:05 2012 Child 6 will create 11710 files. Tue Jan 10 01:44:11 2012 Child 10 finished creating 383 directories. Tue Jan 10 01:44:11 2012 Child 10 will create 11710 files. Tue Jan 10 01:44:14 2012 Child 0 finished creating 383 directories. Tue Jan 10 01:44:14 2012 Child 0 will create 11710 files. Tue Jan 10 01:44:14 2012 Child 8 finished creating 383 directories. Tue Jan 10 01:44:14 2012 Child 8 will create 11710 files. Tue Jan 10 01:44:16 2012 Child 2 finished creating 383 directories. Tue Jan 10 01:44:16 2012 Child 2 will create 11710 files. Tue Jan 10 01:44:19 2012 Child 5 finished creating 383 directories. Tue Jan 10 01:44:19 2012 Child 5 will create 11710 files. Tue Jan 10 01:44:21 2012 Child 11 finished creating 383 directories. Tue Jan 10 01:44:21 2012 Child 11 will create 11710 files. Tue Jan 10 01:44:24 2012 Child 4 finished creating 383 directories. Tue Jan 10 01:44:24 2012 Child 4 will create 11710 files. Tue Jan 10 01:50:08 2012 Child 12 verified 10972 files (850 creates). Tue Jan 10 01:50:08 2012 Child 7 verified 11117 files (995 creates). Tue Jan 10 01:50:08 2012 Child 11 verified 10161 files (39 creates). Tue Jan 10 01:50:08 2012 Child 3 verified 10461 files (339 creates). Tue Jan 10 01:50:08 2012 Child 6 verified 10461 files (339 creates). Tue Jan 10 01:50:08 2012 Child 9 verified 10461 files (339 creates). Tue Jan 10 01:50:08 2012 Child 1 verified 10507 files (385 creates). Tue Jan 10 01:50:17 2012 Child 13 verified 10533 files (411 creates). Tue Jan 10 01:50:17 2012 Child 15 verified 10533 files (411 creates). Tue Jan 10 01:50:17 2012 Child 8 verified 10353 files (231 creates). Tue Jan 10 01:50:17 2012 Child 4 verified 10157 files (35 creates). Tue Jan 10 01:50:17 2012 Child 14 verified 11148 files (1026 creates). Tue Jan 10 01:50:17 2012 Child 0 verified 10318 files (196 creates). Tue Jan 10 01:50:17 2012 Child 10 verified 10353 files (231 creates). Tue Jan 10 01:50:17 2012 Child 5 verified 10245 files (123 creates). Tue Jan 10 01:50:17 2012 Child 2 verified 10275 files (153 creates). Tue Jan 10 01:53:48 2012 Child 14 verified 11710 files, created 1474 Tue Jan 10 01:53:48 2012 Child 14 will create 2 symlinks. Tue Jan 10 01:53:48 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 01:53:49 2012 Child 7 verified 11710 files, created 1474 Tue Jan 10 01:53:49 2012 Child 7 will create 2 symlinks. Tue Jan 10 01:54:00 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 01:54:10 2012 Child 12 verified 11710 files, created 1474 Tue Jan 10 01:54:10 2012 Child 12 will create 2 symlinks. Tue Jan 10 01:54:10 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 01:55:02 2012 Child 11 verified 10972 files (850 creates). Tue Jan 10 01:55:02 2012 Child 6 verified 11311 files (1189 creates). Tue Jan 10 01:55:02 2012 Child 9 verified 11216 files (1094 creates). Tue Jan 10 01:55:02 2012 Child 3 verified 11311 files (1189 creates). Tue Jan 10 01:55:02 2012 Child 1 verified 11360 files (1238 creates). Tue Jan 10 01:55:20 2012 Child 10 verified 11300 files (1178 creates). Tue Jan 10 01:55:20 2012 Child 15 verified 11437 files (1315 creates). Tue Jan 10 01:55:20 2012 Child 5 verified 11071 files (949 creates). Tue Jan 10 01:55:20 2012 Child 4 verified 11067 files (945 creates). Tue Jan 10 01:55:20 2012 Child 0 verified 11236 files (1114 creates). Tue Jan 10 01:55:20 2012 Child 8 verified 11307 files (1185 creates). Tue Jan 10 01:55:20 2012 Child 2 verified 11194 files (1072 creates). Tue Jan 10 01:55:24 2012 Child 13 verified 11710 files, created 1474 Tue Jan 10 01:55:24 2012 Child 13 will create 2 symlinks. Tue Jan 10 01:55:25 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 01:55:30 2012 Child 15 verified 11710 files, created 1474 Tue Jan 10 01:55:30 2012 Child 15 will create 2 symlinks. Tue Jan 10 01:55:31 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 01:55:31 2012 Child 1 verified 11710 files, created 1474 Tue Jan 10 01:55:31 2012 Child 1 will create 2 symlinks. Tue Jan 10 01:55:31 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 02:01:22 2012 Child 5 verified 11148 files (1026 creates). Tue Jan 10 02:01:22 2012 Child 11 verified 11148 files (1026 creates). Tue Jan 10 02:01:22 2012 Child 4 verified 11148 files (1026 creates). Tue Jan 10 02:01:22 2012 Child 0 verified 11321 files (1199 creates). Tue Jan 10 02:01:22 2012 Child 9 verified 11391 files (1269 creates). Tue Jan 10 02:01:22 2012 Child 8 verified 11391 files (1269 creates). Tue Jan 10 02:01:22 2012 Child 3 verified 11437 files (1315 creates). Tue Jan 10 02:01:22 2012 Child 6 verified 11437 files (1315 creates). Tue Jan 10 02:01:23 2012 Child 10 verified 11391 files (1269 creates). Tue Jan 10 02:01:23 2012 Child 2 verified 11289 files (1167 creates). Tue Jan 10 02:01:32 2012 Child 6 verified 11710 files, created 1474 Tue Jan 10 02:01:32 2012 Child 6 will create 2 symlinks. Tue Jan 10 02:01:32 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 02:01:32 2012 Child 3 verified 11710 files, created 1474 Tue Jan 10 02:01:32 2012 Child 3 will create 2 symlinks. Tue Jan 10 02:01:32 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 02:01:39 2012 Child 10 verified 11710 files, created 1474 Tue Jan 10 02:01:39 2012 Child 10 will create 2 symlinks. Tue Jan 10 02:01:39 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 02:01:39 2012 Child 9 verified 11710 files, created 1474 Tue Jan 10 02:01:39 2012 Child 9 will create 2 symlinks. Tue Jan 10 02:01:39 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 02:01:39 2012 Child 8 verified 11710 files, created 1474 Tue Jan 10 02:01:39 2012 Child 8 will create 2 symlinks. Tue Jan 10 02:01:40 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 02:01:40 2012 Child 0 verified 11710 files, created 1474 Tue Jan 10 02:01:40 2012 Child 0 will create 2 symlinks. Tue Jan 10 02:01:44 2012 Child 2 verified 11710 files, created 1474 Tue Jan 10 02:01:44 2012 Child 2 will create 2 symlinks. Tue Jan 10 02:01:44 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 02:01:47 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 02:01:54 2012 Child 11 verified 11710 files, created 1474 Tue Jan 10 02:01:54 2012 Child 11 will create 2 symlinks. Tue Jan 10 02:01:54 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 02:01:54 2012 Child 4 verified 11710 files, created 1474 Tue Jan 10 02:01:54 2012 Child 4 will create 2 symlinks. Tue Jan 10 02:01:54 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 02:01:54 2012 Child 5 verified 11710 files, created 1474 Tue Jan 10 02:01:54 2012 Child 5 will create 2 symlinks. Tue Jan 10 02:01:54 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 02:01:55 2012 Completed. Tue Jan 10 02:01:55 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:01:55 2012 Completed. Tue Jan 10 02:01:55 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:01:56 2012 Received. Tue Jan 10 02:01:56 2012 Performing 300 seconds pretest warmup. Tue Jan 10 02:06:56 2012 Completed. Tue Jan 10 02:06:56 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:06:56 2012 Completed. Tue Jan 10 02:06:56 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:06:57 2012 Received. Tue Jan 10 02:06:57 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 02:12:07 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% 63316 63316 0 2.35 111.06 0.08 3.2% setattr 4.0% 4.0% 9800 9800 0 38.18 68.09 0.16 8.1% lookup 24.0% 24.1% 58908 58908 0 2.82 127.78 0.09 3.6% readlink 1.0% 1.0% 2458 2458 0 2.40 45.93 0.27 0.1% read 18.0% 18.0% 43963 54027 0 44.64 452.06 0.20 42.4% write 10.0% 10.1% 24593 29599 0 46.48 501.15 0.28 24.7% create 1.0% 1.0% 2481 2481 0 66.15 95.17 0.38 3.5% remove 1.0% 1.0% 2460 2460 0 41.58 54.55 0.29 2.2% readdir 1.0% 1.0% 2393 2393 0 1.81 7.36 0.11 0.1% fsstat 1.0% 1.0% 2460 2460 0 8.10 333.30 0.72 0.4% access 11.0% 11.0% 26833 26833 0 2.45 103.77 0.12 1.4% commit 0.0% 0.0% 0 10201 0 0.00 0.00 0.00 9.9% readdirplus 2.0% 2.0% 4947 4947 0 2.99 35.29 0.17 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 818.10 Ops/Sec AVG. RESPONSE TIME: 18.92 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 800 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 244612 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 269883 PHYSICAL NFS V3 THROUGHPUT: 902.61 Ops/Sec FILE SET SIZE CREATED: 97534080.0 KB FILE SET SIZE ACCESSED: 29268720.0 - 29722352.0 KB (100.00% to 101.55% of Base) ------------------------------------------------------------------------ Tue Jan 10 02:12:07 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 Tue Jan 10 02:12:07 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:12:07 2012 Completed. Tue Jan 10 02:12:07 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 02:12:08 2012 Received. Tue Jan 10 02:12:08 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 02:12:08 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, Tue Jan 10 02:12:10 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 Tue Jan 10 02:12:10 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 Tue Jan 10 02:12:24 2012 Completed. Tue Jan 10 02:12:24 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:12:24 2012 Completed. Tue Jan 10 02:12:24 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:12:26 2012 Received. Tue Jan 10 02:12:26 2012 Initializing test directories. Tue Jan 10 02:12:26 2012 Child 15 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 7 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 14 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 1 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 9 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 6 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 2 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 3 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 8 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 5 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 4 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 13 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 10 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 12 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 0 will create 433 directories. Tue Jan 10 02:12:26 2012 Child 11 will create 433 directories. Tue Jan 10 02:12:28 2012 Child 10 finished creating 433 directories. Tue Jan 10 02:12:28 2012 Child 10 will create 13221 files. Tue Jan 10 02:12:29 2012 Child 5 finished creating 433 directories. Tue Jan 10 02:12:29 2012 Child 5 will create 13221 files. Tue Jan 10 02:12:33 2012 Child 7 finished creating 433 directories. Tue Jan 10 02:12:33 2012 Child 7 will create 13221 files. Tue Jan 10 02:12:33 2012 Child 3 finished creating 433 directories. Tue Jan 10 02:12:33 2012 Child 3 will create 13221 files. Tue Jan 10 02:12:36 2012 Child 8 finished creating 433 directories. Tue Jan 10 02:12:36 2012 Child 8 will create 13221 files. Tue Jan 10 02:12:42 2012 Child 13 finished creating 433 directories. Tue Jan 10 02:12:42 2012 Child 13 will create 13221 files. Tue Jan 10 02:12:43 2012 Child 12 finished creating 433 directories. Tue Jan 10 02:12:43 2012 Child 12 will create 13221 files. Tue Jan 10 02:12:44 2012 Child 1 finished creating 433 directories. Tue Jan 10 02:12:44 2012 Child 1 will create 13221 files. Tue Jan 10 02:12:47 2012 Child 11 finished creating 433 directories. Tue Jan 10 02:12:47 2012 Child 11 will create 13221 files. Tue Jan 10 02:12:48 2012 Child 15 finished creating 433 directories. Tue Jan 10 02:12:48 2012 Child 15 will create 13221 files. Tue Jan 10 02:12:55 2012 Child 0 finished creating 433 directories. Tue Jan 10 02:12:55 2012 Child 0 will create 13221 files. Tue Jan 10 02:12:59 2012 Child 4 finished creating 433 directories. Tue Jan 10 02:12:59 2012 Child 4 will create 13221 files. Tue Jan 10 02:13:02 2012 Child 2 finished creating 433 directories. Tue Jan 10 02:13:02 2012 Child 2 will create 13221 files. Tue Jan 10 02:13:02 2012 Child 6 finished creating 433 directories. Tue Jan 10 02:13:02 2012 Child 6 will create 13221 files. Tue Jan 10 02:13:02 2012 Child 9 finished creating 433 directories. Tue Jan 10 02:13:02 2012 Child 9 will create 13221 files. Tue Jan 10 02:13:04 2012 Child 14 finished creating 433 directories. Tue Jan 10 02:13:04 2012 Child 14 will create 13221 files. Tue Jan 10 02:15:29 2012 Child 10 verified 13221 files, created 1612 Tue Jan 10 02:15:29 2012 Child 10 will create 2 symlinks. Tue Jan 10 02:15:33 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 02:15:34 2012 Child 5 verified 13221 files, created 1612 Tue Jan 10 02:15:34 2012 Child 5 will create 2 symlinks. Tue Jan 10 02:15:34 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 02:16:48 2012 Child 7 verified 13221 files, created 1612 Tue Jan 10 02:16:48 2012 Child 7 will create 2 symlinks. Tue Jan 10 02:16:48 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 02:26:30 2012 Child 11 verified 12623 files (1143 creates). Tue Jan 10 02:26:30 2012 Child 15 verified 12525 files (1045 creates). Tue Jan 10 02:26:30 2012 Child 0 verified 12316 files (836 creates). Tue Jan 10 02:26:30 2012 Child 12 verified 12676 files (1196 creates). Tue Jan 10 02:26:32 2012 Child 13 verified 12652 files (1172 creates). Tue Jan 10 02:26:32 2012 Child 8 verified 12916 files (1436 creates). Tue Jan 10 02:26:32 2012 Child 3 verified 12951 files (1471 creates). Tue Jan 10 02:26:32 2012 Child 14 verified 12144 files (664 creates). Tue Jan 10 02:26:32 2012 Child 4 verified 12260 files (780 creates). Tue Jan 10 02:26:32 2012 Child 6 verified 12204 files (724 creates). Tue Jan 10 02:26:32 2012 Child 9 verified 12241 files (761 creates). Tue Jan 10 02:26:32 2012 Child 1 verified 12723 files (1243 creates). Tue Jan 10 02:26:32 2012 Child 2 verified 12204 files (724 creates). Tue Jan 10 02:26:54 2012 Child 8 verified 13221 files, created 1612 Tue Jan 10 02:26:54 2012 Child 8 will create 2 symlinks. Tue Jan 10 02:26:54 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 02:27:12 2012 Child 1 verified 13221 files, created 1612 Tue Jan 10 02:27:12 2012 Child 1 will create 2 symlinks. Tue Jan 10 02:27:12 2012 Child 3 verified 13221 files, created 1612 Tue Jan 10 02:27:12 2012 Child 3 will create 2 symlinks. Tue Jan 10 02:27:16 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 02:27:16 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 02:27:16 2012 Child 12 verified 13221 files, created 1612 Tue Jan 10 02:27:16 2012 Child 12 will create 2 symlinks. Tue Jan 10 02:27:17 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 02:27:19 2012 Child 13 verified 13221 files, created 1612 Tue Jan 10 02:27:19 2012 Child 13 will create 2 symlinks. Tue Jan 10 02:27:19 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 02:27:22 2012 Child 11 verified 13221 files, created 1612 Tue Jan 10 02:27:22 2012 Child 11 will create 2 symlinks. Tue Jan 10 02:27:22 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 02:27:30 2012 Child 15 verified 13221 files, created 1612 Tue Jan 10 02:27:30 2012 Child 15 will create 2 symlinks. Tue Jan 10 02:27:30 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 02:27:43 2012 Child 4 verified 13221 files, created 1612 Tue Jan 10 02:27:43 2012 Child 4 will create 2 symlinks. Tue Jan 10 02:27:48 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 02:27:48 2012 Child 0 verified 13221 files, created 1612 Tue Jan 10 02:27:48 2012 Child 0 will create 2 symlinks. Tue Jan 10 02:27:48 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 02:27:50 2012 Child 6 verified 13221 files, created 1612 Tue Jan 10 02:27:50 2012 Child 6 will create 2 symlinks. Tue Jan 10 02:27:50 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 02:27:51 2012 Child 2 verified 13221 files, created 1612 Tue Jan 10 02:27:51 2012 Child 2 will create 2 symlinks. Tue Jan 10 02:27:51 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 02:27:51 2012 Child 9 verified 13221 files, created 1612 Tue Jan 10 02:27:51 2012 Child 9 will create 2 symlinks. Tue Jan 10 02:27:51 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 02:27:54 2012 Child 14 verified 13221 files, created 1612 Tue Jan 10 02:27:54 2012 Child 14 will create 2 symlinks. Tue Jan 10 02:27:54 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 02:27:55 2012 Completed. Tue Jan 10 02:27:55 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:27:55 2012 Completed. Tue Jan 10 02:27:55 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:27:56 2012 Received. Tue Jan 10 02:27:56 2012 Performing 300 seconds pretest warmup. Tue Jan 10 02:32:56 2012 Completed. Tue Jan 10 02:32:56 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:32:56 2012 Completed. Tue Jan 10 02:32:56 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:32:57 2012 Received. Tue Jan 10 02:32:57 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 02:38:07 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% 50550 50550 0 3.60 101.28 0.09 3.2% setattr 4.0% 4.0% 7758 7758 0 60.82 84.47 0.20 8.2% lookup 24.0% 24.1% 47021 47021 0 3.12 10.69 0.03 2.6% readlink 1.0% 1.0% 1933 1933 0 3.08 9.66 0.14 0.1% read 18.0% 18.0% 35024 42882 0 68.30 453.15 0.22 41.7% write 10.0% 10.1% 19619 23740 0 79.32 649.28 0.36 27.1% create 1.0% 1.0% 1952 1952 0 107.44 104.61 0.45 3.7% remove 1.0% 1.0% 1946 1946 0 68.04 78.70 0.39 2.3% readdir 1.0% 1.0% 1976 1976 0 3.90 15.45 0.17 0.1% fsstat 1.0% 1.0% 1925 1925 0 3.62 17.48 0.19 0.1% access 11.0% 11.0% 21386 21386 0 3.95 128.74 0.15 1.5% commit 0.0% 0.0% 0 7400 0 0.00 0.00 0.00 9.3% readdirplus 2.0% 2.0% 3910 3910 0 3.67 9.37 0.10 0.2% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 652.17 Ops/Sec AVG. RESPONSE TIME: 29.44 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 900 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 195000 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 214379 PHYSICAL NFS V3 THROUGHPUT: 716.98 Ops/Sec FILE SET SIZE CREATED: 110116656.0 KB FILE SET SIZE ACCESSED: 33040944.0 - 33350276.0 KB (100.00% to 100.94% of Base) ------------------------------------------------------------------------ Tue Jan 10 02:38:07 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 Tue Jan 10 02:38:07 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:38:07 2012 Completed. Tue Jan 10 02:38:07 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 02:38:08 2012 Received. Tue Jan 10 02:38:08 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 02:38:08 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, Tue Jan 10 02:38:10 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 Tue Jan 10 02:38:10 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 Tue Jan 10 02:38:24 2012 Completed. Tue Jan 10 02:38:24 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:38:24 2012 Completed. Tue Jan 10 02:38:24 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 02:38:26 2012 Received. Tue Jan 10 02:38:26 2012 Initializing test directories. Tue Jan 10 02:38:26 2012 Child 11 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 15 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 13 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 1 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 0 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 14 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 2 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 5 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 10 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 12 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 3 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 9 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 8 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 4 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 7 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 6 will create 482 directories. Tue Jan 10 02:38:26 2012 Child 6 finished creating 482 directories. Tue Jan 10 02:38:26 2012 Child 6 will create 14732 files. Tue Jan 10 02:38:30 2012 Child 9 finished creating 482 directories. Tue Jan 10 02:38:30 2012 Child 9 will create 14732 files. Tue Jan 10 02:38:31 2012 Child 3 finished creating 482 directories. Tue Jan 10 02:38:31 2012 Child 3 will create 14732 files. Tue Jan 10 02:38:33 2012 Child 8 finished creating 482 directories. Tue Jan 10 02:38:33 2012 Child 8 will create 14732 files. Tue Jan 10 02:38:33 2012 Child 1 finished creating 482 directories. Tue Jan 10 02:38:33 2012 Child 1 will create 14732 files. Tue Jan 10 02:38:36 2012 Child 0 finished creating 482 directories. Tue Jan 10 02:38:36 2012 Child 0 will create 14732 files. Tue Jan 10 02:38:37 2012 Child 10 finished creating 482 directories. Tue Jan 10 02:38:37 2012 Child 10 will create 14732 files. Tue Jan 10 02:38:39 2012 Child 11 finished creating 482 directories. Tue Jan 10 02:38:39 2012 Child 11 will create 14732 files. Tue Jan 10 02:38:39 2012 Child 7 finished creating 482 directories. Tue Jan 10 02:38:39 2012 Child 7 will create 14732 files. Tue Jan 10 02:38:45 2012 Child 15 finished creating 482 directories. Tue Jan 10 02:38:45 2012 Child 15 will create 14732 files. Tue Jan 10 02:38:46 2012 Child 2 finished creating 482 directories. Tue Jan 10 02:38:46 2012 Child 2 will create 14732 files. Tue Jan 10 02:38:46 2012 Child 13 finished creating 482 directories. Tue Jan 10 02:38:46 2012 Child 13 will create 14732 files. Tue Jan 10 02:38:47 2012 Child 4 finished creating 482 directories. Tue Jan 10 02:38:47 2012 Child 4 will create 14732 files. Tue Jan 10 02:38:48 2012 Child 5 finished creating 482 directories. Tue Jan 10 02:38:48 2012 Child 5 will create 14732 files. Tue Jan 10 02:39:12 2012 Child 14 finished creating 482 directories. Tue Jan 10 02:39:12 2012 Child 14 will create 14732 files. Tue Jan 10 02:39:12 2012 Child 12 finished creating 482 directories. Tue Jan 10 02:39:12 2012 Child 12 will create 14732 files. Tue Jan 10 02:49:49 2012 Child 14 verified 13116 files (155 creates). Tue Jan 10 02:49:49 2012 Child 0 verified 13756 files (795 creates). Tue Jan 10 02:49:49 2012 Child 7 verified 13612 files (651 creates). Tue Jan 10 02:49:51 2012 Child 6 verified 14273 files (1312 creates). Tue Jan 10 02:49:51 2012 Child 11 verified 13614 files (653 creates). Tue Jan 10 02:49:51 2012 Child 4 verified 13421 files (460 creates). Tue Jan 10 02:49:52 2012 Child 5 verified 13353 files (392 creates). Tue Jan 10 02:49:54 2012 Child 3 verified 13903 files (942 creates). Tue Jan 10 02:49:54 2012 Child 2 verified 13376 files (415 creates). Tue Jan 10 02:49:54 2012 Child 10 verified 13629 files (668 creates). Tue Jan 10 02:49:54 2012 Child 15 verified 13376 files (415 creates). Tue Jan 10 02:49:54 2012 Child 9 verified 13953 files (992 creates). Tue Jan 10 02:49:54 2012 Child 13 verified 13376 files (415 creates). Tue Jan 10 02:49:54 2012 Child 1 verified 13761 files (800 creates). Tue Jan 10 02:49:54 2012 Child 12 verified 13130 files (169 creates). Tue Jan 10 02:49:54 2012 Child 8 verified 13789 files (828 creates). Tue Jan 10 02:51:57 2012 Child 6 verified 14732 files, created 1627 Tue Jan 10 02:51:57 2012 Child 6 will create 2 symlinks. Tue Jan 10 02:51:57 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 02:54:50 2012 Child 0 verified 13950 files (989 creates). Tue Jan 10 02:54:50 2012 Child 11 verified 14120 files (1159 creates). Tue Jan 10 02:54:50 2012 Child 7 verified 14038 files (1077 creates). Tue Jan 10 02:54:55 2012 Child 15 verified 13789 files (828 creates). Tue Jan 10 02:54:56 2012 Child 2 verified 13812 files (851 creates). Tue Jan 10 02:54:56 2012 Child 4 verified 13903 files (942 creates). Tue Jan 10 02:54:56 2012 Child 5 verified 13761 files (800 creates). Tue Jan 10 02:54:56 2012 Child 12 verified 13544 files (583 creates). Tue Jan 10 02:54:56 2012 Child 13 verified 13789 files (828 creates). Tue Jan 10 02:54:56 2012 Child 9 verified 14432 files (1471 creates). Tue Jan 10 02:54:56 2012 Child 3 verified 14142 files (1181 creates). Tue Jan 10 02:54:56 2012 Child 1 verified 13998 files (1037 creates). Tue Jan 10 02:54:56 2012 Child 14 verified 13335 files (374 creates). Tue Jan 10 02:54:56 2012 Child 10 verified 14041 files (1080 creates). Tue Jan 10 02:54:56 2012 Child 8 verified 14238 files (1277 creates). Tue Jan 10 02:55:14 2012 Child 9 verified 14732 files, created 1627 Tue Jan 10 02:55:14 2012 Child 9 will create 2 symlinks. Tue Jan 10 02:55:14 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 03:00:09 2012 Child 1 verified 14199 files (1238 creates). Tue Jan 10 03:00:09 2012 Child 8 verified 14415 files (1454 creates). Tue Jan 10 03:00:09 2012 Child 14 verified 13535 files (574 creates). Tue Jan 10 03:00:09 2012 Child 0 verified 14178 files (1217 creates). Tue Jan 10 03:00:13 2012 Child 11 verified 14326 files (1365 creates). Tue Jan 10 03:00:13 2012 Child 10 verified 14214 files (1253 creates). Tue Jan 10 03:00:13 2012 Child 4 verified 14065 files (1104 creates). Tue Jan 10 03:00:13 2012 Child 3 verified 14326 files (1365 creates). Tue Jan 10 03:00:13 2012 Child 13 verified 13953 files (992 creates). Tue Jan 10 03:00:13 2012 Child 7 verified 14277 files (1316 creates). Tue Jan 10 03:00:13 2012 Child 2 verified 14001 files (1040 creates). Tue Jan 10 03:00:13 2012 Child 5 verified 13953 files (992 creates). Tue Jan 10 03:00:13 2012 Child 12 verified 13761 files (800 creates). Tue Jan 10 03:00:13 2012 Child 15 verified 13953 files (992 creates). Tue Jan 10 03:00:25 2012 Child 8 verified 14732 files, created 1627 Tue Jan 10 03:00:25 2012 Child 8 will create 2 symlinks. Tue Jan 10 03:00:25 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 03:01:43 2012 Child 11 verified 14732 files, created 1627 Tue Jan 10 03:01:43 2012 Child 11 will create 2 symlinks. Tue Jan 10 03:01:43 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 03:02:43 2012 Child 3 verified 14732 files, created 1627 Tue Jan 10 03:02:43 2012 Child 3 will create 2 symlinks. Tue Jan 10 03:02:43 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 03:02:51 2012 Child 4 verified 14732 files, created 1627 Tue Jan 10 03:02:51 2012 Child 4 will create 2 symlinks. Tue Jan 10 03:02:55 2012 Child 10 verified 14732 files, created 1627 Tue Jan 10 03:02:55 2012 Child 10 will create 2 symlinks. Tue Jan 10 03:02:56 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 03:02:59 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 03:03:10 2012 Child 2 verified 14732 files, created 1627 Tue Jan 10 03:03:10 2012 Child 2 will create 2 symlinks. Tue Jan 10 03:03:10 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 03:03:11 2012 Child 1 verified 14732 files, created 1627 Tue Jan 10 03:03:11 2012 Child 1 will create 2 symlinks. Tue Jan 10 03:03:11 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 03:03:12 2012 Child 13 verified 14732 files, created 1627 Tue Jan 10 03:03:12 2012 Child 13 will create 2 symlinks. Tue Jan 10 03:03:12 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 03:03:12 2012 Child 5 verified 14732 files, created 1627 Tue Jan 10 03:03:12 2012 Child 5 will create 2 symlinks. Tue Jan 10 03:03:12 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 03:03:14 2012 Child 15 verified 14732 files, created 1627 Tue Jan 10 03:03:14 2012 Child 15 will create 2 symlinks. Tue Jan 10 03:03:14 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 03:03:14 2012 Child 0 verified 14732 files, created 1627 Tue Jan 10 03:03:14 2012 Child 0 will create 2 symlinks. Tue Jan 10 03:03:14 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 03:03:18 2012 Child 12 verified 14732 files, created 1627 Tue Jan 10 03:03:18 2012 Child 12 will create 2 symlinks. Tue Jan 10 03:03:18 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 03:03:21 2012 Child 7 verified 14732 files, created 1627 Tue Jan 10 03:03:21 2012 Child 7 will create 2 symlinks. Tue Jan 10 03:03:21 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 03:03:25 2012 Child 14 verified 14732 files, created 1627 Tue Jan 10 03:03:25 2012 Child 14 will create 2 symlinks. Tue Jan 10 03:03:25 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 03:03:25 2012 Completed. Tue Jan 10 03:03:25 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:03:25 2012 Completed. Tue Jan 10 03:03:25 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:03:26 2012 Received. Tue Jan 10 03:03:26 2012 Performing 300 seconds pretest warmup. Tue Jan 10 03:08:26 2012 Completed. Tue Jan 10 03:08:26 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:08:26 2012 Completed. Tue Jan 10 03:08:26 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:08:27 2012 Received. Tue Jan 10 03:08:27 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 03:13:49 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% 44958 44958 0 5.73 195.15 0.13 4.2% setattr 4.0% 4.0% 6878 6878 0 80.16 167.22 0.31 8.9% lookup 24.0% 24.0% 41592 41592 0 5.71 176.75 0.13 3.8% readlink 1.0% 1.0% 1705 1705 0 3.71 15.56 0.19 0.1% read 18.0% 17.9% 30953 37608 0 87.73 666.23 0.29 44.0% write 10.0% 10.1% 17410 20990 0 74.79 516.35 0.34 21.1% create 1.0% 1.0% 1706 1706 0 150.15 232.13 0.72 4.1% remove 1.0% 1.0% 1777 1777 0 89.05 179.97 0.62 2.6% readdir 1.0% 1.0% 1762 1762 0 4.47 11.83 0.16 0.1% fsstat 1.0% 1.0% 1748 1748 0 4.75 25.78 0.24 0.1% access 11.0% 11.0% 18991 18991 0 4.42 19.84 0.06 1.4% commit 0.0% 0.0% 0 6407 0 0.00 0.00 0.00 9.3% readdirplus 2.0% 2.0% 3492 3492 0 5.35 14.87 0.13 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 556.18 Ops/Sec AVG. RESPONSE TIME: 35.70 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1000 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 172972 TEST TIME: 311 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 189614 PHYSICAL NFS V3 THROUGHPUT: 609.69 Ops/Sec FILE SET SIZE CREATED: 122707728.0 KB FILE SET SIZE ACCESSED: 36813168.0 - 37088656.0 KB (100.00% to 100.75% of Base) ------------------------------------------------------------------------ Tue Jan 10 03:13:49 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 Tue Jan 10 03:13:49 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:13:49 2012 Completed. Tue Jan 10 03:13:49 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 03:13:49 2012 Received. Tue Jan 10 03:13:49 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 03:13:49 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, Tue Jan 10 03:13:51 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 Tue Jan 10 03:13:51 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 Tue Jan 10 03:14:05 2012 Completed. Tue Jan 10 03:14:05 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:14:05 2012 Completed. Tue Jan 10 03:14:05 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:14:07 2012 Received. Tue Jan 10 03:14:07 2012 Initializing test directories. Tue Jan 10 03:14:07 2012 Child 15 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 1 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 14 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 10 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 0 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 13 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 2 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 9 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 12 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 3 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 11 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 4 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 8 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 5 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 7 will create 527 directories. Tue Jan 10 03:14:07 2012 Child 6 will create 527 directories. Tue Jan 10 03:14:12 2012 Child 4 finished creating 527 directories. Tue Jan 10 03:14:12 2012 Child 4 will create 16118 files. Tue Jan 10 03:14:13 2012 Child 14 finished creating 527 directories. Tue Jan 10 03:14:13 2012 Child 14 will create 16118 files. Tue Jan 10 03:14:16 2012 Child 2 finished creating 527 directories. Tue Jan 10 03:14:16 2012 Child 2 will create 16118 files. Tue Jan 10 03:14:19 2012 Child 1 finished creating 527 directories. Tue Jan 10 03:14:19 2012 Child 1 will create 16118 files. Tue Jan 10 03:14:21 2012 Child 6 finished creating 527 directories. Tue Jan 10 03:14:21 2012 Child 6 will create 16118 files. Tue Jan 10 03:14:22 2012 Child 7 finished creating 527 directories. Tue Jan 10 03:14:22 2012 Child 7 will create 16118 files. Tue Jan 10 03:14:27 2012 Child 8 finished creating 527 directories. Tue Jan 10 03:14:27 2012 Child 8 will create 16118 files. Tue Jan 10 03:14:32 2012 Child 12 finished creating 527 directories. Tue Jan 10 03:14:32 2012 Child 12 will create 16118 files. Tue Jan 10 03:14:36 2012 Child 15 finished creating 527 directories. Tue Jan 10 03:14:36 2012 Child 15 will create 16118 files. Tue Jan 10 03:14:38 2012 Child 5 finished creating 527 directories. Tue Jan 10 03:14:38 2012 Child 5 will create 16118 files. Tue Jan 10 03:14:38 2012 Child 13 finished creating 527 directories. Tue Jan 10 03:14:38 2012 Child 13 will create 16118 files. Tue Jan 10 03:14:39 2012 Child 11 finished creating 527 directories. Tue Jan 10 03:14:39 2012 Child 11 will create 16118 files. Tue Jan 10 03:14:39 2012 Child 9 finished creating 527 directories. Tue Jan 10 03:14:39 2012 Child 9 will create 16118 files. Tue Jan 10 03:14:48 2012 Child 0 finished creating 527 directories. Tue Jan 10 03:14:48 2012 Child 0 will create 16118 files. Tue Jan 10 03:14:54 2012 Child 3 finished creating 527 directories. Tue Jan 10 03:14:54 2012 Child 3 will create 16118 files. Tue Jan 10 03:14:59 2012 Child 10 finished creating 527 directories. Tue Jan 10 03:14:59 2012 Child 10 will create 16118 files. Tue Jan 10 03:20:25 2012 Child 8 verified 14936 files (493 creates). Tue Jan 10 03:20:25 2012 Child 3 verified 14558 files (115 creates). Tue Jan 10 03:20:25 2012 Child 2 verified 15316 files (873 creates). Tue Jan 10 03:20:25 2012 Child 10 verified 14490 files (47 creates). Tue Jan 10 03:20:25 2012 Child 0 verified 14588 files (145 creates). Tue Jan 10 03:20:25 2012 Child 1 verified 15130 files (687 creates). Tue Jan 10 03:20:25 2012 Child 6 verified 15065 files (622 creates). Tue Jan 10 03:20:26 2012 Child 14 verified 15639 files (1196 creates). Tue Jan 10 03:20:29 2012 Child 13 verified 14716 files (273 creates). Tue Jan 10 03:20:29 2012 Child 11 verified 14716 files (273 creates). Tue Jan 10 03:20:29 2012 Child 4 verified 15672 files (1229 creates). Tue Jan 10 03:20:29 2012 Child 7 verified 14980 files (537 creates). Tue Jan 10 03:20:29 2012 Child 9 verified 14716 files (273 creates). Tue Jan 10 03:20:29 2012 Child 15 verified 14745 files (302 creates). Tue Jan 10 03:20:29 2012 Child 12 verified 14745 files (302 creates). Tue Jan 10 03:20:29 2012 Child 5 verified 14733 files (290 creates). Tue Jan 10 03:22:08 2012 Child 4 verified 16118 files, created 1517 Tue Jan 10 03:22:08 2012 Child 4 will create 2 symlinks. Tue Jan 10 03:22:08 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 03:23:59 2012 Child 14 verified 16118 files, created 1517 Tue Jan 10 03:23:59 2012 Child 14 will create 2 symlinks. Tue Jan 10 03:23:59 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 03:26:19 2012 Child 6 verified 15407 files (964 creates). Tue Jan 10 03:26:19 2012 Child 10 verified 14822 files (379 creates). Tue Jan 10 03:26:19 2012 Child 5 verified 15030 files (587 creates). Tue Jan 10 03:26:19 2012 Child 1 verified 15499 files (1056 creates). Tue Jan 10 03:26:19 2012 Child 3 verified 14851 files (408 creates). Tue Jan 10 03:26:19 2012 Child 9 verified 15030 files (587 creates). Tue Jan 10 03:26:19 2012 Child 0 verified 14916 files (473 creates). Tue Jan 10 03:26:19 2012 Child 2 verified 15658 files (1215 creates). Tue Jan 10 03:26:19 2012 Child 8 verified 15256 files (813 creates). Tue Jan 10 03:26:19 2012 Child 13 verified 15030 files (587 creates). Tue Jan 10 03:26:19 2012 Child 11 verified 15030 files (587 creates). Tue Jan 10 03:26:19 2012 Child 15 verified 15088 files (645 creates). Tue Jan 10 03:26:19 2012 Child 7 verified 15279 files (836 creates). Tue Jan 10 03:26:19 2012 Child 12 verified 15071 files (628 creates). Tue Jan 10 03:27:01 2012 Child 2 verified 16118 files, created 1517 Tue Jan 10 03:27:01 2012 Child 2 will create 2 symlinks. Tue Jan 10 03:27:01 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 03:27:27 2012 Child 1 verified 16118 files, created 1517 Tue Jan 10 03:27:27 2012 Child 1 will create 2 symlinks. Tue Jan 10 03:27:27 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 03:27:32 2012 Child 6 verified 16118 files, created 1517 Tue Jan 10 03:27:32 2012 Child 6 will create 2 symlinks. Tue Jan 10 03:27:32 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 03:27:47 2012 Child 7 verified 16118 files, created 1517 Tue Jan 10 03:27:47 2012 Child 7 will create 2 symlinks. Tue Jan 10 03:27:50 2012 Child 8 verified 16118 files, created 1517 Tue Jan 10 03:27:50 2012 Child 8 will create 2 symlinks. Tue Jan 10 03:27:50 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 03:27:58 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 03:28:16 2012 Child 15 verified 16118 files, created 1517 Tue Jan 10 03:28:16 2012 Child 15 will create 2 symlinks. Tue Jan 10 03:28:16 2012 Child 12 verified 16118 files, created 1517 Tue Jan 10 03:28:16 2012 Child 12 will create 2 symlinks. Tue Jan 10 03:28:16 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 03:28:16 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 03:28:18 2012 Child 5 verified 16118 files, created 1517 Tue Jan 10 03:28:18 2012 Child 5 will create 2 symlinks. Tue Jan 10 03:28:19 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 03:28:19 2012 Child 11 verified 16118 files, created 1517 Tue Jan 10 03:28:19 2012 Child 11 will create 2 symlinks. Tue Jan 10 03:28:20 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 03:28:21 2012 Child 9 verified 16118 files, created 1517 Tue Jan 10 03:28:21 2012 Child 9 will create 2 symlinks. Tue Jan 10 03:28:21 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 03:28:22 2012 Child 13 verified 16118 files, created 1517 Tue Jan 10 03:28:22 2012 Child 13 will create 2 symlinks. Tue Jan 10 03:28:22 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 03:28:25 2012 Child 0 verified 16118 files, created 1517 Tue Jan 10 03:28:25 2012 Child 0 will create 2 symlinks. Tue Jan 10 03:28:25 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 03:28:26 2012 Child 3 verified 16118 files, created 1517 Tue Jan 10 03:28:26 2012 Child 3 will create 2 symlinks. Tue Jan 10 03:28:26 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 03:28:26 2012 Child 10 verified 16118 files, created 1517 Tue Jan 10 03:28:26 2012 Child 10 will create 2 symlinks. Tue Jan 10 03:28:26 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 03:28:27 2012 Completed. Tue Jan 10 03:28:27 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:28:27 2012 Completed. Tue Jan 10 03:28:27 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:28:27 2012 Received. Tue Jan 10 03:28:27 2012 Performing 300 seconds pretest warmup. Tue Jan 10 03:33:27 2012 Completed. Tue Jan 10 03:33:27 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:33:27 2012 Completed. Tue Jan 10 03:33:27 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:33:28 2012 Received. Tue Jan 10 03:33:28 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 03:38: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% 32120 32120 0 5.84 18.16 0.05 3.3% setattr 4.0% 4.0% 4937 4937 0 140.18 238.69 0.43 12.1% lookup 24.0% 24.1% 29574 29574 0 5.86 19.16 0.05 3.0% readlink 1.0% 1.0% 1229 1229 0 5.91 18.65 0.24 0.1% read 18.0% 17.8% 21920 26637 0 107.59 148.50 0.16 41.2% write 10.0% 10.0% 12238 14813 0 88.86 99.97 0.18 19.0% create 1.0% 1.0% 1199 1199 0 258.93 339.01 1.04 5.4% remove 1.0% 1.0% 1225 1225 0 136.68 200.71 0.79 2.9% readdir 1.0% 1.0% 1203 1203 0 6.16 17.42 0.24 0.1% fsstat 1.0% 1.0% 1234 1234 0 5.78 17.03 0.23 0.1% access 11.0% 11.0% 13542 13542 0 5.74 18.87 0.07 1.4% commit 0.0% 0.0% 0 4300 0 0.00 0.00 0.00 10.9% readdirplus 2.0% 2.0% 2443 2443 0 6.84 18.07 0.17 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 410.91 Ops/Sec AVG. RESPONSE TIME: 46.54 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1100 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 122864 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 134456 PHYSICAL NFS V3 THROUGHPUT: 449.68 Ops/Sec FILE SET SIZE CREATED: 134245296.0 KB FILE SET SIZE ACCESSED: 40279536.0 - 40451072.0 KB (100.00% to 100.43% of Base) ------------------------------------------------------------------------ Tue Jan 10 03:38:38 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 Tue Jan 10 03:38:38 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:38:38 2012 Completed. Tue Jan 10 03:38:38 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 03:38:39 2012 Received. Tue Jan 10 03:38:39 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 03:38:39 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, Tue Jan 10 03:38: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 Tue Jan 10 03:38: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 Tue Jan 10 03:38:55 2012 Completed. Tue Jan 10 03:38:55 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:38:55 2012 Completed. Tue Jan 10 03:38:55 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:38:57 2012 Received. Tue Jan 10 03:38:57 2012 Initializing test directories. Tue Jan 10 03:38:57 2012 Child 15 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 11 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 14 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 0 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 1 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 13 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 3 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 2 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 12 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 8 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 5 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 4 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 10 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 6 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 7 will create 577 directories. Tue Jan 10 03:38:57 2012 Child 9 will create 577 directories. Tue Jan 10 03:38:58 2012 Child 6 finished creating 577 directories. Tue Jan 10 03:38:58 2012 Child 6 will create 17628 files. Tue Jan 10 03:39:01 2012 Child 9 finished creating 577 directories. Tue Jan 10 03:39:01 2012 Child 9 will create 17628 files. Tue Jan 10 03:39:04 2012 Child 10 finished creating 577 directories. Tue Jan 10 03:39:04 2012 Child 10 will create 17628 files. Tue Jan 10 03:39:08 2012 Child 13 finished creating 577 directories. Tue Jan 10 03:39:08 2012 Child 13 will create 17628 files. Tue Jan 10 03:39:08 2012 Child 0 finished creating 577 directories. Tue Jan 10 03:39:08 2012 Child 0 will create 17628 files. Tue Jan 10 03:39:09 2012 Child 14 finished creating 577 directories. Tue Jan 10 03:39:09 2012 Child 14 will create 17628 files. Tue Jan 10 03:39:12 2012 Child 1 finished creating 577 directories. Tue Jan 10 03:39:12 2012 Child 1 will create 17628 files. Tue Jan 10 03:39:20 2012 Child 3 finished creating 577 directories. Tue Jan 10 03:39:20 2012 Child 3 will create 17628 files. Tue Jan 10 03:39:23 2012 Child 4 finished creating 577 directories. Tue Jan 10 03:39:23 2012 Child 4 will create 17628 files. Tue Jan 10 03:39:26 2012 Child 7 finished creating 577 directories. Tue Jan 10 03:39:26 2012 Child 7 will create 17628 files. Tue Jan 10 03:39:29 2012 Child 8 finished creating 577 directories. Tue Jan 10 03:39:29 2012 Child 8 will create 17628 files. Tue Jan 10 03:39:33 2012 Child 11 finished creating 577 directories. Tue Jan 10 03:39:33 2012 Child 11 will create 17628 files. Tue Jan 10 03:39:34 2012 Child 2 finished creating 577 directories. Tue Jan 10 03:39:34 2012 Child 2 will create 17628 files. Tue Jan 10 03:39:35 2012 Child 12 finished creating 577 directories. Tue Jan 10 03:39:35 2012 Child 12 will create 17628 files. Tue Jan 10 03:39:40 2012 Child 15 finished creating 577 directories. Tue Jan 10 03:39:40 2012 Child 15 will create 17628 files. Tue Jan 10 03:39:53 2012 Child 5 finished creating 577 directories. Tue Jan 10 03:39:53 2012 Child 5 will create 17628 files. Tue Jan 10 03:42:21 2012 Child 6 verified 17628 files, created 1655 Tue Jan 10 03:42:21 2012 Child 6 will create 2 symlinks. Tue Jan 10 03:42:27 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 03:43:19 2012 Child 9 verified 17628 files, created 1655 Tue Jan 10 03:43:19 2012 Child 9 will create 2 symlinks. Tue Jan 10 03:43:28 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 03:43:43 2012 Child 10 verified 17628 files, created 1655 Tue Jan 10 03:43:43 2012 Child 10 will create 2 symlinks. Tue Jan 10 03:43:44 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 03:43:53 2012 Child 13 verified 17628 files, created 1655 Tue Jan 10 03:43:53 2012 Child 13 will create 2 symlinks. Tue Jan 10 03:43:56 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 03:43:57 2012 Child 0 verified 17628 files, created 1655 Tue Jan 10 03:43:57 2012 Child 0 will create 2 symlinks. Tue Jan 10 03:44:03 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 03:44:14 2012 Child 1 verified 17189 files (1388 creates). Tue Jan 10 03:44:19 2012 Child 14 verified 17628 files, created 1655 Tue Jan 10 03:44:19 2012 Child 14 will create 2 symlinks. Tue Jan 10 03:44:23 2012 Child 3 verified 17074 files (1273 creates). Tue Jan 10 03:44:24 2012 Child 4 verified 17069 files (1268 creates). Tue Jan 10 03:44:27 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 03:44:27 2012 Child 7 verified 17014 files (1213 creates). Tue Jan 10 03:44:34 2012 Child 8 verified 17043 files (1242 creates). Tue Jan 10 03:44:34 2012 Child 11 verified 16974 files (1173 creates). Tue Jan 10 03:44:36 2012 Child 2 verified 16980 files (1179 creates). Tue Jan 10 03:44:36 2012 Child 12 verified 16988 files (1187 creates). Tue Jan 10 03:44:45 2012 Child 15 verified 16973 files (1172 creates). Tue Jan 10 03:44:46 2012 Child 1 verified 17628 files, created 1655 Tue Jan 10 03:44:46 2012 Child 1 will create 2 symlinks. Tue Jan 10 03:44:46 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 03:44:54 2012 Child 5 verified 16887 files (1086 creates). Tue Jan 10 03:45:05 2012 Child 3 verified 17628 files, created 1655 Tue Jan 10 03:45:05 2012 Child 3 will create 2 symlinks. Tue Jan 10 03:45:05 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 03:45:06 2012 Child 4 verified 17628 files, created 1655 Tue Jan 10 03:45:06 2012 Child 4 will create 2 symlinks. Tue Jan 10 03:45:06 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 03:45:11 2012 Child 7 verified 17628 files, created 1655 Tue Jan 10 03:45:11 2012 Child 7 will create 2 symlinks. Tue Jan 10 03:45:11 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 03:45:18 2012 Child 8 verified 17628 files, created 1655 Tue Jan 10 03:45:18 2012 Child 8 will create 2 symlinks. Tue Jan 10 03:45:18 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 03:45:23 2012 Child 2 verified 17628 files, created 1655 Tue Jan 10 03:45:23 2012 Child 2 will create 2 symlinks. Tue Jan 10 03:45:23 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 03:45:23 2012 Child 11 verified 17628 files, created 1655 Tue Jan 10 03:45:23 2012 Child 11 will create 2 symlinks. Tue Jan 10 03:45:23 2012 Child 12 verified 17628 files, created 1655 Tue Jan 10 03:45:23 2012 Child 12 will create 2 symlinks. Tue Jan 10 03:45:23 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 03:45:23 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 03:45:23 2012 Child 15 verified 17628 files, created 1655 Tue Jan 10 03:45:23 2012 Child 15 will create 2 symlinks. Tue Jan 10 03:45:24 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 03:45:28 2012 Child 5 verified 17628 files, created 1655 Tue Jan 10 03:45:28 2012 Child 5 will create 2 symlinks. Tue Jan 10 03:45:28 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 03:45:28 2012 Completed. Tue Jan 10 03:45:28 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:45:28 2012 Completed. Tue Jan 10 03:45:28 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:45:28 2012 Received. Tue Jan 10 03:45:28 2012 Performing 300 seconds pretest warmup. Tue Jan 10 03:50:28 2012 Completed. Tue Jan 10 03:50:28 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:50:28 2012 Completed. Tue Jan 10 03:50:28 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:50:29 2012 Received. Tue Jan 10 03:50:29 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 03:55:39 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% 8938 8938 0 49.40 161.29 0.26 7.1% setattr 4.0% 4.1% 1428 1428 0 211.43 244.92 0.81 4.8% lookup 24.0% 23.9% 8341 8341 0 50.49 165.29 0.28 6.7% readlink 1.0% 1.0% 363 363 0 61.13 267.70 1.68 0.4% read 18.0% 18.2% 6352 7849 0 540.42 1748.79 1.03 54.8% write 10.0% 10.1% 3545 4296 0 265.48 579.15 0.79 15.0% create 1.0% 1.0% 353 353 0 288.74 266.47 1.70 1.6% remove 1.0% 0.9% 321 321 0 219.28 202.89 1.56 1.1% readdir 1.0% 0.9% 310 310 0 53.70 141.87 1.33 0.3% fsstat 1.0% 1.1% 388 388 0 48.02 145.02 1.20 0.3% access 11.0% 11.2% 3901 3901 0 44.75 146.85 0.38 2.8% commit 0.0% 0.0% 0 1213 0 0.00 0.00 0.00 4.4% readdirplus 2.0% 2.0% 698 698 0 60.03 129.15 0.84 0.7% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 117.63 Ops/Sec AVG. RESPONSE TIME: 179.19 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1200 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 34938 TEST TIME: 297 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 38399 PHYSICAL NFS V3 THROUGHPUT: 129.28 Ops/Sec FILE SET SIZE CREATED: 146827872.0 KB FILE SET SIZE ACCESSED: 44051760.0 - 44112464.0 KB (100.00% to 100.14% of Base) ------------------------------------------------------------------------ Tue Jan 10 03:55:39 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 Tue Jan 10 03:55:39 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:55:39 2012 Completed. Tue Jan 10 03:55:39 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 03:55:40 2012 Received. Tue Jan 10 03:55:40 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 03:55:40 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, Tue Jan 10 03:55:42 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 Tue Jan 10 03:55:42 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 Tue Jan 10 03:55:56 2012 Completed. Tue Jan 10 03:55:56 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:55:56 2012 Completed. Tue Jan 10 03:55:56 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 03:55:58 2012 Received. Tue Jan 10 03:55:58 2012 Initializing test directories. Tue Jan 10 03:55:58 2012 Child 11 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 1 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 2 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 6 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 10 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 5 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 15 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 3 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 9 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 13 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 8 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 7 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 14 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 12 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 4 will create 626 directories. Tue Jan 10 03:55:58 2012 Child 0 will create 626 directories. Tue Jan 10 03:56:00 2012 Child 12 finished creating 626 directories. Tue Jan 10 03:56:00 2012 Child 12 will create 19139 files. Tue Jan 10 03:56:01 2012 Child 5 finished creating 626 directories. Tue Jan 10 03:56:01 2012 Child 5 will create 19139 files. Tue Jan 10 03:56:04 2012 Child 13 finished creating 626 directories. Tue Jan 10 03:56:04 2012 Child 13 will create 19139 files. Tue Jan 10 03:56:07 2012 Child 4 finished creating 626 directories. Tue Jan 10 03:56:07 2012 Child 4 will create 19139 files. Tue Jan 10 03:56:07 2012 Child 7 finished creating 626 directories. Tue Jan 10 03:56:07 2012 Child 7 will create 19139 files. Tue Jan 10 03:56:10 2012 Child 1 finished creating 626 directories. Tue Jan 10 03:56:10 2012 Child 1 will create 19139 files. Tue Jan 10 03:56:39 2012 Child 10 finished creating 626 directories. Tue Jan 10 03:56:39 2012 Child 10 will create 19139 files. Tue Jan 10 03:56:42 2012 Child 6 finished creating 626 directories. Tue Jan 10 03:56:42 2012 Child 6 will create 19139 files. Tue Jan 10 03:56:45 2012 Child 14 finished creating 626 directories. Tue Jan 10 03:56:45 2012 Child 14 will create 19139 files. Tue Jan 10 03:56:47 2012 Child 0 finished creating 626 directories. Tue Jan 10 03:56:47 2012 Child 0 will create 19139 files. Tue Jan 10 03:56:53 2012 Child 3 finished creating 626 directories. Tue Jan 10 03:56:53 2012 Child 3 will create 19139 files. Tue Jan 10 03:57:03 2012 Child 8 finished creating 626 directories. Tue Jan 10 03:57:03 2012 Child 8 will create 19139 files. Tue Jan 10 03:57:03 2012 Child 11 finished creating 626 directories. Tue Jan 10 03:57:03 2012 Child 11 will create 19139 files. Tue Jan 10 03:57:10 2012 Child 2 finished creating 626 directories. Tue Jan 10 03:57:10 2012 Child 2 will create 19139 files. Tue Jan 10 03:57:10 2012 Child 15 finished creating 626 directories. Tue Jan 10 03:57:10 2012 Child 15 will create 19139 files. Tue Jan 10 03:57:11 2012 Child 9 finished creating 626 directories. Tue Jan 10 03:57:11 2012 Child 9 will create 19139 files. Tue Jan 10 04:01:02 2012 Child 12 verified 17421 files (139 creates). Tue Jan 10 04:01:02 2012 Child 5 verified 17480 files (198 creates). Tue Jan 10 04:01:05 2012 Child 13 verified 17472 files (190 creates). Tue Jan 10 04:01:57 2012 Child 7 verified 17500 files (218 creates). Tue Jan 10 04:01:57 2012 Child 1 verified 17500 files (218 creates). Tue Jan 10 04:01:57 2012 Child 10 verified 17500 files (218 creates). Tue Jan 10 04:01:57 2012 Child 4 verified 17508 files (226 creates). Tue Jan 10 04:02:01 2012 Child 6 verified 17420 files (138 creates). Tue Jan 10 04:02:01 2012 Child 0 verified 17420 files (138 creates). Tue Jan 10 04:02:01 2012 Child 14 verified 17420 files (138 creates). Tue Jan 10 04:02:01 2012 Child 3 verified 17420 files (138 creates). Tue Jan 10 04:02:04 2012 Child 8 verified 17466 files (184 creates). Tue Jan 10 04:02:05 2012 Child 11 verified 17464 files (182 creates). Tue Jan 10 04:02:55 2012 Child 9 verified 17500 files (218 creates). Tue Jan 10 04:02:55 2012 Child 15 verified 17500 files (218 creates). Tue Jan 10 04:02:55 2012 Child 2 verified 17508 files (226 creates). Tue Jan 10 04:06:15 2012 Child 5 verified 17827 files (545 creates). Tue Jan 10 04:06:15 2012 Child 12 verified 17798 files (516 creates). Tue Jan 10 04:06:15 2012 Child 13 verified 17798 files (516 creates). Tue Jan 10 04:07:17 2012 Child 14 verified 17854 files (572 creates). Tue Jan 10 04:07:17 2012 Child 10 verified 17862 files (580 creates). Tue Jan 10 04:07:32 2012 Child 1 verified 17865 files (583 creates). Tue Jan 10 04:07:33 2012 Child 11 verified 17785 files (503 creates). Tue Jan 10 04:07:33 2012 Child 6 verified 17785 files (503 creates). Tue Jan 10 04:07:33 2012 Child 7 verified 17866 files (584 creates). Tue Jan 10 04:07:33 2012 Child 4 verified 17866 files (584 creates). Tue Jan 10 04:07:33 2012 Child 0 verified 17798 files (516 creates). Tue Jan 10 04:07:33 2012 Child 8 verified 17785 files (503 creates). Tue Jan 10 04:07:33 2012 Child 3 verified 17785 files (503 creates). Tue Jan 10 04:08:26 2012 Child 9 verified 17798 files (516 creates). Tue Jan 10 04:08:26 2012 Child 15 verified 17798 files (516 creates). Tue Jan 10 04:08:26 2012 Child 2 verified 17866 files (584 creates). Tue Jan 10 04:11:47 2012 Child 5 verified 18034 files (752 creates). Tue Jan 10 04:11:47 2012 Child 12 verified 17991 files (709 creates). Tue Jan 10 04:11:47 2012 Child 13 verified 17991 files (709 creates). Tue Jan 10 04:12:40 2012 Child 6 verified 17936 files (654 creates). Tue Jan 10 04:12:40 2012 Child 3 verified 17936 files (654 creates). Tue Jan 10 04:12:41 2012 Child 11 verified 17936 files (654 creates). Tue Jan 10 04:12:41 2012 Child 8 verified 17936 files (654 creates). Tue Jan 10 04:13:21 2012 Child 4 verified 18035 files (753 creates). Tue Jan 10 04:13:21 2012 Child 14 verified 18035 files (753 creates). Tue Jan 10 04:13:21 2012 Child 7 verified 18035 files (753 creates). Tue Jan 10 04:13:21 2012 Child 1 verified 18035 files (753 creates). Tue Jan 10 04:13:21 2012 Child 10 verified 18035 files (753 creates). Tue Jan 10 04:13:21 2012 Child 0 verified 17991 files (709 creates). Tue Jan 10 04:14:41 2012 Child 2 verified 18039 files (757 creates). Tue Jan 10 04:14:42 2012 Child 9 verified 17991 files (709 creates). Tue Jan 10 04:14:42 2012 Child 15 verified 17958 files (676 creates). Tue Jan 10 04:17:56 2012 Child 3 verified 18251 files (969 creates). Tue Jan 10 04:17:56 2012 Child 6 verified 18183 files (901 creates). Tue Jan 10 04:17:56 2012 Child 13 verified 18387 files (1105 creates). Tue Jan 10 04:17:56 2012 Child 11 verified 18209 files (927 creates). Tue Jan 10 04:17:56 2012 Child 5 verified 18398 files (1116 creates). Tue Jan 10 04:17:56 2012 Child 8 verified 18183 files (901 creates). Tue Jan 10 04:17:56 2012 Child 12 verified 18318 files (1036 creates). Tue Jan 10 04:18:21 2012 Child 0 verified 18401 files (1119 creates). Tue Jan 10 04:18:22 2012 Child 1 verified 18488 files (1206 creates). Tue Jan 10 04:18:22 2012 Child 4 verified 18423 files (1141 creates). Tue Jan 10 04:18:22 2012 Child 14 verified 18423 files (1141 creates). Tue Jan 10 04:18:22 2012 Child 10 verified 18449 files (1167 creates). Tue Jan 10 04:18:22 2012 Child 7 verified 18450 files (1168 creates). Tue Jan 10 04:19:27 2012 Child 1 verified 19139 files, created 1670 Tue Jan 10 04:19:27 2012 Child 1 will create 2 symlinks. Tue Jan 10 04:19:27 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 04:19:27 2012 Child 5 verified 19139 files, created 1670 Tue Jan 10 04:19:27 2012 Child 5 will create 2 symlinks. Tue Jan 10 04:19:27 2012 Child 13 verified 19139 files, created 1670 Tue Jan 10 04:19:27 2012 Child 13 will create 2 symlinks. Tue Jan 10 04:19:28 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 04:19:28 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 04:19:34 2012 Child 2 verified 19139 files, created 1670 Tue Jan 10 04:19:34 2012 Child 2 will create 2 symlinks. Tue Jan 10 04:19:35 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 04:19:37 2012 Child 14 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 14 will create 2 symlinks. Tue Jan 10 04:19:37 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 04:19:37 2012 Child 9 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 9 will create 2 symlinks. Tue Jan 10 04:19:37 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 04:19:37 2012 Child 12 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 12 will create 2 symlinks. Tue Jan 10 04:19:37 2012 Child 10 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 10 will create 2 symlinks. Tue Jan 10 04:19:37 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 04:19:37 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 04:19:37 2012 Child 7 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 7 will create 2 symlinks. Tue Jan 10 04:19:37 2012 Child 4 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 4 will create 2 symlinks. Tue Jan 10 04:19:37 2012 Child 0 verified 19139 files, created 1670 Tue Jan 10 04:19:37 2012 Child 0 will create 2 symlinks. Tue Jan 10 04:19:38 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 04:19:38 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 04:19:38 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 04:19:38 2012 Child 11 verified 19139 files, created 1670 Tue Jan 10 04:19:38 2012 Child 11 will create 2 symlinks. Tue Jan 10 04:19:38 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 04:19:39 2012 Child 3 verified 19139 files, created 1670 Tue Jan 10 04:19:39 2012 Child 3 will create 2 symlinks. Tue Jan 10 04:19:39 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 04:19:39 2012 Child 6 verified 19139 files, created 1670 Tue Jan 10 04:19:39 2012 Child 6 will create 2 symlinks. Tue Jan 10 04:19:39 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 04:19:39 2012 Child 8 verified 19139 files, created 1670 Tue Jan 10 04:19:39 2012 Child 8 will create 2 symlinks. Tue Jan 10 04:19:39 2012 Child 15 verified 19139 files, created 1670 Tue Jan 10 04:19:39 2012 Child 15 will create 2 symlinks. Tue Jan 10 04:19:39 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 04:19:39 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 04:19:40 2012 Completed. Tue Jan 10 04:19:40 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:19:40 2012 Completed. Tue Jan 10 04:19:40 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:19:40 2012 Received. Tue Jan 10 04:19:40 2012 Performing 300 seconds pretest warmup. Tue Jan 10 04:24:40 2012 Completed. Tue Jan 10 04:24:40 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:24:40 2012 Completed. Tue Jan 10 04:24:40 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:24:41 2012 Received. Tue Jan 10 04:24:41 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 04:29: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% 26.1% 24867 24867 0 8.02 24.29 0.06 3.5% setattr 4.0% 4.0% 3839 3839 0 146.72 502.22 0.71 9.8% lookup 24.0% 24.0% 22868 22868 0 8.98 121.67 0.14 3.6% readlink 1.0% 1.0% 952 952 0 7.20 17.61 0.27 0.1% read 18.0% 17.8% 16944 20800 0 145.86 479.59 0.33 43.1% write 10.0% 9.9% 9455 11549 0 118.27 527.83 0.46 19.5% create 1.0% 1.1% 1010 1010 0 268.97 613.69 1.53 4.7% remove 1.0% 1.0% 952 952 0 218.52 1041.81 2.05 3.6% readdir 1.0% 1.0% 957 957 0 8.95 33.21 0.37 0.1% fsstat 1.0% 1.0% 955 955 0 8.66 32.10 0.36 0.1% access 11.0% 11.1% 10595 10595 0 9.41 176.39 0.25 1.7% commit 0.0% 0.0% 0 3271 0 0.00 0.00 0.00 9.6% readdirplus 2.0% 2.1% 1998 1998 0 8.55 18.51 0.19 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 319.03 Ops/Sec AVG. RESPONSE TIME: 60.07 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1300 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 95392 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 104613 PHYSICAL NFS V3 THROUGHPUT: 349.87 Ops/Sec FILE SET SIZE CREATED: 159410448.0 KB FILE SET SIZE ACCESSED: 47823984.0 - 47963976.0 KB (100.00% to 100.29% of Base) ------------------------------------------------------------------------ Tue Jan 10 04:29: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 Tue Jan 10 04:29:52 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:29:52 2012 Completed. Tue Jan 10 04:29:52 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 04:29:52 2012 Received. Tue Jan 10 04:29:52 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 04:29:52 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, Tue Jan 10 04:29:54 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 Tue Jan 10 04:29:54 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 Tue Jan 10 04:30:08 2012 Completed. Tue Jan 10 04:30:08 2012 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:30:08 2012 Completed. Tue Jan 10 04:30:08 2012 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:30:10 2012 Received. Tue Jan 10 04:30:10 2012 Initializing test directories. Tue Jan 10 04:30:10 2012 Child 15 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 14 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 1 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 13 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 0 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 2 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 12 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 11 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 3 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 6 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 10 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 4 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 5 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 8 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 7 will create 675 directories. Tue Jan 10 04:30:10 2012 Child 9 will create 675 directories. Tue Jan 10 04:30:12 2012 Child 0 finished creating 675 directories. Tue Jan 10 04:30:12 2012 Child 0 will create 20649 files. Tue Jan 10 04:30:15 2012 Child 13 finished creating 675 directories. Tue Jan 10 04:30:15 2012 Child 13 will create 20649 files. Tue Jan 10 04:30:18 2012 Child 14 finished creating 675 directories. Tue Jan 10 04:30:18 2012 Child 14 will create 20649 files. Tue Jan 10 04:30:19 2012 Child 10 finished creating 675 directories. Tue Jan 10 04:30:19 2012 Child 10 will create 20649 files. Tue Jan 10 04:30:25 2012 Child 12 finished creating 675 directories. Tue Jan 10 04:30:25 2012 Child 12 will create 20649 files. Tue Jan 10 04:30:25 2012 Child 4 finished creating 675 directories. Tue Jan 10 04:30:25 2012 Child 4 will create 20649 files. Tue Jan 10 04:30:26 2012 Child 8 finished creating 675 directories. Tue Jan 10 04:30:26 2012 Child 8 will create 20649 files. Tue Jan 10 04:30:29 2012 Child 7 finished creating 675 directories. Tue Jan 10 04:30:29 2012 Child 7 will create 20649 files. Tue Jan 10 04:30:35 2012 Child 11 finished creating 675 directories. Tue Jan 10 04:30:35 2012 Child 11 will create 20649 files. Tue Jan 10 04:30:36 2012 Child 1 finished creating 675 directories. Tue Jan 10 04:30:36 2012 Child 1 will create 20649 files. Tue Jan 10 04:30:44 2012 Child 2 finished creating 675 directories. Tue Jan 10 04:30:44 2012 Child 2 will create 20649 files. Tue Jan 10 04:30:47 2012 Child 15 finished creating 675 directories. Tue Jan 10 04:30:47 2012 Child 15 will create 20649 files. Tue Jan 10 04:31:13 2012 Child 5 finished creating 675 directories. Tue Jan 10 04:31:13 2012 Child 5 will create 20649 files. Tue Jan 10 04:31:14 2012 Child 9 finished creating 675 directories. Tue Jan 10 04:31:14 2012 Child 9 will create 20649 files. Tue Jan 10 04:31:14 2012 Child 3 finished creating 675 directories. Tue Jan 10 04:31:14 2012 Child 3 will create 20649 files. Tue Jan 10 04:31:18 2012 Child 6 finished creating 675 directories. Tue Jan 10 04:31:18 2012 Child 6 will create 20649 files. Tue Jan 10 04:32:50 2012 Child 0 verified 20649 files, created 1684 Tue Jan 10 04:32:50 2012 Child 0 will create 2 symlinks. Tue Jan 10 04:32:51 2012 Child 0 finished creating 2 symlinks. Tue Jan 10 04:34:23 2012 Child 13 verified 20649 files, created 1684 Tue Jan 10 04:34:23 2012 Child 13 will create 2 symlinks. Tue Jan 10 04:34:39 2012 Child 13 finished creating 2 symlinks. Tue Jan 10 04:34:55 2012 Child 10 verified 20649 files, created 1684 Tue Jan 10 04:34:55 2012 Child 10 will create 2 symlinks. Tue Jan 10 04:34:55 2012 Child 10 finished creating 2 symlinks. Tue Jan 10 04:34:55 2012 Child 14 verified 20649 files, created 1684 Tue Jan 10 04:34:55 2012 Child 14 will create 2 symlinks. Tue Jan 10 04:34:56 2012 Child 14 finished creating 2 symlinks. Tue Jan 10 04:35:26 2012 Child 12 verified 20219 files (1456 creates). Tue Jan 10 04:35:32 2012 Child 7 verified 20155 files (1392 creates). Tue Jan 10 04:35:34 2012 Child 8 verified 20649 files, created 1684 Tue Jan 10 04:35:34 2012 Child 8 will create 2 symlinks. Tue Jan 10 04:35:34 2012 Child 8 finished creating 2 symlinks. Tue Jan 10 04:35:37 2012 Child 11 verified 20056 files (1293 creates). Tue Jan 10 04:35:38 2012 Child 1 verified 20027 files (1264 creates). Tue Jan 10 04:35:43 2012 Child 4 verified 20649 files, created 1684 Tue Jan 10 04:35:43 2012 Child 4 will create 2 symlinks. Tue Jan 10 04:35:43 2012 Child 4 finished creating 2 symlinks. Tue Jan 10 04:35:45 2012 Child 2 verified 19972 files (1209 creates). Tue Jan 10 04:35:46 2012 Child 12 verified 20649 files, created 1684 Tue Jan 10 04:35:46 2012 Child 12 will create 2 symlinks. Tue Jan 10 04:35:46 2012 Child 12 finished creating 2 symlinks. Tue Jan 10 04:35:48 2012 Child 15 verified 20036 files (1273 creates). Tue Jan 10 04:35:56 2012 Child 7 verified 20649 files, created 1684 Tue Jan 10 04:35:56 2012 Child 7 will create 2 symlinks. Tue Jan 10 04:35:56 2012 Child 7 finished creating 2 symlinks. Tue Jan 10 04:36:04 2012 Child 11 verified 20649 files, created 1684 Tue Jan 10 04:36:04 2012 Child 11 will create 2 symlinks. Tue Jan 10 04:36:04 2012 Child 11 finished creating 2 symlinks. Tue Jan 10 04:36:07 2012 Child 1 verified 20649 files, created 1684 Tue Jan 10 04:36:07 2012 Child 1 will create 2 symlinks. Tue Jan 10 04:36:07 2012 Child 1 finished creating 2 symlinks. Tue Jan 10 04:36:09 2012 Child 15 verified 20649 files, created 1684 Tue Jan 10 04:36:09 2012 Child 15 will create 2 symlinks. Tue Jan 10 04:36:09 2012 Child 15 finished creating 2 symlinks. Tue Jan 10 04:36:16 2012 Child 3 verified 20056 files (1293 creates). Tue Jan 10 04:36:16 2012 Child 5 verified 20171 files (1408 creates). Tue Jan 10 04:36:16 2012 Child 9 verified 20056 files (1293 creates). Tue Jan 10 04:36:16 2012 Child 2 verified 20649 files, created 1684 Tue Jan 10 04:36:16 2012 Child 2 will create 2 symlinks. Tue Jan 10 04:36:16 2012 Child 2 finished creating 2 symlinks. Tue Jan 10 04:36:21 2012 Child 6 verified 20213 files (1450 creates). Tue Jan 10 04:36:23 2012 Child 5 verified 20649 files, created 1684 Tue Jan 10 04:36:23 2012 Child 5 will create 2 symlinks. Tue Jan 10 04:36:23 2012 Child 5 finished creating 2 symlinks. Tue Jan 10 04:36:24 2012 Child 6 verified 20649 files, created 1684 Tue Jan 10 04:36:24 2012 Child 6 will create 2 symlinks. Tue Jan 10 04:36:24 2012 Child 6 finished creating 2 symlinks. Tue Jan 10 04:36:30 2012 Child 9 verified 20649 files, created 1684 Tue Jan 10 04:36:30 2012 Child 9 will create 2 symlinks. Tue Jan 10 04:36:30 2012 Child 9 finished creating 2 symlinks. Tue Jan 10 04:36:30 2012 Child 3 verified 20649 files, created 1684 Tue Jan 10 04:36:30 2012 Child 3 will create 2 symlinks. Tue Jan 10 04:36:30 2012 Child 3 finished creating 2 symlinks. Tue Jan 10 04:36:31 2012 Completed. Tue Jan 10 04:36:31 2012 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:36:31 2012 Completed. Tue Jan 10 04:36:31 2012 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:36:32 2012 Received. Tue Jan 10 04:36:32 2012 Performing 300 seconds pretest warmup. Tue Jan 10 04:41:32 2012 Completed. Tue Jan 10 04:41:32 2012 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:41:32 2012 Completed. Tue Jan 10 04:41:32 2012 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:41:33 2012 Received. Tue Jan 10 04:41:33 2012 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Jan 10 04:47: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% 25.6% 12841 12841 0 28.12 65.12 0.14 6.5% setattr 4.0% 4.0% 2007 2007 0 192.83 242.18 0.68 6.9% lookup 24.0% 24.1% 12048 12048 0 30.87 72.52 0.15 6.6% readlink 1.0% 1.0% 518 518 0 28.80 79.67 0.77 0.3% read 18.0% 17.8% 8940 10999 11 277.06 590.16 0.50 44.2% write 10.0% 10.0% 4989 6147 5 230.69 381.67 0.54 20.6% create 1.0% 1.0% 496 496 0 292.90 321.01 1.58 2.6% remove 1.0% 1.0% 525 525 0 199.04 225.93 1.29 1.9% readdir 1.0% 1.1% 556 556 0 28.11 82.77 0.76 0.3% fsstat 1.0% 1.0% 516 516 0 29.36 72.30 0.73 0.3% access 11.0% 11.3% 5636 5636 0 26.93 70.56 0.22 2.7% commit 0.0% 0.0% 0 1704 0 0.00 0.00 0.00 6.4% readdirplus 2.0% 2.0% 1016 1016 0 43.10 86.81 0.57 0.8% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 143.51 Ops/Sec AVG. RESPONSE TIME: 111.77 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1400 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 50088 TEST TIME: 349 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 55009 PHYSICAL NFS V3 THROUGHPUT: 157.61 Ops/Sec FILE SET SIZE CREATED: 171993024.0 KB FILE SET SIZE ACCESSED: 51604704.0 - 51680436.0 KB (100.00% to 100.15% of Base) ------------------------------------------------------------------------ Tue Jan 10 04:47:37 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 Tue Jan 10 04:47:37 2012 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Jan 10 04:47:37 2012 Completed. Tue Jan 10 04:47:37 2012 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Jan 10 04:47:37 2012 Received. Tue Jan 10 04:47:37 2012 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Jan 10 04:47:37 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, Tue Jan 10 04:47:39 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 Tue Jan 10 04:47:39 2012 Mounting 16 remote test directories. RPC: Timed out Unable to contact NFS server vrable2.sysnet.ucsd.edu. Verify NFS server daemon supporting version 3 is running and registered with the portmapper. Mount failed. Exit(145) RPC: Timed out Unable to contact NFS server vrable2.sysnet.ucsd.edu. Verify NFS server daemon supporting version 3 is running and registered with the portmapper. Mount failed. Exit(145) RPC: Timed out Unable to contact NFS server vrable2.sysnet.ucsd.edu. Verify NFS server daemon supporting version 3 is running and registered with the portmapper. Mount failed. Exit(145) RPC: Timed out Unable to contact NFS server vrable2.sysnet.ucsd.edu. Verify NFS server daemon supporting version 3 is running and registered with the portmapper. Mount failed. Exit(145) sfsnfs3: caught unexpected SIGCHLD. A child (PID: 31251) has abnormally exited with status of 145 Tell Prime to stop the benchmark. Terminating all child processes. sfsnfs3: sending Pid 0 Signal 2