1 The following are examples of tcl_cpudist.d.
3 This script traces the on-CPU time of Tcl commands and procedures and
4 prints a report containing distribution plots per subroutine. Here it
5 traces the example program, Code/Tcl/func_slow.tcl.
8 Tracing... Hit Ctrl-C to end.
11 Top 10 exclusive on-CPU times (us),
13 value ------------- Distribution ------------- count
23 PID=16043, cmd, namespace
24 value ------------- Distribution ------------- count
37 value ------------- Distribution ------------- count
39 32 |@@@@@@@@@@@@@@@@@@@@@@@@@@@ 2
45 value ------------- Distribution ------------- count
48 16 |@@@@@@@@@@@@@@@@@@@@ 4
54 PID=16043, cmd, tclInit
55 value ------------- Distribution ------------- count
57 256 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
61 value ------------- Distribution ------------- count
70 PID=16043, cmd, source
71 value ------------- Distribution ------------- count
73 512 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
76 PID=16043, proc, func_a
77 value ------------- Distribution ------------- count
79 524288 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
82 PID=16043, proc, func_b
83 value ------------- Distribution ------------- count
85 1048576 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
88 PID=16043, proc, func_c
89 value ------------- Distribution ------------- count
91 1048576 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
95 Top 10 inclusive on-CPU times (us),
96 PID=16043, cmd, source
97 value ------------- Distribution ------------- count
99 1024 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
102 PID=16043, cmd, uplevel
103 value ------------- Distribution ------------- count
105 1024 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
108 PID=16043, proc, tclInit
109 value ------------- Distribution ------------- count
111 1024 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
114 PID=16043, cmd, tclInit
115 value ------------- Distribution ------------- count
117 2048 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
120 PID=16043, cmd, func_c
121 value ------------- Distribution ------------- count
123 1048576 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
126 PID=16043, proc, func_c
127 value ------------- Distribution ------------- count
129 1048576 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
132 PID=16043, cmd, func_a
133 value ------------- Distribution ------------- count
135 2097152 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
138 PID=16043, cmd, func_b
139 value ------------- Distribution ------------- count
141 2097152 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
144 PID=16043, proc, func_a
145 value ------------- Distribution ------------- count
147 2097152 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
150 PID=16043, proc, func_b
151 value ------------- Distribution ------------- count
153 2097152 |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
156 These on-CPU times are the time the thread spent running on a CPU, from when
157 the subroutine began to when it completed. This does not include time
158 spent off-CPU time such as sleeping for I/O or waiting for scheduling.
160 On-CPU times are useful for showing who is causing the CPUs to be busy.
161 See Notes/ALLoncpu_notes.txt for more details. Also see
162 Notes/ALLexclusive_notes.txt and Notes/ALLinclusive_notes.txt for a
163 detailed explanation of exclusive vs inclusive subroutine time.