author | Claus Gittinger <cg@exept.de> |
Wed, 15 May 2019 14:19:15 +0200 | |
changeset 4426 | a82374e79e97 |
parent 4096 | 5535543c5db8 |
child 4097 | 6553a6479595 |
child 4433 | 0db54b55fc65 |
permissions | -rw-r--r-- |
4096 | 1 |
"{ Encoding: utf8 }" |
2 |
||
0 | 3 |
" |
21 | 4 |
COPYRIGHT (c) 1995 by Claus Gittinger |
5 |
All Rights Reserved |
|
0 | 6 |
|
7 |
This software is furnished under a license and may be used |
|
8 |
only in accordance with the terms of that license and with the |
|
9 |
inclusion of the above copyright notice. This software may not |
|
10 |
be provided or otherwise made available to, or used by, any |
|
11 |
other person. No title to or ownership of the software is |
|
12 |
hereby transferred. |
|
13 |
" |
|
957 | 14 |
"{ Package: 'stx:libbasic3' }" |
15 |
||
4013 | 16 |
"{ NameSpace: Smalltalk }" |
17 |
||
120 | 18 |
Object subclass:#MessageTally |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
19 |
instanceVariableNames:'tree probes ntally theBlock spyInterval executing startTime |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
20 |
endTime' |
236 | 21 |
classVariableNames:'' |
22 |
poolDictionaries:'' |
|
23 |
category:'System-Debugging-Support' |
|
0 | 24 |
! |
25 |
||
9 | 26 |
!MessageTally class methodsFor:'documentation'! |
27 |
||
88 | 28 |
copyright |
29 |
" |
|
30 |
COPYRIGHT (c) 1995 by Claus Gittinger |
|
31 |
All Rights Reserved |
|
32 |
||
33 |
This software is furnished under a license and may be used |
|
34 |
only in accordance with the terms of that license and with the |
|
35 |
inclusion of the above copyright notice. This software may not |
|
36 |
be provided or otherwise made available to, or used by, any |
|
37 |
other person. No title to or ownership of the software is |
|
38 |
hereby transferred. |
|
39 |
" |
|
9 | 40 |
! |
41 |
||
42 |
documentation |
|
43 |
" |
|
44 |
MessageTally allows profiling excution of a block; |
|
45 |
statistic of method evaluation is output on Transcript. |
|
46 |
To get statistic, use 'MessageTally spyOn:aBlock'. |
|
47 |
||
21 | 48 |
By default, probing is done every 10ms (i.e. the execution of the block is |
49 |
interrupted every 10ms, and the context chain analyzed). |
|
260 | 50 |
|
21 | 51 |
For better resolution, use smaller clock ticks (if your OperatingSystem |
260 | 52 |
supports it). Try 'spyDetailedOn:aBlock', which tries to measure things |
53 |
every 1ms. |
|
54 |
(Notice, that some OS's do not provide this timer resolution, |
|
55 |
so measuring may be less accurate.) |
|
56 |
||
57 |
For good results, make certain that the measured block runs for some |
|
58 |
time (say 5 seconds) - add a timesRepeat-loop around it if required. |
|
236 | 59 |
|
561 | 60 |
The displayed information is: |
61 |
- the calling tree augmented with total and leaf times. |
|
62 |
the leaf time is the time spent in the method itself; |
|
63 |
the total time is the time spent in the method and all of its called |
|
64 |
methods. |
|
65 |
||
66 |
- the leaf methods by receiver |
|
67 |
this lists the leaf nodes only, sorted by time spent there. |
|
68 |
Here, method invocations for different receiver types are |
|
69 |
listed separately. |
|
70 |
||
71 |
- the leaf methods |
|
72 |
this lists the leaf nodes only, sorted by time spent there. |
|
73 |
Here, method invocations for different receiver types are |
|
74 |
summed up separately. |
|
75 |
||
594 | 76 |
The last list (leaf methods) is propably the most interesting; |
77 |
if you are only interested in that (or the calling hierarchy is too |
|
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
78 |
deep for the list to be useful or the amount of data to be handled |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
79 |
correctly), use a leaf-spy with: |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
80 |
MessageTally spyLeafOn:aBlock |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
81 |
This only accumulates statistics about methods where the cpu time |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
82 |
is actually spent (not collecing hierarchy information). |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
83 |
|
561 | 84 |
|
236 | 85 |
[author:] |
86 |
Claus Gittinger |
|
260 | 87 |
|
88 |
[see also:] |
|
261 | 89 |
CallChain ProfileTree |
90 |
MessageTracer |
|
21 | 91 |
" |
92 |
! |
|
93 |
||
94 |
examples |
|
9 | 95 |
" |
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
96 |
the block must execute for a while; |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
97 |
otherwise, no probes (and therefore no statistics) can |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
98 |
be gathered: |
244 | 99 |
[exBegin] |
4013 | 100 |
MessageTally spyOn:[ #(6 5 4 3 2 1) copy sort ] |
244 | 101 |
[exEnd] |
102 |
||
103 |
||
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
104 |
if required, execute the block in a loop; |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
105 |
however, for the example below, a larger repeat count |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
106 |
is required, for a reasonable measurement: |
244 | 107 |
[exBegin] |
259 | 108 |
MessageTally spyOn:[ |
4013 | 109 |
10000 timesRepeat:[ #(6 5 4 3 2 1) copy sort] |
259 | 110 |
] |
244 | 111 |
[exEnd] |
112 |
||
113 |
||
4096 | 114 |
that's better: |
244 | 115 |
[exBegin] |
259 | 116 |
MessageTally spyOn:[ |
4013 | 117 |
100000 timesRepeat:[ #(6 5 4 3 2 1) copy sort] |
259 | 118 |
] |
244 | 119 |
[exEnd] |
120 |
||
121 |
||
4096 | 122 |
that's much better |
244 | 123 |
[exBegin] |
259 | 124 |
MessageTally spyOn:[ |
4013 | 125 |
500000 timesRepeat:[#(6 5 4 3 2 1) copy sort] |
259 | 126 |
] |
244 | 127 |
[exEnd] |
128 |
||
129 |
||
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
130 |
a smaller probing tick also helps: |
244 | 131 |
[exBegin] |
259 | 132 |
MessageTally spyDetailedOn:[ |
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
133 |
500000 timesRepeat:[(10 to:1 by:-1) asArray reverse] |
259 | 134 |
] |
244 | 135 |
[exEnd] |
136 |
||
21 | 137 |
|
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
138 |
as usual, measurements add some extra overhead; |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
139 |
compare the above time to the time given by: |
244 | 140 |
[exBegin] |
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
141 |
Transcript showCR:( |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
142 |
Time millisecondsToRun:[ |
4013 | 143 |
500000 timesRepeat:[#(6 5 4 3 2 1) copy sort] |
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
144 |
] |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
145 |
) |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
146 |
[exEnd] |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
147 |
probing the leafs only may help to reduce the overhead |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
148 |
a bit: |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
149 |
[exBegin] |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
150 |
MessageTally spyLeafDetailedOn:[ |
4013 | 151 |
500000 timesRepeat:[#(6 5 4 3 2 1) copy sort] |
259 | 152 |
] |
244 | 153 |
[exEnd] |
154 |
||
155 |
||
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
156 |
|
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
157 |
|
244 | 158 |
[exBegin] |
21 | 159 |
MessageTally spyOn:[SystemBrowser open ] |
244 | 160 |
[exEnd] |
161 |
||
162 |
||
163 |
[exBegin] |
|
21 | 164 |
MessageTally spyDetailedOn:[SystemBrowser open ] |
244 | 165 |
[exEnd] |
21 | 166 |
" |
9 | 167 |
! ! |
0 | 168 |
|
2970 | 169 |
!MessageTally class methodsFor:'constants'! |
170 |
||
171 |
detailedSamplingIntervalMS |
|
172 |
^ 1 |
|
173 |
! |
|
174 |
||
175 |
normalSamplingIntervalMS |
|
176 |
^ 10 |
|
177 |
! ! |
|
178 |
||
1280 | 179 |
!MessageTally class methodsFor:'spying-private'! |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
180 |
|
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
181 |
spyLeafOn:aBlock interval:ms to:outStream |
2970 | 182 |
"evaluate aBlock and output leaf method statistics on outStream. |
183 |
Return the value from aBlock." |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
184 |
|
2970 | 185 |
|aTally retVal| |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
186 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
187 |
aTally := self new. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
188 |
|
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
189 |
[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
190 |
[ |
2970 | 191 |
retVal := aTally spyLeafOn:aBlock interval:ms. |
1152
b1f95f16a0a4
Use #ifCurtailed: instead of #valueOnUnwindDo:
Stefan Vogel <sv@exept.de>
parents:
1139
diff
changeset
|
192 |
] ifCurtailed:[ |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
193 |
outStream nextPutLine:'TALLY: block returned'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
194 |
] |
1139
56861678ff27
#valueNowOrOnUnwindDo: -> #ensure:
Claus Gittinger <cg@exept.de>
parents:
963
diff
changeset
|
195 |
] ensure:[ |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
196 |
aTally printLeafStatisticOn:outStream |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
197 |
]. |
2970 | 198 |
^ retVal |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
199 |
|
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
200 |
"Modified: 22.3.1997 / 16:50:47 / cg" |
88 | 201 |
! |
202 |
||
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
203 |
spyOn:aBlock interval:ms to:outStream |
2970 | 204 |
"evaluate aBlock and output full statistics on outstream. |
205 |
Return the value from aBlock." |
|
0 | 206 |
|
2249 | 207 |
|aTally retVal| |
0 | 208 |
|
209 |
aTally := self new. |
|
21 | 210 |
|
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
211 |
[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
212 |
[ |
2249 | 213 |
retVal := aTally spyOn:aBlock interval:ms. |
1152
b1f95f16a0a4
Use #ifCurtailed: instead of #valueOnUnwindDo:
Stefan Vogel <sv@exept.de>
parents:
1139
diff
changeset
|
214 |
] ifCurtailed:[ |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
215 |
outStream nextPutLine:'TALLY: block returned'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
216 |
] |
1139
56861678ff27
#valueNowOrOnUnwindDo: -> #ensure:
Claus Gittinger <cg@exept.de>
parents:
963
diff
changeset
|
217 |
] ensure:[ |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
218 |
aTally printFullStatisticOn:outStream |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
219 |
]. |
2249 | 220 |
^ retVal |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
221 |
|
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
222 |
"Modified: 22.3.1997 / 16:54:36 / cg" |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
223 |
! ! |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
224 |
|
1280 | 225 |
!MessageTally class methodsFor:'spying-public interface'! |
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
226 |
|
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
227 |
spyDetailedOn:aBlock |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
228 |
"evaluate aBlock and output full statistics on the Transcript. |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
229 |
The Tick is 1ms for more detailed measurement. |
1376 | 230 |
Notice: not all architectures support such a small timer interval." |
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
231 |
|
2970 | 232 |
^ self spyOn:aBlock interval:(self detailedSamplingIntervalMS) to:Transcript |
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
233 |
|
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
234 |
"Modified: 22.3.1997 / 16:26:39 / cg" |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
235 |
! |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
236 |
|
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
237 |
spyLeafDetailedOn:aBlock |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
238 |
"evaluate aBlock and output leaf method statistics on the Transcript. |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
239 |
The Tick is 1ms for more detailed measurement. |
1376 | 240 |
Notice: not all architectures support such a small timer interval." |
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
241 |
|
2970 | 242 |
^ self spyLeafOn:aBlock interval:(self detailedSamplingIntervalMS) to:Transcript |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
243 |
|
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
244 |
"Created: 20.3.1997 / 20:41:53 / cg" |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
245 |
"Modified: 22.3.1997 / 16:26:47 / cg" |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
246 |
! |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
247 |
|
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
248 |
spyLeafOn:aBlock |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
249 |
"evaluate aBlock and output leaf method statistics on the Transcript. |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
250 |
The Tick is 10ms for less detailed measurements." |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
251 |
|
2970 | 252 |
^ self spyLeafOn:aBlock interval:(self normalSamplingIntervalMS) to:Transcript |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
253 |
|
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
254 |
"Created: 20.3.1997 / 20:41:40 / cg" |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
255 |
"Modified: 22.3.1997 / 16:26:49 / cg" |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
256 |
! |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
257 |
|
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
258 |
spyOn:aBlock |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
259 |
"evaluate aBlock and output full statistics on the Transcript. |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
260 |
The Tick is 10ms for less detailed measurements." |
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
261 |
|
2970 | 262 |
^ self spyOn:aBlock interval:(self normalSamplingIntervalMS) to:Transcript |
570
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
263 |
|
9c3efb856ee6
allow spy output to be sent to some stream
Claus Gittinger <cg@exept.de>
parents:
567
diff
changeset
|
264 |
"Modified: 22.3.1997 / 16:26:51 / cg" |
88 | 265 |
! ! |
266 |
||
267 |
!MessageTally methodsFor:'accessing'! |
|
268 |
||
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
269 |
endTime |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
270 |
"return the endTime of the run" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
271 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
272 |
^ endTime |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
273 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
274 |
"Modified: 18.5.1996 / 18:47:47 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
275 |
"Created: 22.3.1997 / 16:44:29 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
276 |
! |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
277 |
|
88 | 278 |
nTally |
260 | 279 |
"return the number of accumulated probes" |
280 |
||
281 |
^ ntally |
|
282 |
||
283 |
"Modified: 18.5.1996 / 18:47:47 / cg" |
|
0 | 284 |
! |
285 |
||
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
286 |
probes |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
287 |
"return the accumulated collection of flat probes" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
288 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
289 |
^ probes |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
290 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
291 |
"Modified: 18.5.1996 / 18:47:57 / cg" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
292 |
"Created: 20.3.1997 / 20:54:19 / cg" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
293 |
! |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
294 |
|
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
295 |
startTime |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
296 |
"return the startTime of the run" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
297 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
298 |
^ startTime |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
299 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
300 |
"Modified: 18.5.1996 / 18:47:47 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
301 |
"Created: 22.3.1997 / 16:44:33 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
302 |
! |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
303 |
|
88 | 304 |
tree |
260 | 305 |
"return the accumulated calling tree" |
306 |
||
88 | 307 |
^ tree |
260 | 308 |
|
309 |
"Modified: 18.5.1996 / 18:47:57 / cg" |
|
0 | 310 |
! ! |
311 |
||
963 | 312 |
!MessageTally methodsFor:'printing & storing'! |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
313 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
314 |
printFlatMethodLeafsOn:aStream |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
315 |
"print all flat method leafNodes statistics on aStream" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
316 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
317 |
|leafNodes| |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
318 |
|
567
180b5d0da74b
combine multiple invocations of the same method
Claus Gittinger <cg@exept.de>
parents:
565
diff
changeset
|
319 |
leafNodes := OrderedCollection new. |
180b5d0da74b
combine multiple invocations of the same method
Claus Gittinger <cg@exept.de>
parents:
565
diff
changeset
|
320 |
probes do:[:aProbe | aProbe addMethodLeafNodesTo:leafNodes]. |
180b5d0da74b
combine multiple invocations of the same method
Claus Gittinger <cg@exept.de>
parents:
565
diff
changeset
|
321 |
leafNodes := leafNodes asSortedCollection:[:a :b | |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
322 |
a leafTally < b leafTally]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
323 |
leafNodes do:[:aNode | |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
324 |
aNode leafTally ~= 0 ifTrue:[ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
325 |
aNode printSingleMethodLeafOn:aStream. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
326 |
aStream cr. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
327 |
] |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
328 |
]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
329 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
330 |
"Created: 20.3.1997 / 20:56:13 / cg" |
567
180b5d0da74b
combine multiple invocations of the same method
Claus Gittinger <cg@exept.de>
parents:
565
diff
changeset
|
331 |
"Modified: 21.3.1997 / 10:18:31 / cg" |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
332 |
! |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
333 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
334 |
printFullStatisticOn:outStream |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
335 |
"output full statistics on outstream" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
336 |
|
589 | 337 |
|runTime err| |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
338 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
339 |
ntally == 0 ifTrue:[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
340 |
self printNoProbesOn:outStream. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
341 |
^ self |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
342 |
]. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
343 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
344 |
tree computePercentage:ntally. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
345 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
346 |
runTime := endTime - startTime. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
347 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
348 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
349 |
err := (1000 // ntally / 10.0). |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
350 |
err > 0 ifTrue:[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
351 |
outStream nextPutLine:('total execution time: ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
352 |
, runTime printString , ' ms ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
353 |
, '(' , ntally printString , ' probes ;' |
770 | 354 |
, ' error ' |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
355 |
, (1000 // ntally / 10.0) printString |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
356 |
, '%)' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
357 |
). |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
358 |
] ifFalse:[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
359 |
outStream nextPutLine:('total execution time: ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
360 |
, runTime printString , ' ms ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
361 |
, '(' , ntally printString , ' probes)' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
362 |
). |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
363 |
]. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
364 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
365 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
366 |
tree printOn:outStream. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
367 |
outStream cr; cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
368 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
369 |
outStream nextPutLine:'leafs of calling tree (by receiver & implementing method):'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
370 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
371 |
tree printLeafsOn:outStream. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
372 |
outStream cr; cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
373 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
374 |
outStream nextPutLine:'method leafs of calling tree (by implementing method only):'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
375 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
376 |
tree printMethodLeafsOn:outStream. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
377 |
outStream cr; cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
378 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
379 |
"Created: 22.3.1997 / 16:52:09 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
380 |
"Modified: 22.3.1997 / 16:53:38 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
381 |
! |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
382 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
383 |
printLeafStatisticOn:outStream |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
384 |
"output leaf statistics on outstream" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
385 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
386 |
|runTime err| |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
387 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
388 |
ntally == 0 ifTrue:[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
389 |
self printNoProbesOn:outStream. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
390 |
^ self. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
391 |
]. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
392 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
393 |
runTime := endTime - startTime. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
394 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
395 |
probes do:[:aProbe | |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
396 |
aProbe computePercentage:ntally |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
397 |
]. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
398 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
399 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
400 |
err := (1000 // ntally / 10.0). |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
401 |
err > 0 ifTrue:[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
402 |
outStream nextPutLine:('total execution time: ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
403 |
, runTime printString , ' ms ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
404 |
, '(' , ntally printString , ' probes ;' |
770 | 405 |
, ' error ' |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
406 |
, (1000 // ntally / 10.0) printString |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
407 |
, '%)' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
408 |
). |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
409 |
] ifFalse:[ |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
410 |
outStream nextPutLine:('total execution time: ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
411 |
, runTime printString , ' ms ' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
412 |
, '(' , ntally printString , ' probes)' |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
413 |
). |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
414 |
]. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
415 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
416 |
outStream nextPutLine:'method leafs of calling tree:'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
417 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
418 |
self printFlatMethodLeafsOn:outStream. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
419 |
outStream cr; cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
420 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
421 |
"Created: 22.3.1997 / 16:50:06 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
422 |
"Modified: 22.3.1997 / 16:53:15 / cg" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
423 |
! |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
424 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
425 |
printNoProbesOn:outStream |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
426 |
"output a message that no probes are present" |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
427 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
428 |
outStream cr. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
429 |
outStream nextPutLine:'TALLY: No probes - execution time too short;'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
430 |
outStream nextPutLine:'TALLY: retry using: spyOn:[n timesRepeat:[...]]'. |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
431 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
432 |
"Created: 22.3.1997 / 16:53:23 / cg" |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
433 |
! ! |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
434 |
|
0 | 435 |
!MessageTally methodsFor:'private'! |
436 |
||
21 | 437 |
execute |
260 | 438 |
"evaluate the target block" |
439 |
||
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
440 |
executing := true. |
2249 | 441 |
^ theBlock value |
260 | 442 |
|
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
443 |
"Modified: 20.3.1997 / 21:36:27 / cg" |
21 | 444 |
! ! |
0 | 445 |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
446 |
!MessageTally methodsFor:'probing'! |
21 | 447 |
|
448 |
count:aContext |
|
260 | 449 |
"entered whenever the probed block gets interrupted; |
450 |
look where it is, and remember in the calling tree" |
|
451 |
||
263 | 452 |
"{ Pragma: +optSpeed }" |
453 |
||
2281 | 454 |
|chain| |
0 | 455 |
|
2281 | 456 |
chain := CallChain |
457 |
callChainTo:aContext |
|
458 |
stopAtCallerForWhich:[:con | |
|
459 |
(con receiver == self) and:[con selector == #execute] |
|
460 |
]. |
|
0 | 461 |
|
2281 | 462 |
"add chain to the tree" |
463 |
||
464 |
chain notNil ifTrue:[ |
|
465 |
ntally := ntally + 1. |
|
466 |
tree addChain:chain |
|
0 | 467 |
]. |
468 |
||
2281 | 469 |
"Modified: / 04-07-2010 / 09:45:28 / cg" |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
470 |
! |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
471 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
472 |
countLeaf:aContext |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
473 |
"entered whenever the probed block gets interrupted; |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
474 |
look where it is, and remember in the flat profile" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
475 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
476 |
"{ Pragma: +optSpeed }" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
477 |
|
2281 | 478 |
|con entry recClass selector mthdClass sender home existingEntry| |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
479 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
480 |
con := aContext. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
481 |
con isNil ifTrue:[^ self]. |
0 | 482 |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
483 |
"walk up above the interrupt context" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
484 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
485 |
[con receiver == Processor] whileTrue:[ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
486 |
con := con sender |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
487 |
]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
488 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
489 |
"got it - collect info from contexts" |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
490 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
491 |
con isNil ifTrue:[^ self]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
492 |
((con receiver == self) and:[con selector == #execute]) ifTrue:[^ self]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
493 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
494 |
sender := con sender. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
495 |
sender isNil ifTrue:[^ self]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
496 |
((sender receiver == self) and:[sender selector == #execute]) ifTrue:[^ self]. |
88 | 497 |
|
2281 | 498 |
home := con methodHome. |
499 |
home isNil ifTrue:[ |
|
500 |
recClass := UndefinedObject. |
|
501 |
selector := 'optimized'. |
|
502 |
mthdClass := UndefinedObject. |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
503 |
] ifFalse:[ |
2281 | 504 |
recClass := home receiver class. |
505 |
selector := home selector. |
|
506 |
mthdClass := home methodClass. |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
507 |
]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
508 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
509 |
"add info to the probes collection" |
88 | 510 |
|
565
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
511 |
ntally := ntally + 1. |
01241f73a7a0
dont increment tally for ignored contexts.
Claus Gittinger <cg@exept.de>
parents:
564
diff
changeset
|
512 |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
513 |
entry := ProfileTree new. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
514 |
entry |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
515 |
receiver:recClass |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
516 |
selector:selector |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
517 |
class:mthdClass |
2281 | 518 |
isBlock:(con isBlockContext). |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
519 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
520 |
existingEntry := probes elementAt:entry ifAbsent:nil. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
521 |
existingEntry isNil ifTrue:[ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
522 |
probes add:entry |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
523 |
] ifFalse:[ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
524 |
existingEntry incrementLeafTally. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
525 |
]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
526 |
|
2281 | 527 |
"Modified: / 04-07-2010 / 09:47:06 / cg" |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
528 |
! ! |
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
529 |
|
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
530 |
!MessageTally methodsFor:'spy setup'! |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
531 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
532 |
spyLeafOn:aBlock interval:ms |
2970 | 533 |
"spy on execution time; generate information on leaf nodes only |
534 |
(which generates slightly less sampling overhead) |
|
535 |
Return the value from aBlock." |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
536 |
|
2970 | 537 |
|probing delay probingProcess probedProcess retVal| |
88 | 538 |
|
539 |
theBlock := aBlock. |
|
540 |
||
1439 | 541 |
Processor activeProcess withPriority:(Processor userInterruptPriority-1) do:[ |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
542 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
543 |
probingProcess := [ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
544 |
|p| |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
545 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
546 |
p := probedProcess. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
547 |
[probing] whileTrue:[ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
548 |
delay wait. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
549 |
executing ifTrue:[ |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
550 |
self countLeaf:p suspendedContext |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
551 |
] |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
552 |
]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
553 |
] newProcess. |
88 | 554 |
|
1439 | 555 |
probingProcess priority:(Processor userInterruptPriority+1). |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
556 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
557 |
delay := (Delay forMilliseconds:ms). |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
558 |
ntally := 0. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
559 |
probes := Set new:200. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
560 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
561 |
probedProcess := Processor activeProcess. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
562 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
563 |
executing := false. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
564 |
probing := true. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
565 |
probingProcess resume. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
566 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
567 |
[ |
263 | 568 |
startTime := OperatingSystem getMillisecondTime. |
2970 | 569 |
retVal := self execute. |
1139
56861678ff27
#valueNowOrOnUnwindDo: -> #ensure:
Claus Gittinger <cg@exept.de>
parents:
963
diff
changeset
|
570 |
] ensure:[ |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
571 |
probing := executing := false. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
572 |
theBlock := nil. |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
573 |
endTime := OperatingSystem getMillisecondTime. |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
574 |
]. |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
575 |
]. |
2970 | 576 |
^ retVal |
88 | 577 |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
578 |
"Created: 20.3.1997 / 20:15:07 / cg" |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
579 |
"Modified: 22.3.1997 / 16:46:42 / cg" |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
580 |
! |
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
581 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
582 |
spyOn:aBlock interval:ms |
2970 | 583 |
"spy on execution time, generate a hierarchical call information on the output stream. |
584 |
Return the value from aBlock." |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
585 |
|
2932 | 586 |
|probing delay probingProcess probedProcess retVal runPrio probePrio| |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
587 |
|
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
588 |
theBlock := aBlock. |
2932 | 589 |
runPrio := (Processor activePriority-1 "userInterruptPriority-1"). |
590 |
probePrio := (Processor activePriority"+1" "Processor userInterruptPriority+1"). |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
591 |
|
2249 | 592 |
Processor activeProcess |
2932 | 593 |
withPriority:runPrio |
2249 | 594 |
do:[ |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
595 |
|
2249 | 596 |
probingProcess := [ |
597 |
|p| |
|
88 | 598 |
|
2249 | 599 |
p := probedProcess. |
600 |
[probing] whileTrue:[ |
|
2932 | 601 |
delay wait. |
2249 | 602 |
executing ifTrue:[ |
603 |
self count:p suspendedContext |
|
604 |
] |
|
605 |
]. |
|
606 |
] newProcess. |
|
88 | 607 |
|
2932 | 608 |
probingProcess priority:probePrio. |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
609 |
|
2249 | 610 |
delay := (Delay forMilliseconds:ms). |
611 |
ntally := 0. |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
612 |
|
2249 | 613 |
tree := ProfileTree new. |
614 |
tree |
|
615 |
receiver:MessageTally |
|
616 |
selector:#execute |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
617 |
class:MessageTally |
2249 | 618 |
isBlock:false. |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
619 |
|
2249 | 620 |
probedProcess := Processor activeProcess. |
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
621 |
|
2249 | 622 |
executing := false. |
623 |
probing := true. |
|
624 |
probingProcess resume. |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
625 |
|
2249 | 626 |
[ |
627 |
startTime := OperatingSystem getMillisecondTime. |
|
628 |
retVal := self execute. |
|
629 |
] ensure:[ |
|
630 |
probing := executing := false. |
|
631 |
theBlock := nil. |
|
632 |
endTime := OperatingSystem getMillisecondTime. |
|
633 |
]. |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
634 |
]. |
2249 | 635 |
|
636 |
^ retVal |
|
263 | 637 |
|
564
d549f0b9c2f2
tallied block now executes under thisProcess;
Claus Gittinger <cg@exept.de>
parents:
561
diff
changeset
|
638 |
"Created: 20.3.1997 / 20:14:44 / cg" |
571
58e603e1ce24
made all print stuff instance methods;
Claus Gittinger <cg@exept.de>
parents:
570
diff
changeset
|
639 |
"Modified: 22.3.1997 / 16:45:42 / cg" |
88 | 640 |
! ! |
641 |
||
98 | 642 |
!MessageTally class methodsFor:'documentation'! |
643 |
||
261 | 644 |
version |
4013 | 645 |
^ '$Header$' |
2249 | 646 |
! |
647 |
||
648 |
version_CVS |
|
4013 | 649 |
^ '$Header$' |
98 | 650 |
! ! |
4013 | 651 |