mvfstime

Summarizes MVFS activity while a command is executing

APPLICABILITY

SYNOPSIS

mvfstime [ -icrvVhalzAd ] [ -o outfile] [ time ] [ count ] command [ args ]

DESCRIPTION

The mvfstime command executes a command and reports on:

• Timing statistics

• MVFS usage statistics, similar to those generated by mvfsstat.

Use this command to perform timing experiments for applications running in a ClearCase environment.

IMPORTANT: The statistics gathered while command is running under mvfstime are systemwide statistics for that time period and are not limited to that command's activities. To get an accurate reading of the MVFS activity of command, make sure that no other activity is taking place on the machine when you invoke mvfstime.

See the mvfsstat reference page for an explanation of MVFS statistics. See the csh(1) reference page for information on UNIX statistics.

RESTRICTIONS

See the mvfsstat reference page for a description of the restrictions.

OPTIONS AND ARGUMENTS

See the mvfsstat reference page for a description of the command-line options.

EXAMPLES

• Generate timing statistics for an invocation of the clearmake program.

% /usr/atria/etc/mvfstime -iclr clearmake
----------------- Started at Mon Jul 19 10:58:48 1999 -------------------
cp test2.txt file2sub.txt

cp file2sub.txt file2.txt

cat file1.txt > foo
cat file2.txt >> foo

----------------- Ended at Mon Jul 19 10:59:01 1999 -------------------
time: 0.7u 0.9s 0:13 13% 0+0io 0pf+0w
Directory Name Cache: 93 calls
82 ( 88.2%) hit:
24 current directory
0/1 directories ( 0.0%)
42/48 regular files ( 87.5%)
16/26 name not found ( 61.5%)
11 ( 11.8%) miss
0 event misses
17 ( 18.3%) add:
1 directories
6 regular files
10 name not found
Attribute cache: 287 calls
274 ( 95.5%) hit: 2 lvut-generated
13 ( 4.5%) miss:
10 close-to-open
0 build generation mismatch
0 timed out
0 new
3 vob/view event;
0 lvut also missed
56 updates
14 unexpected modifications
7 expected modifications
0 VOB/view cache modifications

Cleartext I/O:
Cleartext layer:
-------clriotype------calls---c/s------rt--rt/call-rt%
get 3 0.23 0.001 0.000 0%
create 5 0.38 0.142 0.028 1%
read 19 1.46 0.017 0.001 0%
write 19 1.46 0.006 0.000 0%
open 12 0.92 0.000 0.000 0%
clrio total: 58 4.46 0.167 1%

MVFS layer:
-------clriotype------calls---c/s------rt--rt/call-rt%--mvfs%
cto_getattr 10 0.77 0.016 0.002 0%
read 19 1.46 0.002 0.000 0% 11%
write 19 1.46 0.002 0.000 0% 25%
get/open/cto 16 1.23 0.216 0.013 2% 92%

Remote calls to view server:
----------rpc-------calls---c/s------rt--rt/call
setattr 18 1.38 0.176 0.010 1%
create 5 0.38 0.102 0.020 1%
rename 2 0.15 0.132 0.066 1%
readdir 1 0.08 0.006 0.006 0%
cltxt 3 0.23 0.012 0.004 0%
chg oid 7 0.54 0.201 0.029 2%
revalidate 1 0.08 0.007 0.007 0%
ch mtype 3 0.23 0.028 0.009 0%
lookup 11 0.85 0.036 0.003 0%
getattr 13 1.00 0.021 0.002 0%
replica root 1 0.08 0.002 0.002 0%
rpc total: 65 5.00 0.722 6% 0 retransmissions

RPC handles:
gets 65
creates 0 ( 0% of gets)
destroys 0
Largest excessive RPC delay: 0 seconds

• Generate timing statistics for an invocation of the omake program.

G:\smg_bld> mvfstime -iclr omake
copy test.txt test
1 file(s) copied.
Child process created on 07/19/1999 at 10:52:20.488
Child process ended on 07/19/1999 at 10:52:25.855
time: 0.1u 0.2s 0:05 6%
Directory Name Cache: 102 calls
88 ( 86.3%) hit:
1 current directory
40/40 directories (100.0%)
26/30 regular files ( 86.7%)
21/28 name not found ( 75.0%)
14 ( 13.7%) miss
1 event misses
11 ( 10.8%) add:
0 directories
4 regular files
7 name not found
Attribute cache: 250 calls
234 ( 93.6%) hit: 0 lvut-generated
16 ( 6.4%) miss:
13 close-to-open
0 build generation mismatch
0 timed out
0 new
3 vob/view event;
0 lvut also missed
45 updates
4 unexpected modifications
4 expected modifications
0 VOB/view cache modifications
Cleartext I/O:
Cleartext layer:
-------clriotype------calls---c/s------rt--rt/call-rt%
get 4 0.72 0.010 0.003 0%
create 2 0.36 0.000 0.000 0%
read 7 1.27 0.000 0.000 0%
write 7 1.27 0.000 0.000 0%
open 13 2.35 0.000 0.000 0%
clrio total: 33 5.97 0.010 0%

MVFS layer:
-------clriotype------calls---c/s------rt--rt/call-rt%--mvfs%
cto_getattr 13 2.35 0.030 0.002 1%
read 7 1.27 0.000 0.000 0% 0%
write 7 1.27 0.000 0.000 0% 0%
get/open/cto 41 7.42 0.060 0.001 1% 60%

Remote calls to view server:
----------rpc-------calls---c/s------rt--rt/call
setattr 5 0.90 0.010 0.002 0%
create 2 0.36 0.020 0.010 0%
remove 1 0.18 0.000 0.000 0%
rename 1 0.18 0.010 0.010 0%
readdir 8 1.45 0.010 0.001 0%
chg oid 7 1.27 0.060 0.009 1%
revalidate 8 1.45 0.010 0.001 0%
ch mtype 1 0.18 0.010 0.010 0%
lookup 6 1.09 0.010 0.002 0%
getattr 16 2.89 0.030 0.002 1%
rpc total: 55 9.95 0.170 3% 0 retransmissions

RPC handles:
gets 55
creates 0 ( 0% of gets)
destroys 0
Largest excessive RPC delay: 0 seconds

SEE ALSO

csh(1), mvfscache, mvfslog, mvfsstat, mvfsstorage, mvfsversion