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 8 -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 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 Mar 15 08:26:06 2011 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 Mar 15 08:26:06 2011 Mounting 8 remote test directories. Child[003]: NFS negotiated block size 32768 Child[004]: NFS negotiated block size 32768 Child[006]: NFS negotiated block size 32768 Child[007]: 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[005]: NFS negotiated block size 32768 Tue Mar 15 08:26:23 2011 Completed. Tue Mar 15 08:26:23 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:26:23 2011 Completed. Tue Mar 15 08:26:23 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:26:23 2011 Received. Tue Mar 15 08:26:23 2011 Initializing test directories. Tue Mar 15 08:26:23 2011 Child 5 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 7 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 3 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 1 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 4 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 6 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 2 will create 190 directories. Tue Mar 15 08:26:23 2011 Child 0 will create 190 directories. Tue Mar 15 08:26:24 2011 Child 7 finished creating 190 directories. Tue Mar 15 08:26:24 2011 Child 7 will create 5793 files. Tue Mar 15 08:26:24 2011 Child 2 finished creating 190 directories. Tue Mar 15 08:26:24 2011 Child 2 will create 5793 files. Tue Mar 15 08:26:24 2011 Child 1 finished creating 190 directories. Tue Mar 15 08:26:24 2011 Child 1 will create 5793 files. Tue Mar 15 08:26:24 2011 Child 4 finished creating 190 directories. Tue Mar 15 08:26:24 2011 Child 4 will create 5793 files. Tue Mar 15 08:26:25 2011 Child 5 finished creating 190 directories. Tue Mar 15 08:26:25 2011 Child 5 will create 5793 files. Tue Mar 15 08:26:25 2011 Child 0 finished creating 190 directories. Tue Mar 15 08:26:25 2011 Child 0 will create 5793 files. Tue Mar 15 08:26:25 2011 Child 6 finished creating 190 directories. Tue Mar 15 08:26:25 2011 Child 6 will create 5793 files. Tue Mar 15 08:26:25 2011 Child 3 finished creating 190 directories. Tue Mar 15 08:26:25 2011 Child 3 will create 5793 files. Tue Mar 15 08:31:27 2011 Child 1 verified 2501 files (2501 creates). Tue Mar 15 08:31:27 2011 Child 7 verified 2487 files (2487 creates). Tue Mar 15 08:31:27 2011 Child 4 verified 2501 files (2501 creates). Tue Mar 15 08:31:27 2011 Child 5 verified 2432 files (2432 creates). Tue Mar 15 08:31:28 2011 Child 2 verified 2468 files (2468 creates). Tue Mar 15 08:31:28 2011 Child 0 verified 2470 files (2470 creates). Tue Mar 15 08:31:28 2011 Child 3 verified 2393 files (2393 creates). Tue Mar 15 08:31:29 2011 Child 6 verified 2471 files (2471 creates). Tue Mar 15 08:36:27 2011 Child 1 verified 4644 files (4644 creates). Tue Mar 15 08:36:27 2011 Child 7 verified 4702 files (4702 creates). Tue Mar 15 08:36:27 2011 Child 4 verified 4630 files (4630 creates). Tue Mar 15 08:36:29 2011 Child 0 verified 4571 files (4571 creates). Tue Mar 15 08:36:29 2011 Child 2 verified 4636 files (4636 creates). Tue Mar 15 08:36:29 2011 Child 6 verified 4642 files (4642 creates). Tue Mar 15 08:36:29 2011 Child 5 verified 4636 files (4636 creates). Tue Mar 15 08:36:29 2011 Child 3 verified 4571 files (4571 creates). Tue Mar 15 08:38:16 2011 Child 7 verified 5793 files, created 5737 Tue Mar 15 08:38:16 2011 Child 7 will create 3 symlinks. Tue Mar 15 08:38:16 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 08:38:16 2011 Child 2 verified 5793 files, created 5737 Tue Mar 15 08:38:16 2011 Child 2 will create 3 symlinks. Tue Mar 15 08:38:16 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 08:38:17 2011 Child 1 verified 5793 files, created 5737 Tue Mar 15 08:38:17 2011 Child 1 will create 3 symlinks. Tue Mar 15 08:38:17 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 08:38:17 2011 Child 5 verified 5793 files, created 5737 Tue Mar 15 08:38:17 2011 Child 5 will create 3 symlinks. Tue Mar 15 08:38:17 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 08:38:17 2011 Child 4 verified 5793 files, created 5737 Tue Mar 15 08:38:17 2011 Child 4 will create 3 symlinks. Tue Mar 15 08:38:17 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 08:38:17 2011 Child 6 verified 5793 files, created 5737 Tue Mar 15 08:38:17 2011 Child 6 will create 3 symlinks. Tue Mar 15 08:38:17 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 08:38:19 2011 Child 0 verified 5793 files, created 5737 Tue Mar 15 08:38:19 2011 Child 0 will create 3 symlinks. Tue Mar 15 08:38:19 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 08:38:19 2011 Child 3 verified 5793 files, created 5737 Tue Mar 15 08:38:19 2011 Child 3 will create 3 symlinks. Tue Mar 15 08:38:19 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 08:38:19 2011 Completed. Tue Mar 15 08:38:19 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:38:19 2011 Completed. Tue Mar 15 08:38:19 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:38:19 2011 Received. Tue Mar 15 08:38:19 2011 Performing 300 seconds pretest warmup. Tue Mar 15 08:43:19 2011 Completed. Tue Mar 15 08:43:19 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:43:19 2011 Completed. Tue Mar 15 08:43:19 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:43:20 2011 Received. Tue Mar 15 08:43:20 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 08:48:30 2011 -------------------------------------------------------------------------------------- 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.3% 15858 15858 0 1.83 38.76 0.10 6.2% setattr 4.0% 4.0% 2382 2382 0 45.37 220.14 0.60 23.1% lookup 24.0% 24.1% 14555 14555 0 1.97 37.65 0.10 6.1% readlink 1.0% 1.0% 585 585 0 0.79 8.80 0.24 0.1% read 18.0% 17.7% 10667 13112 0 7.46 51.01 0.14 17.0% write 10.0% 10.1% 6085 7354 0 7.67 61.16 0.20 10.0% create 1.0% 1.0% 615 615 0 62.71 278.79 1.32 8.3% remove 1.0% 1.0% 605 605 0 44.79 234.70 1.22 5.8% readdir 1.0% 1.0% 593 593 0 2.48 28.24 0.43 0.3% fsstat 1.0% 1.0% 606 606 0 0.26 1.67 0.10 0.0% access 11.0% 10.8% 6489 6489 0 1.79 40.60 0.16 2.5% commit 0.0% 0.0% 0 2687 0 0.00 0.00 0.00 19.4% readdirplus 2.0% 2.1% 1256 1256 0 4.25 55.31 0.41 1.1% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 201.65 Ops/Sec AVG. RESPONSE TIME: 7.74 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 200 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 60296 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 66697 PHYSICAL NFS V3 THROUGHPUT: 223.06 Ops/Sec FILE SET SIZE CREATED: 24124392.0 KB FILE SET SIZE ACCESSED: 7238592.0 - 7383151.0 KB (100.00% to 102.00% of Base) ------------------------------------------------------------------------ Tue Mar 15 08:48:30 2011 Completed. Client Test Parameters: Number of processes = 8 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 = 45432 Files created for I/O operations 13624 Files accessed for I/O operations 912 Files for non-I/O operations 24 Symlinks 1512 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 08:48:30 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:48:30 2011 Completed. Tue Mar 15 08:48:30 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 08:48:31 2011 Received. Tue Mar 15 08:48:31 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 08:48:31 2011 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 8 -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 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 Mar 15 08:48:33 2011 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 Mar 15 08:48:33 2011 Mounting 8 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 Tue Mar 15 08:48:45 2011 Completed. Tue Mar 15 08:48:45 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:48:45 2011 Completed. Tue Mar 15 08:48:45 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 08:48:49 2011 Received. Tue Mar 15 08:48:49 2011 Initializing test directories. Tue Mar 15 08:48:49 2011 Child 5 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 7 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 2 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 4 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 0 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 3 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 1 will create 379 directories. Tue Mar 15 08:48:49 2011 Child 6 will create 379 directories. Tue Mar 15 08:48:52 2011 Child 1 finished creating 379 directories. Tue Mar 15 08:48:52 2011 Child 1 will create 11585 files. Tue Mar 15 08:48:54 2011 Child 4 finished creating 379 directories. Tue Mar 15 08:48:54 2011 Child 4 will create 11585 files. Tue Mar 15 08:48:57 2011 Child 7 finished creating 379 directories. Tue Mar 15 08:48:57 2011 Child 7 will create 11585 files. Tue Mar 15 08:48:57 2011 Child 0 finished creating 379 directories. Tue Mar 15 08:48:57 2011 Child 0 will create 11585 files. Tue Mar 15 08:49:05 2011 Child 2 finished creating 379 directories. Tue Mar 15 08:49:05 2011 Child 2 will create 11585 files. Tue Mar 15 08:49:07 2011 Child 5 finished creating 379 directories. Tue Mar 15 08:49:07 2011 Child 5 will create 11585 files. Tue Mar 15 08:49:36 2011 Child 3 finished creating 379 directories. Tue Mar 15 08:49:36 2011 Child 3 will create 11585 files. Tue Mar 15 08:49:37 2011 Child 6 finished creating 379 directories. Tue Mar 15 08:49:37 2011 Child 6 will create 11585 files. Tue Mar 15 08:53:53 2011 Child 1 verified 7947 files (2268 creates). Tue Mar 15 08:53:55 2011 Child 4 verified 7779 files (2100 creates). Tue Mar 15 08:53:58 2011 Child 7 verified 7745 files (2066 creates). Tue Mar 15 08:53:58 2011 Child 0 verified 7728 files (2049 creates). Tue Mar 15 08:54:06 2011 Child 2 verified 7597 files (1918 creates). Tue Mar 15 08:54:08 2011 Child 5 verified 7600 files (1921 creates). Tue Mar 15 08:54:37 2011 Child 3 verified 7748 files (2069 creates). Tue Mar 15 08:54:39 2011 Child 6 verified 7700 files (2021 creates). Tue Mar 15 08:58:54 2011 Child 1 verified 10294 files (4615 creates). Tue Mar 15 08:58:56 2011 Child 4 verified 10116 files (4437 creates). Tue Mar 15 08:59:01 2011 Child 7 verified 10126 files (4447 creates). Tue Mar 15 08:59:01 2011 Child 0 verified 10111 files (4432 creates). Tue Mar 15 08:59:08 2011 Child 2 verified 9939 files (4260 creates). Tue Mar 15 08:59:09 2011 Child 5 verified 9930 files (4251 creates). Tue Mar 15 08:59:38 2011 Child 3 verified 9991 files (4312 creates). Tue Mar 15 08:59:40 2011 Child 6 verified 10000 files (4321 creates). Tue Mar 15 09:01:20 2011 Child 1 verified 11585 files, created 5793 Tue Mar 15 09:01:20 2011 Child 1 will create 3 symlinks. Tue Mar 15 09:01:21 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 09:01:37 2011 Child 4 verified 11585 files, created 5793 Tue Mar 15 09:01:37 2011 Child 4 will create 3 symlinks. Tue Mar 15 09:01:37 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 09:01:40 2011 Child 0 verified 11585 files, created 5793 Tue Mar 15 09:01:40 2011 Child 0 will create 3 symlinks. Tue Mar 15 09:01:40 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 09:01:40 2011 Child 7 verified 11585 files, created 5793 Tue Mar 15 09:01:40 2011 Child 7 will create 3 symlinks. Tue Mar 15 09:01:41 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 09:01:53 2011 Child 2 verified 11585 files, created 5793 Tue Mar 15 09:01:53 2011 Child 2 will create 3 symlinks. Tue Mar 15 09:01:53 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 09:01:53 2011 Child 5 verified 11585 files, created 5793 Tue Mar 15 09:01:53 2011 Child 5 will create 3 symlinks. Tue Mar 15 09:01:53 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 09:01:56 2011 Child 3 verified 11585 files, created 5793 Tue Mar 15 09:01:56 2011 Child 3 will create 3 symlinks. Tue Mar 15 09:01:56 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 09:01:56 2011 Child 6 verified 11585 files, created 5793 Tue Mar 15 09:01:56 2011 Child 6 will create 3 symlinks. Tue Mar 15 09:01:56 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 09:01:56 2011 Completed. Tue Mar 15 09:01:56 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:01:56 2011 Completed. Tue Mar 15 09:01:56 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:01:56 2011 Received. Tue Mar 15 09:01:56 2011 Performing 300 seconds pretest warmup. Tue Mar 15 09:06:56 2011 Completed. Tue Mar 15 09:06:56 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:06:56 2011 Completed. Tue Mar 15 09:06:56 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:06:57 2011 Received. Tue Mar 15 09:06:57 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 09:12:09 2011 -------------------------------------------------------------------------------------- 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% 26389 26389 0 2.98 30.78 0.07 3.1% setattr 4.0% 3.9% 3940 3940 0 187.72 309.97 0.55 28.8% lookup 24.0% 23.6% 23845 23845 0 3.08 34.89 0.07 2.9% readlink 1.0% 1.0% 1003 1003 0 2.45 14.57 0.24 0.1% read 18.0% 18.2% 18348 22574 0 24.55 59.59 0.11 17.6% write 10.0% 10.1% 10174 12281 0 20.28 63.82 0.16 8.0% create 1.0% 1.0% 1005 1005 0 342.37 451.29 1.31 13.4% remove 1.0% 1.0% 990 990 0 190.67 323.24 1.12 7.4% readdir 1.0% 1.0% 992 992 0 5.54 40.61 0.40 0.2% fsstat 1.0% 1.0% 976 976 0 2.42 12.27 0.22 0.1% access 11.0% 11.2% 11285 11285 0 3.14 33.16 0.11 1.4% commit 0.0% 0.0% 0 4095 0 0.00 0.00 0.00 16.5% readdirplus 2.0% 2.0% 1991 1991 0 6.50 39.63 0.28 0.5% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 335.34 Ops/Sec AVG. RESPONSE TIME: 25.39 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 400 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 100938 TEST TIME: 301 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 111366 PHYSICAL NFS V3 THROUGHPUT: 369.98 Ops/Sec FILE SET SIZE CREATED: 48244536.0 KB FILE SET SIZE ACCESSED: 14477184.0 - 14679550.0 KB (100.00% to 101.40% of Base) ------------------------------------------------------------------------ Tue Mar 15 09:12:09 2011 Completed. Client Test Parameters: Number of processes = 8 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 = 90856 Files created for I/O operations 27256 Files accessed for I/O operations 1824 Files for non-I/O operations 24 Symlinks 3024 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 09:12:09 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:12:09 2011 Completed. Tue Mar 15 09:12:09 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 09:12:09 2011 Received. Tue Mar 15 09:12:09 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 09:12:09 2011 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 8 -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 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 Mar 15 09:12:11 2011 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 Mar 15 09:12:11 2011 Mounting 8 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 Tue Mar 15 09:12:23 2011 Completed. Tue Mar 15 09:12:23 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:12:23 2011 Completed. Tue Mar 15 09:12:23 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:12:27 2011 Received. Tue Mar 15 09:12:27 2011 Initializing test directories. Tue Mar 15 09:12:27 2011 Child 6 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 1 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 7 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 4 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 0 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 2 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 5 will create 577 directories. Tue Mar 15 09:12:27 2011 Child 3 will create 577 directories. Tue Mar 15 09:12:34 2011 Child 2 finished creating 577 directories. Tue Mar 15 09:12:34 2011 Child 2 will create 17628 files. Tue Mar 15 09:12:35 2011 Child 0 finished creating 577 directories. Tue Mar 15 09:12:35 2011 Child 0 will create 17628 files. Tue Mar 15 09:12:40 2011 Child 5 finished creating 577 directories. Tue Mar 15 09:12:40 2011 Child 5 will create 17628 files. Tue Mar 15 09:12:43 2011 Child 3 finished creating 577 directories. Tue Mar 15 09:12:43 2011 Child 3 will create 17628 files. Tue Mar 15 09:12:45 2011 Child 4 finished creating 577 directories. Tue Mar 15 09:12:45 2011 Child 4 will create 17628 files. Tue Mar 15 09:12:50 2011 Child 1 finished creating 577 directories. Tue Mar 15 09:12:50 2011 Child 1 will create 17628 files. Tue Mar 15 09:12:59 2011 Child 7 finished creating 577 directories. Tue Mar 15 09:12:59 2011 Child 7 will create 17628 files. Tue Mar 15 09:13:30 2011 Child 6 finished creating 577 directories. Tue Mar 15 09:13:30 2011 Child 6 will create 17628 files. Tue Mar 15 09:17:35 2011 Child 2 verified 13441 files (2084 creates). Tue Mar 15 09:17:39 2011 Child 0 verified 13362 files (2005 creates). Tue Mar 15 09:17:41 2011 Child 5 verified 13230 files (1873 creates). Tue Mar 15 09:17:44 2011 Child 3 verified 13109 files (1752 creates). Tue Mar 15 09:17:47 2011 Child 4 verified 13055 files (1698 creates). Tue Mar 15 09:17:51 2011 Child 1 verified 13082 files (1725 creates). Tue Mar 15 09:18:00 2011 Child 7 verified 13098 files (1741 creates). Tue Mar 15 09:18:32 2011 Child 6 verified 13044 files (1687 creates). Tue Mar 15 09:22:36 2011 Child 2 verified 15500 files (4143 creates). Tue Mar 15 09:22:40 2011 Child 0 verified 15442 files (4085 creates). Tue Mar 15 09:22:42 2011 Child 5 verified 15271 files (3914 creates). Tue Mar 15 09:22:46 2011 Child 3 verified 15181 files (3824 creates). Tue Mar 15 09:22:48 2011 Child 4 verified 15088 files (3731 creates). Tue Mar 15 09:22:52 2011 Child 1 verified 15110 files (3753 creates). Tue Mar 15 09:23:01 2011 Child 7 verified 15096 files (3739 creates). Tue Mar 15 09:23:34 2011 Child 6 verified 15088 files (3731 creates). Tue Mar 15 09:26:41 2011 Child 2 verified 17628 files, created 6099 Tue Mar 15 09:26:41 2011 Child 2 will create 3 symlinks. Tue Mar 15 09:26:41 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 09:26:49 2011 Child 0 verified 17628 files, created 6099 Tue Mar 15 09:26:49 2011 Child 0 will create 3 symlinks. Tue Mar 15 09:26:49 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 09:27:06 2011 Child 5 verified 17628 files, created 6099 Tue Mar 15 09:27:06 2011 Child 5 will create 3 symlinks. Tue Mar 15 09:27:06 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 09:27:14 2011 Child 3 verified 17628 files, created 6099 Tue Mar 15 09:27:14 2011 Child 3 will create 3 symlinks. Tue Mar 15 09:27:14 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 09:27:17 2011 Child 1 verified 17628 files, created 6099 Tue Mar 15 09:27:17 2011 Child 1 will create 3 symlinks. Tue Mar 15 09:27:17 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 09:27:17 2011 Child 4 verified 17628 files, created 6099 Tue Mar 15 09:27:17 2011 Child 4 will create 3 symlinks. Tue Mar 15 09:27:17 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 09:27:18 2011 Child 7 verified 17628 files, created 6099 Tue Mar 15 09:27:18 2011 Child 7 will create 3 symlinks. Tue Mar 15 09:27:18 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 09:27:22 2011 Child 6 verified 17628 files, created 6099 Tue Mar 15 09:27:22 2011 Child 6 will create 3 symlinks. Tue Mar 15 09:27:22 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 09:27:22 2011 Completed. Tue Mar 15 09:27:22 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:27:22 2011 Completed. Tue Mar 15 09:27:22 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:27:22 2011 Received. Tue Mar 15 09:27:22 2011 Performing 300 seconds pretest warmup. Tue Mar 15 09:32:22 2011 Completed. Tue Mar 15 09:32:22 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:32:22 2011 Completed. Tue Mar 15 09:32:22 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:32:23 2011 Received. Tue Mar 15 09:32:23 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 09:37:35 2011 -------------------------------------------------------------------------------------- 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% 20366 20366 0 3.55 30.75 0.08 2.8% setattr 4.0% 4.0% 3124 3124 0 224.96 346.11 0.65 27.4% lookup 24.0% 24.2% 19110 19110 0 3.61 33.45 0.08 2.7% readlink 1.0% 1.0% 817 817 0 2.63 10.16 0.22 0.1% read 18.0% 17.9% 14126 17310 0 36.46 64.54 0.13 20.1% write 10.0% 10.1% 7972 9657 0 31.17 88.91 0.21 9.7% create 1.0% 1.0% 823 823 0 433.01 485.93 1.51 13.9% remove 1.0% 1.0% 806 806 0 225.82 344.26 1.28 7.1% readdir 1.0% 1.0% 823 823 0 7.70 41.83 0.44 0.2% fsstat 1.0% 1.0% 789 789 0 2.86 8.96 0.21 0.1% access 11.0% 11.0% 8683 8683 0 3.44 27.25 0.11 1.2% commit 0.0% 0.0% 0 2925 0 0.00 0.00 0.00 14.0% readdirplus 2.0% 2.0% 1611 1611 0 10.53 72.02 0.41 0.7% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 263.50 Ops/Sec AVG. RESPONSE TIME: 32.42 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 600 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 79050 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 86844 PHYSICAL NFS V3 THROUGHPUT: 289.48 Ops/Sec FILE SET SIZE CREATED: 73413936.0 KB FILE SET SIZE ACCESSED: 22025880.0 - 22186792.0 KB (100.00% to 100.73% of Base) ------------------------------------------------------------------------ Tue Mar 15 09:37:35 2011 Completed. Client Test Parameters: Number of processes = 8 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 24 Symlinks 4608 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 09:37:35 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:37:35 2011 Completed. Tue Mar 15 09:37:35 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 09:37:35 2011 Received. Tue Mar 15 09:37:35 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 09:37:35 2011 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 8 -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 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 Mar 15 09:37:37 2011 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 Mar 15 09:37:37 2011 Mounting 8 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 Tue Mar 15 09:37:49 2011 Completed. Tue Mar 15 09:37:49 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:37:49 2011 Completed. Tue Mar 15 09:37:49 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:37:53 2011 Received. Tue Mar 15 09:37:53 2011 Initializing test directories. Tue Mar 15 09:37:53 2011 Child 6 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 7 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 5 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 0 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 4 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 3 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 1 will create 766 directories. Tue Mar 15 09:37:53 2011 Child 2 will create 766 directories. Tue Mar 15 09:38:03 2011 Child 2 finished creating 766 directories. Tue Mar 15 09:38:03 2011 Child 2 will create 23420 files. Tue Mar 15 09:38:08 2011 Child 0 finished creating 766 directories. Tue Mar 15 09:38:08 2011 Child 0 will create 23420 files. Tue Mar 15 09:38:16 2011 Child 1 finished creating 766 directories. Tue Mar 15 09:38:16 2011 Child 1 will create 23420 files. Tue Mar 15 09:38:33 2011 Child 3 finished creating 766 directories. Tue Mar 15 09:38:33 2011 Child 3 will create 23420 files. Tue Mar 15 09:38:46 2011 Child 5 finished creating 766 directories. Tue Mar 15 09:38:46 2011 Child 5 will create 23420 files. Tue Mar 15 09:38:50 2011 Child 7 finished creating 766 directories. Tue Mar 15 09:38:50 2011 Child 7 will create 23420 files. Tue Mar 15 09:38:53 2011 Child 4 finished creating 766 directories. Tue Mar 15 09:38:53 2011 Child 4 will create 23420 files. Tue Mar 15 09:39:04 2011 Child 6 finished creating 766 directories. Tue Mar 15 09:39:04 2011 Child 6 will create 23420 files. Tue Mar 15 09:43:04 2011 Child 2 verified 19887 files (2605 creates). Tue Mar 15 09:43:09 2011 Child 0 verified 19539 files (2257 creates). Tue Mar 15 09:43:18 2011 Child 1 verified 19277 files (1995 creates). Tue Mar 15 09:43:34 2011 Child 3 verified 19221 files (1939 creates). Tue Mar 15 09:43:48 2011 Child 5 verified 19233 files (1951 creates). Tue Mar 15 09:43:51 2011 Child 7 verified 19154 files (1872 creates). Tue Mar 15 09:43:54 2011 Child 4 verified 19137 files (1855 creates). Tue Mar 15 09:44:05 2011 Child 6 verified 19140 files (1858 creates). Tue Mar 15 09:48:06 2011 Child 2 verified 22326 files (5044 creates). Tue Mar 15 09:48:11 2011 Child 0 verified 21999 files (4717 creates). Tue Mar 15 09:48:18 2011 Child 1 verified 21782 files (4500 creates). Tue Mar 15 09:48:35 2011 Child 3 verified 21686 files (4404 creates). Tue Mar 15 09:48:49 2011 Child 5 verified 21748 files (4466 creates). Tue Mar 15 09:48:52 2011 Child 7 verified 21649 files (4367 creates). Tue Mar 15 09:48:56 2011 Child 4 verified 21681 files (4399 creates). Tue Mar 15 09:49:08 2011 Child 6 verified 21605 files (4323 creates). Tue Mar 15 09:49:48 2011 Child 2 verified 23420 files, created 5909 Tue Mar 15 09:49:48 2011 Child 2 will create 3 symlinks. Tue Mar 15 09:49:48 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 09:50:28 2011 Child 0 verified 23420 files, created 5909 Tue Mar 15 09:50:28 2011 Child 0 will create 3 symlinks. Tue Mar 15 09:50:28 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 09:51:01 2011 Child 1 verified 23420 files, created 5909 Tue Mar 15 09:51:01 2011 Child 1 will create 3 symlinks. Tue Mar 15 09:51:02 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 09:51:15 2011 Child 3 verified 23420 files, created 5909 Tue Mar 15 09:51:15 2011 Child 3 will create 3 symlinks. Tue Mar 15 09:51:15 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 09:51:16 2011 Child 5 verified 23420 files, created 5909 Tue Mar 15 09:51:16 2011 Child 5 will create 3 symlinks. Tue Mar 15 09:51:16 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 09:51:18 2011 Child 7 verified 23420 files, created 5909 Tue Mar 15 09:51:18 2011 Child 7 will create 3 symlinks. Tue Mar 15 09:51:18 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 09:51:18 2011 Child 4 verified 23420 files, created 5909 Tue Mar 15 09:51:18 2011 Child 4 will create 3 symlinks. Tue Mar 15 09:51:18 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 09:51:22 2011 Child 6 verified 23420 files, created 5909 Tue Mar 15 09:51:22 2011 Child 6 will create 3 symlinks. Tue Mar 15 09:51:22 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 09:51:22 2011 Completed. Tue Mar 15 09:51:22 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:51:22 2011 Completed. Tue Mar 15 09:51:22 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:51:22 2011 Received. Tue Mar 15 09:51:22 2011 Performing 300 seconds pretest warmup. Tue Mar 15 09:56:22 2011 Completed. Tue Mar 15 09:56:22 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:56:22 2011 Completed. Tue Mar 15 09:56:22 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 09:56:23 2011 Received. Tue Mar 15 09:56:23 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 10:01:33 2011 -------------------------------------------------------------------------------------- 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% 17953 17953 0 3.93 33.55 0.08 2.7% setattr 4.0% 4.0% 2808 2808 0 256.29 383.68 0.72 27.9% lookup 24.0% 24.1% 16714 16714 0 3.80 25.85 0.08 2.5% readlink 1.0% 1.0% 691 691 0 3.34 10.75 0.24 0.1% read 18.0% 17.9% 12393 15157 0 47.41 72.19 0.15 22.8% write 10.0% 9.9% 6893 8300 0 32.08 82.88 0.21 8.6% create 1.0% 1.0% 707 707 0 496.53 544.06 1.72 13.6% remove 1.0% 1.0% 677 677 0 251.80 383.16 1.47 6.6% readdir 1.0% 1.0% 686 686 0 6.73 40.04 0.47 0.2% fsstat 1.0% 1.1% 741 741 0 8.46 80.90 0.65 0.2% access 11.0% 11.1% 7718 7718 0 4.21 35.92 0.13 1.3% commit 0.0% 0.0% 0 2447 0 0.00 0.00 0.00 13.0% readdirplus 2.0% 2.0% 1372 1372 0 9.79 55.62 0.39 0.5% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 231.94 Ops/Sec AVG. RESPONSE TIME: 37.15 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 800 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 69353 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 75971 PHYSICAL NFS V3 THROUGHPUT: 254.08 Ops/Sec FILE SET SIZE CREATED: 97534080.0 KB FILE SET SIZE ACCESSED: 29264472.0 - 29407100.0 KB (100.00% to 100.49% of Base) ------------------------------------------------------------------------ Tue Mar 15 10:01:33 2011 Completed. Client Test Parameters: Number of processes = 8 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 24 Symlinks 6120 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 10:01:33 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:01:33 2011 Completed. Tue Mar 15 10:01:33 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 10:01:34 2011 Received. Tue Mar 15 10:01:34 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 10:01:34 2011 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 8 -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 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 Mar 15 10:01:36 2011 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 Mar 15 10:01:36 2011 Mounting 8 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 Tue Mar 15 10:01:48 2011 Completed. Tue Mar 15 10:01:48 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:01:48 2011 Completed. Tue Mar 15 10:01:48 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:01:52 2011 Received. Tue Mar 15 10:01:52 2011 Initializing test directories. Tue Mar 15 10:01:52 2011 Child 6 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 7 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 2 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 4 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 0 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 3 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 5 will create 963 directories. Tue Mar 15 10:01:52 2011 Child 1 will create 963 directories. Tue Mar 15 10:02:08 2011 Child 5 finished creating 963 directories. Tue Mar 15 10:02:08 2011 Child 5 will create 29463 files. Tue Mar 15 10:02:13 2011 Child 6 finished creating 963 directories. Tue Mar 15 10:02:13 2011 Child 6 will create 29463 files. Tue Mar 15 10:02:16 2011 Child 4 finished creating 963 directories. Tue Mar 15 10:02:16 2011 Child 4 will create 29463 files. Tue Mar 15 10:02:22 2011 Child 7 finished creating 963 directories. Tue Mar 15 10:02:22 2011 Child 7 will create 29463 files. Tue Mar 15 10:02:58 2011 Child 3 finished creating 963 directories. Tue Mar 15 10:02:58 2011 Child 3 will create 29463 files. Tue Mar 15 10:03:02 2011 Child 1 finished creating 963 directories. Tue Mar 15 10:03:02 2011 Child 1 will create 29463 files. Tue Mar 15 10:03:09 2011 Child 2 finished creating 963 directories. Tue Mar 15 10:03:09 2011 Child 2 will create 29463 files. Tue Mar 15 10:03:49 2011 Child 0 finished creating 963 directories. Tue Mar 15 10:03:49 2011 Child 0 will create 29463 files. Tue Mar 15 10:07:10 2011 Child 5 verified 25518 files (2558 creates). Tue Mar 15 10:07:15 2011 Child 6 verified 25253 files (2293 creates). Tue Mar 15 10:07:17 2011 Child 4 verified 25237 files (2277 creates). Tue Mar 15 10:07:23 2011 Child 7 verified 25118 files (2158 creates). Tue Mar 15 10:07:59 2011 Child 3 verified 25095 files (2135 creates). Tue Mar 15 10:08:03 2011 Child 1 verified 25015 files (2055 creates). Tue Mar 15 10:08:10 2011 Child 2 verified 25026 files (2066 creates). Tue Mar 15 10:08:50 2011 Child 0 verified 24972 files (2012 creates). Tue Mar 15 10:12:11 2011 Child 5 verified 28093 files (5133 creates). Tue Mar 15 10:12:16 2011 Child 6 verified 27796 files (4836 creates). Tue Mar 15 10:12:21 2011 Child 4 verified 27775 files (4815 creates). Tue Mar 15 10:12:24 2011 Child 7 verified 27669 files (4709 creates). Tue Mar 15 10:13:00 2011 Child 3 verified 27629 files (4669 creates). Tue Mar 15 10:13:04 2011 Child 1 verified 27559 files (4599 creates). Tue Mar 15 10:13:13 2011 Child 2 verified 27633 files (4673 creates). Tue Mar 15 10:13:51 2011 Child 0 verified 27561 files (4601 creates). Tue Mar 15 10:14:14 2011 Child 5 verified 29463 files, created 6215 Tue Mar 15 10:14:14 2011 Child 5 will create 3 symlinks. Tue Mar 15 10:14:16 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 10:14:48 2011 Child 6 verified 29463 files, created 6215 Tue Mar 15 10:14:48 2011 Child 6 will create 3 symlinks. Tue Mar 15 10:14:48 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 10:14:49 2011 Child 4 verified 29463 files, created 6215 Tue Mar 15 10:14:49 2011 Child 4 will create 3 symlinks. Tue Mar 15 10:14:49 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 10:14:57 2011 Child 7 verified 29463 files, created 6215 Tue Mar 15 10:14:57 2011 Child 7 will create 3 symlinks. Tue Mar 15 10:14:57 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 10:15:17 2011 Child 3 verified 29463 files, created 6215 Tue Mar 15 10:15:17 2011 Child 3 will create 3 symlinks. Tue Mar 15 10:15:17 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 10:15:18 2011 Child 1 verified 29463 files, created 6215 Tue Mar 15 10:15:18 2011 Child 1 will create 3 symlinks. Tue Mar 15 10:15:18 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 10:15:19 2011 Child 2 verified 29463 files, created 6215 Tue Mar 15 10:15:19 2011 Child 2 will create 3 symlinks. Tue Mar 15 10:15:19 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 10:15:27 2011 Child 0 verified 29463 files, created 6215 Tue Mar 15 10:15:27 2011 Child 0 will create 3 symlinks. Tue Mar 15 10:15:27 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 10:15:27 2011 Completed. Tue Mar 15 10:15:27 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:15:27 2011 Completed. Tue Mar 15 10:15:27 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:15:27 2011 Received. Tue Mar 15 10:15:27 2011 Performing 300 seconds pretest warmup. Tue Mar 15 10:20:27 2011 Completed. Tue Mar 15 10:20:27 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:20:27 2011 Completed. Tue Mar 15 10:20:27 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:20:28 2011 Received. Tue Mar 15 10:20:28 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 10:25:39 2011 -------------------------------------------------------------------------------------- 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% 16217 16217 0 4.27 33.31 0.09 2.7% setattr 4.0% 4.1% 2553 2553 0 286.57 439.08 0.81 28.4% lookup 24.0% 23.8% 14806 14806 0 4.97 45.28 0.11 2.9% readlink 1.0% 1.0% 610 610 0 3.70 12.00 0.27 0.1% read 18.0% 18.0% 11153 13763 0 54.70 78.57 0.16 23.7% write 10.0% 9.9% 6157 7341 0 33.23 75.71 0.22 8.0% create 1.0% 1.0% 645 645 0 535.85 621.40 1.92 13.4% remove 1.0% 1.0% 629 629 0 277.24 429.54 1.62 6.8% readdir 1.0% 1.0% 629 629 0 7.20 28.71 0.42 0.2% fsstat 1.0% 1.0% 635 635 0 4.79 34.94 0.46 0.1% access 11.0% 11.0% 6844 6844 0 4.55 32.20 0.13 1.2% commit 0.0% 0.0% 0 2164 0 0.00 0.00 0.00 12.2% readdirplus 2.0% 2.0% 1219 1219 0 7.60 36.21 0.34 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 206.99 Ops/Sec AVG. RESPONSE TIME: 41.42 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1000 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 62097 TEST TIME: 300 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 68055 PHYSICAL NFS V3 THROUGHPUT: 226.85 Ops/Sec FILE SET SIZE CREATED: 122703480.0 KB FILE SET SIZE ACCESSED: 36813168.0 - 36941892.0 KB (100.00% to 100.35% of Base) ------------------------------------------------------------------------ ************************************************************************ Tue Mar 15 10:25:39 2011 Completed. Client Test Parameters: Number of processes = 8 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 = 231080 Files created for I/O operations 69320 Files accessed for I/O operations 4624 Files for non-I/O operations 24 Symlinks 7696 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 10:25:39 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:25:39 2011 Completed. Tue Mar 15 10:25:39 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:25:39 2011 Received. Tue Mar 15 10:25:39 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 10:25:39 2011 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 8 -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 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 Mar 15 10:25:41 2011 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 Mar 15 10:25:41 2011 Mounting 8 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 Tue Mar 15 10:25:53 2011 Completed. Tue Mar 15 10:25:53 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:25:53 2011 Completed. Tue Mar 15 10:25:53 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:25:57 2011 Received. Tue Mar 15 10:25:57 2011 Initializing test directories. Tue Mar 15 10:25:57 2011 Child 6 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 7 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 5 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 2 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 3 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 1 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 4 will create 1153 directories. Tue Mar 15 10:25:57 2011 Child 0 will create 1153 directories. Tue Mar 15 10:26:21 2011 Child 3 finished creating 1153 directories. Tue Mar 15 10:26:21 2011 Child 3 will create 35255 files. Tue Mar 15 10:26:24 2011 Child 7 finished creating 1153 directories. Tue Mar 15 10:26:24 2011 Child 7 will create 35255 files. Tue Mar 15 10:26:29 2011 Child 0 finished creating 1153 directories. Tue Mar 15 10:26:29 2011 Child 0 will create 35255 files. Tue Mar 15 10:27:09 2011 Child 1 finished creating 1153 directories. Tue Mar 15 10:27:09 2011 Child 1 will create 35255 files. Tue Mar 15 10:27:10 2011 Child 2 finished creating 1153 directories. Tue Mar 15 10:27:10 2011 Child 2 will create 35255 files. Tue Mar 15 10:27:13 2011 Child 6 finished creating 1153 directories. Tue Mar 15 10:27:13 2011 Child 6 will create 35255 files. Tue Mar 15 10:27:57 2011 Child 4 finished creating 1153 directories. Tue Mar 15 10:27:57 2011 Child 4 will create 35255 files. Tue Mar 15 10:28:10 2011 Child 5 finished creating 1153 directories. Tue Mar 15 10:28:10 2011 Child 5 will create 35255 files. Tue Mar 15 10:31:22 2011 Child 3 verified 31717 files (2832 creates). Tue Mar 15 10:31:26 2011 Child 7 verified 31560 files (2675 creates). Tue Mar 15 10:31:31 2011 Child 0 verified 31404 files (2519 creates). Tue Mar 15 10:32:11 2011 Child 1 verified 31203 files (2318 creates). Tue Mar 15 10:32:12 2011 Child 2 verified 31179 files (2294 creates). Tue Mar 15 10:32:14 2011 Child 6 verified 31108 files (2223 creates). Tue Mar 15 10:33:00 2011 Child 4 verified 30972 files (2087 creates). Tue Mar 15 10:33:11 2011 Child 5 verified 30949 files (2064 creates). Tue Mar 15 10:36:24 2011 Child 3 verified 34373 files (5488 creates). Tue Mar 15 10:36:26 2011 Child 7 verified 34199 files (5314 creates). Tue Mar 15 10:36:32 2011 Child 0 verified 33999 files (5114 creates). Tue Mar 15 10:37:10 2011 Child 3 verified 35255 files, created 6025 Tue Mar 15 10:37:10 2011 Child 3 will create 3 symlinks. Tue Mar 15 10:37:11 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 10:37:12 2011 Child 1 verified 33880 files (4995 creates). Tue Mar 15 10:37:13 2011 Child 2 verified 33837 files (4952 creates). Tue Mar 15 10:37:15 2011 Child 6 verified 33732 files (4847 creates). Tue Mar 15 10:37:28 2011 Child 7 verified 35255 files, created 6025 Tue Mar 15 10:37:28 2011 Child 7 will create 3 symlinks. Tue Mar 15 10:37:28 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 10:37:40 2011 Child 0 verified 35255 files, created 6025 Tue Mar 15 10:37:40 2011 Child 0 will create 3 symlinks. Tue Mar 15 10:37:40 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 10:38:01 2011 Child 4 verified 33790 files (4905 creates). Tue Mar 15 10:38:12 2011 Child 5 verified 33905 files (5020 creates). Tue Mar 15 10:38:13 2011 Child 1 verified 35255 files, created 6025 Tue Mar 15 10:38:13 2011 Child 1 will create 3 symlinks. Tue Mar 15 10:38:13 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 10:38:14 2011 Child 2 verified 35255 files, created 6025 Tue Mar 15 10:38:14 2011 Child 2 will create 3 symlinks. Tue Mar 15 10:38:14 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 10:38:17 2011 Child 6 verified 35255 files, created 6025 Tue Mar 15 10:38:17 2011 Child 6 will create 3 symlinks. Tue Mar 15 10:38:17 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 10:38:29 2011 Child 4 verified 35255 files, created 6025 Tue Mar 15 10:38:29 2011 Child 4 will create 3 symlinks. Tue Mar 15 10:38:29 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 10:38:31 2011 Child 5 verified 35255 files, created 6025 Tue Mar 15 10:38:31 2011 Child 5 will create 3 symlinks. Tue Mar 15 10:38:31 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 10:38:32 2011 Completed. Tue Mar 15 10:38:32 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:38:32 2011 Completed. Tue Mar 15 10:38:32 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:38:33 2011 Received. Tue Mar 15 10:38:33 2011 Performing 300 seconds pretest warmup. Tue Mar 15 10:43:33 2011 Completed. Tue Mar 15 10:43:33 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:43:33 2011 Completed. Tue Mar 15 10:43:33 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:43:34 2011 Received. Tue Mar 15 10:43:34 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 10:48:44 2011 -------------------------------------------------------------------------------------- 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% 15660 15660 0 4.31 35.32 0.09 2.6% setattr 4.0% 4.1% 2438 2438 0 297.01 448.61 0.84 28.1% lookup 24.0% 24.1% 14492 14492 0 5.31 48.38 0.11 3.0% readlink 1.0% 1.0% 619 619 0 4.16 11.38 0.27 0.1% read 18.0% 18.0% 10811 13319 0 59.51 72.04 0.16 25.0% write 10.0% 9.7% 5811 7012 0 36.97 94.11 0.25 8.3% create 1.0% 1.0% 604 604 0 537.29 533.13 1.84 12.6% remove 1.0% 0.9% 549 549 0 293.41 454.80 1.78 6.3% readdir 1.0% 1.0% 611 611 0 6.94 27.00 0.41 0.2% fsstat 1.0% 1.1% 641 641 0 3.61 10.00 0.24 0.1% access 11.0% 11.1% 6661 6661 0 5.23 44.34 0.16 1.4% commit 0.0% 0.0% 0 2006 0 0.00 0.00 0.00 12.1% readdirplus 2.0% 1.9% 1161 1161 0 5.96 25.17 0.29 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 200.86 Ops/Sec AVG. RESPONSE TIME: 42.84 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1200 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 60058 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 65773 PHYSICAL NFS V3 THROUGHPUT: 219.97 Ops/Sec FILE SET SIZE CREATED: 146823616.0 KB FILE SET SIZE ACCESSED: 44047512.0 - 44169948.0 KB (100.00% to 100.28% of Base) ------------------------------------------------------------------------ Tue Mar 15 10:48:44 2011 Completed. Client Test Parameters: Number of processes = 8 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 = 276504 Files created for I/O operations 82944 Files accessed for I/O operations 5536 Files for non-I/O operations 24 Symlinks 9216 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 10:48:44 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:48:44 2011 Completed. Tue Mar 15 10:48:44 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 10:48:45 2011 Received. Tue Mar 15 10:48:45 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 10:48:45 2011 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 8 -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 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 Mar 15 10:48:47 2011 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 Mar 15 10:48:47 2011 Mounting 8 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 Tue Mar 15 10:48:59 2011 Completed. Tue Mar 15 10:48:59 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:48:59 2011 Completed. Tue Mar 15 10:48:59 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 10:49:03 2011 Received. Tue Mar 15 10:49:03 2011 Initializing test directories. Tue Mar 15 10:49:03 2011 Child 5 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 6 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 0 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 1 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 2 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 7 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 4 will create 1350 directories. Tue Mar 15 10:49:03 2011 Child 3 will create 1350 directories. Tue Mar 15 10:49:26 2011 Child 2 finished creating 1350 directories. Tue Mar 15 10:49:26 2011 Child 2 will create 41298 files. Tue Mar 15 10:49:31 2011 Child 6 finished creating 1350 directories. Tue Mar 15 10:49:31 2011 Child 6 will create 41298 files. Tue Mar 15 10:50:08 2011 Child 4 finished creating 1350 directories. Tue Mar 15 10:50:08 2011 Child 4 will create 41298 files. Tue Mar 15 10:50:12 2011 Child 7 finished creating 1350 directories. Tue Mar 15 10:50:12 2011 Child 7 will create 41298 files. Tue Mar 15 10:50:21 2011 Child 3 finished creating 1350 directories. Tue Mar 15 10:50:21 2011 Child 3 will create 41298 files. Tue Mar 15 10:50:55 2011 Child 1 finished creating 1350 directories. Tue Mar 15 10:50:55 2011 Child 1 will create 41298 files. Tue Mar 15 10:50:58 2011 Child 0 finished creating 1350 directories. Tue Mar 15 10:50:58 2011 Child 0 will create 41298 files. Tue Mar 15 10:51:37 2011 Child 5 finished creating 1350 directories. Tue Mar 15 10:51:37 2011 Child 5 will create 41298 files. Tue Mar 15 10:54:27 2011 Child 2 verified 37372 files (2809 creates). Tue Mar 15 10:54:32 2011 Child 6 verified 37028 files (2465 creates). Tue Mar 15 10:55:09 2011 Child 4 verified 36586 files (2023 creates). Tue Mar 15 10:55:15 2011 Child 7 verified 36478 files (1915 creates). Tue Mar 15 10:55:22 2011 Child 3 verified 36329 files (1766 creates). Tue Mar 15 10:55:57 2011 Child 1 verified 36217 files (1654 creates). Tue Mar 15 10:56:00 2011 Child 0 verified 36238 files (1675 creates). Tue Mar 15 10:56:38 2011 Child 5 verified 36165 files (1602 creates). Tue Mar 15 10:59:28 2011 Child 2 verified 39557 files (4994 creates). Tue Mar 15 10:59:34 2011 Child 6 verified 39161 files (4598 creates). Tue Mar 15 11:00:10 2011 Child 4 verified 38777 files (4214 creates). Tue Mar 15 11:00:16 2011 Child 7 verified 38649 files (4086 creates). Tue Mar 15 11:00:24 2011 Child 3 verified 38503 files (3940 creates). Tue Mar 15 11:00:59 2011 Child 1 verified 38431 files (3868 creates). Tue Mar 15 11:01:01 2011 Child 0 verified 38406 files (3843 creates). Tue Mar 15 11:01:39 2011 Child 5 verified 38367 files (3804 creates). Tue Mar 15 11:02:04 2011 Child 2 verified 41298 files, created 6331 Tue Mar 15 11:02:04 2011 Child 2 will create 3 symlinks. Tue Mar 15 11:02:05 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 11:02:54 2011 Child 6 verified 41298 files, created 6331 Tue Mar 15 11:02:54 2011 Child 6 will create 3 symlinks. Tue Mar 15 11:02:54 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 11:03:42 2011 Child 4 verified 41298 files, created 6331 Tue Mar 15 11:03:42 2011 Child 4 will create 3 symlinks. Tue Mar 15 11:03:42 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 11:03:55 2011 Child 7 verified 41298 files, created 6331 Tue Mar 15 11:03:55 2011 Child 7 will create 3 symlinks. Tue Mar 15 11:03:55 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 11:04:03 2011 Child 3 verified 41298 files, created 6331 Tue Mar 15 11:04:03 2011 Child 3 will create 3 symlinks. Tue Mar 15 11:04:03 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 11:04:12 2011 Child 1 verified 41298 files, created 6331 Tue Mar 15 11:04:12 2011 Child 1 will create 3 symlinks. Tue Mar 15 11:04:12 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 11:04:13 2011 Child 0 verified 41298 files, created 6331 Tue Mar 15 11:04:13 2011 Child 0 will create 3 symlinks. Tue Mar 15 11:04:13 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 11:04:18 2011 Child 5 verified 41298 files, created 6331 Tue Mar 15 11:04:18 2011 Child 5 will create 3 symlinks. Tue Mar 15 11:04:18 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 11:04:19 2011 Completed. Tue Mar 15 11:04:19 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:04:19 2011 Completed. Tue Mar 15 11:04:19 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:04:20 2011 Received. Tue Mar 15 11:04:20 2011 Performing 300 seconds pretest warmup. Tue Mar 15 11:09:20 2011 Completed. Tue Mar 15 11:09:20 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:09:20 2011 Completed. Tue Mar 15 11:09:20 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:09:21 2011 Received. Tue Mar 15 11:09:21 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 11:14:31 2011 -------------------------------------------------------------------------------------- NFS V3 Target Actual NFS Op NFS Op NFS Mean Std Dev Std Error Pcnt Op Logical Logical Logical Physical Op Response Response of Mean, of Type Mix Mix Success Success Error Time Time 95% Conf Total Pcnt Pcnt Count Count Count Msec/Op Msec/Op +-Msec/Op Time -------------------------------------------------------------------------------------- getattr 26.0% 25.7% 13843 13843 0 5.07 48.73 0.12 2.7% setattr 4.0% 4.1% 2196 2196 0 326.02 489.23 0.92 27.9% lookup 24.0% 24.1% 13015 13015 0 4.70 30.72 0.10 2.4% readlink 1.0% 1.0% 543 543 0 3.05 8.87 0.25 0.1% read 18.0% 18.1% 9777 12035 0 65.85 77.04 0.17 25.1% write 10.0% 9.9% 5348 6480 0 40.76 97.89 0.27 8.5% create 1.0% 1.0% 514 514 0 635.26 639.38 2.19 12.7% remove 1.0% 1.0% 558 558 0 319.92 456.27 1.77 7.0% readdir 1.0% 1.0% 525 525 0 9.36 78.72 0.76 0.2% fsstat 1.0% 1.0% 556 556 0 8.03 94.37 0.81 0.2% access 11.0% 11.0% 5911 5911 0 6.07 60.53 0.20 1.4% commit 0.0% 0.0% 0 1844 0 0.00 0.00 0.00 11.6% readdirplus 2.0% 2.1% 1109 1109 0 8.12 53.03 0.43 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 180.25 Ops/Sec AVG. RESPONSE TIME: 47.64 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1400 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 53895 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 59129 PHYSICAL NFS V3 THROUGHPUT: 197.75 Ops/Sec FILE SET SIZE CREATED: 171993024.0 KB FILE SET SIZE ACCESSED: 51600456.0 - 51720640.0 KB (100.00% to 100.23% of Base) ------------------------------------------------------------------------ Tue Mar 15 11:14:31 2011 Completed. Client Test Parameters: Number of processes = 8 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 24 Symlinks 10792 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 11:14:31 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:14:31 2011 Completed. Tue Mar 15 11:14:31 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 11:14:32 2011 Received. Tue Mar 15 11:14:32 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 11:14:32 2011 Completed. Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1600 -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 8 -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 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 Mar 15 11:14:34 2011 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 Mar 15 11:14:34 2011 Mounting 8 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 Tue Mar 15 11:14:46 2011 Completed. Tue Mar 15 11:14:46 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:14:46 2011 Completed. Tue Mar 15 11:14:46 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:14:50 2011 Received. Tue Mar 15 11:14:50 2011 Initializing test directories. Tue Mar 15 11:14:50 2011 Child 6 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 0 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 1 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 7 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 4 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 2 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 3 will create 1539 directories. Tue Mar 15 11:14:50 2011 Child 5 will create 1539 directories. Tue Mar 15 11:15:24 2011 Child 5 finished creating 1539 directories. Tue Mar 15 11:15:24 2011 Child 5 will create 47090 files. Tue Mar 15 11:15:35 2011 Child 0 finished creating 1539 directories. Tue Mar 15 11:15:35 2011 Child 0 will create 47090 files. Tue Mar 15 11:15:37 2011 Child 2 finished creating 1539 directories. Tue Mar 15 11:15:37 2011 Child 2 will create 47090 files. Tue Mar 15 11:16:15 2011 Child 7 finished creating 1539 directories. Tue Mar 15 11:16:15 2011 Child 7 will create 47090 files. Tue Mar 15 11:16:20 2011 Child 4 finished creating 1539 directories. Tue Mar 15 11:16:20 2011 Child 4 will create 47090 files. Tue Mar 15 11:16:37 2011 Child 1 finished creating 1539 directories. Tue Mar 15 11:16:37 2011 Child 1 will create 47090 files. Tue Mar 15 11:17:18 2011 Child 6 finished creating 1539 directories. Tue Mar 15 11:17:18 2011 Child 6 will create 47090 files. Tue Mar 15 11:17:40 2011 Child 3 finished creating 1539 directories. Tue Mar 15 11:17:40 2011 Child 3 will create 47090 files. Tue Mar 15 11:20:25 2011 Child 5 verified 43317 files (2829 creates). Tue Mar 15 11:20:36 2011 Child 0 verified 42842 files (2354 creates). Tue Mar 15 11:20:39 2011 Child 2 verified 42810 files (2322 creates). Tue Mar 15 11:21:17 2011 Child 7 verified 42549 files (2061 creates). Tue Mar 15 11:21:22 2011 Child 4 verified 42423 files (1935 creates). Tue Mar 15 11:21:38 2011 Child 1 verified 42237 files (1749 creates). Tue Mar 15 11:22:19 2011 Child 6 verified 42206 files (1718 creates). Tue Mar 15 11:22:41 2011 Child 3 verified 42182 files (1694 creates). Tue Mar 15 11:25:26 2011 Child 5 verified 45847 files (5359 creates). Tue Mar 15 11:25:38 2011 Child 0 verified 45347 files (4859 creates). Tue Mar 15 11:25:40 2011 Child 2 verified 45341 files (4853 creates). Tue Mar 15 11:26:18 2011 Child 7 verified 45084 files (4596 creates). Tue Mar 15 11:26:24 2011 Child 4 verified 44971 files (4483 creates). Tue Mar 15 11:26:35 2011 Child 5 verified 47090 files, created 6141 Tue Mar 15 11:26:35 2011 Child 5 will create 3 symlinks. Tue Mar 15 11:26:35 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 11:26:40 2011 Child 1 verified 44816 files (4328 creates). Tue Mar 15 11:27:20 2011 Child 6 verified 44905 files (4417 creates). Tue Mar 15 11:27:28 2011 Child 2 verified 47090 files, created 6141 Tue Mar 15 11:27:28 2011 Child 2 will create 3 symlinks. Tue Mar 15 11:27:29 2011 Child 0 verified 47090 files, created 6141 Tue Mar 15 11:27:29 2011 Child 0 will create 3 symlinks. Tue Mar 15 11:27:29 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 11:27:29 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 11:27:42 2011 Child 3 verified 44874 files (4386 creates). Tue Mar 15 11:28:10 2011 Child 7 verified 47090 files, created 6141 Tue Mar 15 11:28:10 2011 Child 7 will create 3 symlinks. Tue Mar 15 11:28:10 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 11:28:13 2011 Child 4 verified 47090 files, created 6141 Tue Mar 15 11:28:13 2011 Child 4 will create 3 symlinks. Tue Mar 15 11:28:13 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 11:28:21 2011 Child 1 verified 47090 files, created 6141 Tue Mar 15 11:28:21 2011 Child 1 will create 3 symlinks. Tue Mar 15 11:28:21 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 11:28:30 2011 Child 6 verified 47090 files, created 6141 Tue Mar 15 11:28:30 2011 Child 6 will create 3 symlinks. Tue Mar 15 11:28:30 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 11:28:34 2011 Child 3 verified 47090 files, created 6141 Tue Mar 15 11:28:34 2011 Child 3 will create 3 symlinks. Tue Mar 15 11:28:34 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 11:28:35 2011 Completed. Tue Mar 15 11:28:35 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:28:35 2011 Completed. Tue Mar 15 11:28:35 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:28:36 2011 Received. Tue Mar 15 11:28:36 2011 Performing 300 seconds pretest warmup. Tue Mar 15 11:33:36 2011 Completed. Tue Mar 15 11:33:36 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:33:36 2011 Completed. Tue Mar 15 11:33:36 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:33:37 2011 Received. Tue Mar 15 11:33:37 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 11:38:47 2011 -------------------------------------------------------------------------------------- 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% 13836 13836 0 4.68 37.74 0.10 2.5% setattr 4.0% 4.0% 2148 2148 0 335.83 483.11 0.93 28.3% lookup 24.0% 24.4% 12964 12964 0 5.86 49.89 0.12 3.0% readlink 1.0% 0.9% 502 502 0 5.72 30.22 0.48 0.1% read 18.0% 17.6% 9376 11432 0 67.42 79.73 0.18 24.8% write 10.0% 10.0% 5331 6461 0 40.40 84.68 0.25 8.5% create 1.0% 1.0% 510 510 0 648.25 683.34 2.27 13.0% remove 1.0% 0.9% 479 479 0 326.95 494.79 1.99 6.2% readdir 1.0% 1.0% 517 517 0 6.40 39.62 0.54 0.1% fsstat 1.0% 1.0% 520 520 0 4.27 10.24 0.28 0.1% access 11.0% 11.1% 5896 5896 0 5.23 41.62 0.16 1.2% commit 0.0% 0.0% 0 1826 0 0.00 0.00 0.00 11.8% readdirplus 2.0% 2.0% 1070 1070 0 9.24 58.59 0.46 0.4% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 178.35 Ops/Sec AVG. RESPONSE TIME: 47.89 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1600 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 53149 TEST TIME: 298 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 58161 PHYSICAL NFS V3 THROUGHPUT: 195.17 Ops/Sec FILE SET SIZE CREATED: 196113168.0 KB FILE SET SIZE ACCESSED: 58834800.0 - 58958580.0 KB (100.00% to 100.21% of Base) ------------------------------------------------------------------------ Tue Mar 15 11:38:47 2011 Completed. Client Test Parameters: Number of processes = 8 Requested Load (NFS V3 operations/second) = 1600 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 = 369328 Files created for I/O operations 110792 Files accessed for I/O operations 7392 Files for non-I/O operations 24 Symlinks 12304 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 11:38:47 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:38:47 2011 Completed. Tue Mar 15 11:38:47 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 11:38:48 2011 Received. Tue Mar 15 11:38:48 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 11:38:48 2011 Completed. Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 1800 -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 8 -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 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 Mar 15 11:38:50 2011 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 Mar 15 11:38:50 2011 Mounting 8 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 Tue Mar 15 11:39:02 2011 Completed. Tue Mar 15 11:39:02 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:39:02 2011 Completed. Tue Mar 15 11:39:02 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:39:06 2011 Received. Tue Mar 15 11:39:06 2011 Initializing test directories. Tue Mar 15 11:39:06 2011 Child 7 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 6 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 5 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 2 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 3 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 4 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 1 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 0 will create 1729 directories. Tue Mar 15 11:39:06 2011 Child 5 finished creating 1729 directories. Tue Mar 15 11:39:06 2011 Child 5 will create 52881 files. Tue Mar 15 11:39:16 2011 Child 2 finished creating 1729 directories. Tue Mar 15 11:39:16 2011 Child 2 will create 52881 files. Tue Mar 15 11:40:10 2011 Child 6 finished creating 1729 directories. Tue Mar 15 11:40:10 2011 Child 6 will create 52881 files. Tue Mar 15 11:40:56 2011 Child 0 finished creating 1729 directories. Tue Mar 15 11:40:56 2011 Child 0 will create 52881 files. Tue Mar 15 11:41:03 2011 Child 4 finished creating 1729 directories. Tue Mar 15 11:41:03 2011 Child 4 will create 52881 files. Tue Mar 15 11:41:20 2011 Child 7 finished creating 1729 directories. Tue Mar 15 11:41:20 2011 Child 7 will create 52881 files. Tue Mar 15 11:41:50 2011 Child 3 finished creating 1729 directories. Tue Mar 15 11:41:50 2011 Child 3 will create 52881 files. Tue Mar 15 11:41:57 2011 Child 1 finished creating 1729 directories. Tue Mar 15 11:41:57 2011 Child 1 will create 52881 files. Tue Mar 15 11:44:08 2011 Child 5 verified 50893 files (4727 creates). Tue Mar 15 11:44:17 2011 Child 2 verified 50217 files (4051 creates). Tue Mar 15 11:45:11 2011 Child 6 verified 48870 files (2704 creates). Tue Mar 15 11:45:58 2011 Child 0 verified 48259 files (2093 creates). Tue Mar 15 11:46:05 2011 Child 4 verified 48183 files (2017 creates). Tue Mar 15 11:46:21 2011 Child 7 verified 48001 files (1835 creates). Tue Mar 15 11:46:50 2011 Child 5 verified 52881 files, created 6197 Tue Mar 15 11:46:50 2011 Child 5 will create 3 symlinks. Tue Mar 15 11:46:50 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 11:46:51 2011 Child 3 verified 48059 files (1893 creates). Tue Mar 15 11:46:58 2011 Child 1 verified 48019 files (1853 creates). Tue Mar 15 11:48:15 2011 Child 2 verified 52881 files, created 6197 Tue Mar 15 11:48:15 2011 Child 2 will create 3 symlinks. Tue Mar 15 11:48:16 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 11:50:13 2011 Child 6 verified 51757 files (5591 creates). Tue Mar 15 11:50:49 2011 Child 6 verified 52881 files, created 6197 Tue Mar 15 11:50:49 2011 Child 6 will create 3 symlinks. Tue Mar 15 11:50:49 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 11:50:59 2011 Child 0 verified 51380 files (5214 creates). Tue Mar 15 11:51:06 2011 Child 4 verified 51369 files (5203 creates). Tue Mar 15 11:51:22 2011 Child 7 verified 51204 files (5038 creates). Tue Mar 15 11:51:51 2011 Child 0 verified 52881 files, created 6197 Tue Mar 15 11:51:51 2011 Child 0 will create 3 symlinks. Tue Mar 15 11:51:52 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 11:51:52 2011 Child 3 verified 51503 files (5337 creates). Tue Mar 15 11:51:57 2011 Child 4 verified 52881 files, created 6197 Tue Mar 15 11:51:57 2011 Child 4 will create 3 symlinks. Tue Mar 15 11:51:57 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 11:51:59 2011 Child 1 verified 51600 files (5434 creates). Tue Mar 15 11:52:11 2011 Child 7 verified 52881 files, created 6197 Tue Mar 15 11:52:11 2011 Child 7 will create 3 symlinks. Tue Mar 15 11:52:11 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 11:52:14 2011 Child 3 verified 52881 files, created 6197 Tue Mar 15 11:52:14 2011 Child 3 will create 3 symlinks. Tue Mar 15 11:52:14 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 11:52:14 2011 Child 1 verified 52881 files, created 6197 Tue Mar 15 11:52:14 2011 Child 1 will create 3 symlinks. Tue Mar 15 11:52:15 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 11:52:15 2011 Completed. Tue Mar 15 11:52:15 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:52:15 2011 Completed. Tue Mar 15 11:52:15 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:52:15 2011 Received. Tue Mar 15 11:52:15 2011 Performing 300 seconds pretest warmup. Tue Mar 15 11:57:15 2011 Completed. Tue Mar 15 11:57:15 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:57:15 2011 Completed. Tue Mar 15 11:57:15 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 11:57:16 2011 Received. Tue Mar 15 11:57:16 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 12:02:26 2011 -------------------------------------------------------------------------------------- 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.3% 14152 14152 0 4.25 23.40 0.08 2.3% setattr 4.0% 4.0% 2129 2129 0 347.45 509.08 0.96 28.7% lookup 24.0% 23.7% 12724 12724 0 5.13 37.43 0.11 2.5% readlink 1.0% 1.0% 560 560 0 4.42 12.27 0.29 0.1% read 18.0% 18.2% 9756 12094 0 71.93 83.49 0.18 27.2% write 10.0% 9.9% 5293 6297 0 38.16 71.33 0.23 7.8% create 1.0% 0.9% 508 508 0 554.04 539.29 2.02 10.9% remove 1.0% 1.0% 551 551 0 335.54 497.85 1.86 7.2% readdir 1.0% 1.0% 535 535 0 8.86 58.83 0.65 0.2% fsstat 1.0% 1.0% 515 515 0 3.88 10.96 0.29 0.1% access 11.0% 11.0% 5926 5926 0 5.25 35.54 0.15 1.2% commit 0.0% 0.0% 0 1815 0 0.00 0.00 0.00 11.4% readdirplus 2.0% 2.0% 1080 1080 0 6.97 29.21 0.32 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 179.69 Ops/Sec AVG. RESPONSE TIME: 47.97 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 1800 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 53729 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 58886 PHYSICAL NFS V3 THROUGHPUT: 196.94 Ops/Sec FILE SET SIZE CREATED: 220233312.0 KB FILE SET SIZE ACCESSED: 66073392.0 - 66190936.0 KB (100.00% to 100.18% of Base) ------------------------------------------------------------------------ Tue Mar 15 12:02:26 2011 Completed. Client Test Parameters: Number of processes = 8 Requested Load (NFS V3 operations/second) = 1800 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 = 414752 Files created for I/O operations 124424 Files accessed for I/O operations 8296 Files for non-I/O operations 24 Symlinks 13824 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 12:02:26 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:02:26 2011 Completed. Tue Mar 15 12:02:26 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 12:02:27 2011 Received. Tue Mar 15 12:02:27 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 12:02:27 2011 Completed. Started on client (c09-44): ../binaries/linux-x86/sfsnfs3 -N 1 -l 2000 -t 300 -a 30 -A 70 -R 8 -W 8 -D 30 -S 20 -Q -p 8 -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 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 Mar 15 12:02:29 2011 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 Mar 15 12:02:29 2011 Mounting 8 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 Tue Mar 15 12:02:41 2011 Completed. Tue Mar 15 12:02:41 2011 Sending DONE-MOUNT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:02:41 2011 Completed. Tue Mar 15 12:02:41 2011 Waiting on DO-INIT message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:02:45 2011 Received. Tue Mar 15 12:02:45 2011 Initializing test directories. Tue Mar 15 12:02:45 2011 Child 7 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 5 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 4 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 1 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 6 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 3 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 0 will create 1926 directories. Tue Mar 15 12:02:45 2011 Child 2 will create 1926 directories. Tue Mar 15 12:02:49 2011 Child 1 finished creating 1926 directories. Tue Mar 15 12:02:49 2011 Child 1 will create 58925 files. Tue Mar 15 12:03:06 2011 Child 7 finished creating 1926 directories. Tue Mar 15 12:03:06 2011 Child 7 will create 58925 files. Tue Mar 15 12:03:36 2011 Child 2 finished creating 1926 directories. Tue Mar 15 12:03:36 2011 Child 2 will create 58925 files. Tue Mar 15 12:03:48 2011 Child 4 finished creating 1926 directories. Tue Mar 15 12:03:48 2011 Child 4 will create 58925 files. Tue Mar 15 12:04:25 2011 Child 3 finished creating 1926 directories. Tue Mar 15 12:04:25 2011 Child 3 will create 58925 files. Tue Mar 15 12:04:49 2011 Child 5 finished creating 1926 directories. Tue Mar 15 12:04:49 2011 Child 5 will create 58925 files. Tue Mar 15 12:05:36 2011 Child 0 finished creating 1926 directories. Tue Mar 15 12:05:36 2011 Child 0 will create 58925 files. Tue Mar 15 12:05:40 2011 Child 6 finished creating 1926 directories. Tue Mar 15 12:05:40 2011 Child 6 will create 58925 files. Tue Mar 15 12:07:50 2011 Child 1 verified 55480 files (3636 creates). Tue Mar 15 12:08:08 2011 Child 7 verified 54679 files (2835 creates). Tue Mar 15 12:08:42 2011 Child 2 verified 54374 files (2530 creates). Tue Mar 15 12:08:49 2011 Child 4 verified 54122 files (2278 creates). Tue Mar 15 12:09:27 2011 Child 3 verified 53831 files (1987 creates). Tue Mar 15 12:09:52 2011 Child 5 verified 53786 files (1942 creates). Tue Mar 15 12:10:37 2011 Child 0 verified 53496 files (1652 creates). Tue Mar 15 12:10:41 2011 Child 6 verified 53486 files (1642 creates). Tue Mar 15 12:13:06 2011 Child 1 verified 58925 files, created 6504 Tue Mar 15 12:13:06 2011 Child 1 will create 3 symlinks. Tue Mar 15 12:13:06 2011 Child 1 finished creating 3 symlinks. Tue Mar 15 12:13:12 2011 Child 7 verified 57240 files (5396 creates). Tue Mar 15 12:13:45 2011 Child 2 verified 57004 files (5160 creates). Tue Mar 15 12:13:50 2011 Child 4 verified 56638 files (4794 creates). Tue Mar 15 12:14:28 2011 Child 3 verified 56498 files (4654 creates). Tue Mar 15 12:14:38 2011 Child 7 verified 58925 files, created 6504 Tue Mar 15 12:14:38 2011 Child 7 will create 3 symlinks. Tue Mar 15 12:14:38 2011 Child 7 finished creating 3 symlinks. Tue Mar 15 12:14:52 2011 Child 5 verified 56534 files (4690 creates). Tue Mar 15 12:15:18 2011 Child 2 verified 58925 files, created 6504 Tue Mar 15 12:15:18 2011 Child 2 will create 3 symlinks. Tue Mar 15 12:15:18 2011 Child 2 finished creating 3 symlinks. Tue Mar 15 12:15:37 2011 Child 4 verified 58925 files, created 6504 Tue Mar 15 12:15:37 2011 Child 4 will create 3 symlinks. Tue Mar 15 12:15:37 2011 Child 4 finished creating 3 symlinks. Tue Mar 15 12:15:38 2011 Child 0 verified 56525 files (4681 creates). Tue Mar 15 12:15:42 2011 Child 6 verified 56651 files (4807 creates). Tue Mar 15 12:16:01 2011 Child 3 verified 58925 files, created 6504 Tue Mar 15 12:16:01 2011 Child 3 will create 3 symlinks. Tue Mar 15 12:16:02 2011 Child 3 finished creating 3 symlinks. Tue Mar 15 12:16:10 2011 Child 5 verified 58925 files, created 6504 Tue Mar 15 12:16:10 2011 Child 5 will create 3 symlinks. Tue Mar 15 12:16:10 2011 Child 5 finished creating 3 symlinks. Tue Mar 15 12:16:21 2011 Child 6 verified 58925 files, created 6504 Tue Mar 15 12:16:21 2011 Child 6 will create 3 symlinks. Tue Mar 15 12:16:21 2011 Child 6 finished creating 3 symlinks. Tue Mar 15 12:16:21 2011 Child 0 verified 58925 files, created 6504 Tue Mar 15 12:16:21 2011 Child 0 will create 3 symlinks. Tue Mar 15 12:16:21 2011 Child 0 finished creating 3 symlinks. Tue Mar 15 12:16:22 2011 Completed. Tue Mar 15 12:16:22 2011 Sending DONE-INIT message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:16:22 2011 Completed. Tue Mar 15 12:16:22 2011 Waiting on DO-WARMUP message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:16:23 2011 Received. Tue Mar 15 12:16:23 2011 Performing 300 seconds pretest warmup. Tue Mar 15 12:21:23 2011 Completed. Tue Mar 15 12:21:23 2011 Sending READY message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:21:23 2011 Completed. Tue Mar 15 12:21:23 2011 Waiting on START message from Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:21:24 2011 Received. Tue Mar 15 12:21:24 2011 Starting 300 seconds test run. SFS Single Client (c09-44) Results, Tue Mar 15 12:26:34 2011 -------------------------------------------------------------------------------------- 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% 13080 13080 0 5.27 49.49 0.12 2.7% setattr 4.0% 4.0% 2019 2019 0 345.20 503.58 0.98 27.2% lookup 24.0% 23.7% 11898 11898 0 5.90 46.05 0.12 2.7% readlink 1.0% 0.9% 474 474 0 3.21 10.59 0.29 0.1% read 18.0% 18.0% 9038 11165 0 75.27 86.52 0.19 26.5% write 10.0% 10.1% 5059 6126 0 41.54 87.48 0.26 8.2% create 1.0% 1.0% 522 522 0 693.53 680.23 2.24 14.1% remove 1.0% 1.0% 479 479 0 300.77 434.61 1.87 5.6% readdir 1.0% 1.0% 484 484 0 4.32 10.60 0.29 0.1% fsstat 1.0% 1.0% 509 509 0 6.67 44.72 0.58 0.1% access 11.0% 11.3% 5662 5662 0 7.03 63.04 0.21 1.6% commit 0.0% 0.0% 0 1734 0 0.00 0.00 0.00 10.8% readdirplus 2.0% 2.0% 998 998 0 7.54 33.17 0.36 0.3% -------------------------------------------------------------------------------------- -------------------------------------------------- | SPEC SFS 2008 SINGLE CLIENT RESULTS SUMMARY | --------------------------+----------------------- SFS NFS V3 THROUGHPUT: 167.96 Ops/Sec AVG. RESPONSE TIME: 51.03 Msec/Op TCP PROTOCOL FAST CALLS: 0 NFS V3 MIXFILE: [ SFS Default ] CLIENT REQUESTED LOAD: 2000 Ops/Sec TOTAL LOGICAL NFS V3 OPERATIONS: 50222 TEST TIME: 299 Sec TOTAL PHYSICAL NFS V3 OPERATIONS: 55150 PHYSICAL NFS V3 THROUGHPUT: 184.44 Ops/Sec FILE SET SIZE CREATED: 245402720.0 KB FILE SET SIZE ACCESSED: 73622088.0 - 73731952.0 KB (100.00% to 100.15% of Base) ------------------------------------------------------------------------ Tue Mar 15 12:26:34 2011 Completed. Client Test Parameters: Number of processes = 8 Requested Load (NFS V3 operations/second) = 2000 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 = 462152 Files created for I/O operations 138640 Files accessed for I/O operations 9248 Files for non-I/O operations 24 Symlinks 15400 Directories Additional non-I/O files created as necessary SPEC SFS 2008 Benchmark, Creation - 1 February 2008 Tue Mar 15 12:26:34 2011 Sending DONE-TEST message to Prime Client(c09-44.sysnet.ucsd.edu). Tue Mar 15 12:26:34 2011 Completed. Tue Mar 15 12:26:34 2011 Waiting on MOVE-DATA message from Prime Client(c09-44.sysnet.ucsd.edu). ************************************************************************ Tue Mar 15 12:26:35 2011 Received. Tue Mar 15 12:26:35 2011 Sending results to Prime Client(c09-44.sysnet.ucsd.edu) Tue Mar 15 12:26:35 2011 Completed.