Rev 1509 | Go to most recent revision | Details | Last modification | View Log | RSS feed
Rev | Author | Line No. | Line |
---|---|---|---|
1506 | giacomo | 1 | This is an example result file made through "extract" utility, |
2 | some comments are added to make it clearer. |
||
3 | |||
4 | Jumpball demo (ecp) statistics |
||
5 | |||
6 | Clk/msec = 651652 (CPU Pentium 3 - 650 MHz) |
||
7 | |||
8 | EVT:Log starts at [227521097059] |
||
9 | EVT:Log ends at [239828158320] |
||
10 | |||
11 | Total dTSC [ 12307061261] us [ 18885941] |
||
12 | Events [ 85737] |
||
13 | Execs [ 74832] |
||
14 | EndCycles [ 10862] |
||
15 | |||
16 | Exec TSC sum = 12307061261 (100.00) |
||
17 | |||
18 | Preemption Removing.... |
||
19 | |||
20 | Compute Task Statistics.... |
||
21 | |||
22 | Background Task CTX [ 0] PID [NODEF] |
||
23 | Total Execution dTSC [ 516556] us [ 792] |
||
24 | Mean CPU Bandwidth [ 0.004197%] |
||
25 | after first exec [ 0.004197%] |
||
26 | Execs Number [ 1] |
||
27 | Min Exec dTSC [ 516556] us [ 792] |
||
28 | Mean Exec dTSC [ 516556] us [ 792] |
||
29 | Max Exec dTSC [ 516556] us [ 792] |
||
30 | |||
31 | - Unknown context: this is the context running when the tracer |
||
32 | is enabled. Before the first context switch, the tracer doesn't |
||
33 | know who is executing... 792 unkwnown microseconds |
||
34 | |||
35 | Interrupts |
||
36 | Total Execution dTSC [ 57502840] us [ 88241] |
||
37 | Mean CPU Bandwidth [ 0.467235%] |
||
38 | after first int [ 0.467254%] |
||
39 | Interrupts Number [ 20880] |
||
40 | Min Interrupt dTSC [ 606] us [ 0] |
||
41 | Mean Interrupt dTSC [ 2753] us [ 4] |
||
42 | Max Interrupt dTSC [ 17724] us [ 27] |
||
43 | |||
44 | Min Arr. Delta dTSC [ 1108] us [ 1] |
||
45 | Mean Arr. Delta dTSC [ 589421] us [ 904] |
||
46 | Max Arr. Delta dTSC [ 7478507] us [ 11476] |
||
47 | |||
48 | - System Interrupts: All the hardware interrupts |
||
49 | |||
50 | Periodical Task CTX [ 256] PID [NODEF] |
||
51 | Total Execution dTSC [ 111566216] us [ 171205] |
||
52 | Mean CPU Bandwidth [ 0.906522%] |
||
53 | after first exec [ 0.906560%] |
||
54 | Execs Number [ 6053] |
||
55 | Min Exec dTSC [ 593] us [ 0] |
||
56 | Mean Exec dTSC [ 18431] us [ 28] |
||
57 | Max Exec dTSC [ 1296917] us [ 1990] |
||
58 | |||
59 | Total Job Exec dTSC [ 111566216] us [ 171205] |
||
60 | Jobs Number [ 1450] |
||
61 | Min Job dTSC [ 74908] us [ 114] |
||
62 | Mean Job dTSC [ 76942] us [ 118] |
||
63 | Max Job dTSC [ 2404229] us [ 3689] |
||
64 | |||
65 | Min Arr. Delta dTSC [ 4664213] us [ 7157] |
||
66 | Mean Arr. Delta dTSC [ 8491748] us [ 13031] |
||
67 | Max Arr. Delta dTSC [ 2870899538] us [ 4405571] |
||
68 | |||
69 | - Main task + palla0: Actually the extract utility doesn't elaborate |
||
70 | correctly this situation. The "main" task (pid 0) terminated after the system |
||
71 | beginning. Palla0 was created after this event, so it got the same context |
||
72 | number... statistics are not useful for this context |
||
73 | |||
74 | Periodical Task CTX [ 296] PID [ 5] |
||
75 | Total Execution dTSC [ 519672442] us [ 797469] |
||
76 | Mean CPU Bandwidth [ 4.222555%] |
||
77 | after first exec [ 4.223554%] |
||
78 | Execs Number [ 561] |
||
79 | Min Exec dTSC [ 23230] us [ 35] |
||
80 | Mean Exec dTSC [ 926332] us [ 1421] |
||
81 | Max Exec dTSC [ 2809219] us [ 4310] |
||
82 | |||
83 | Total Job Exec dTSC [ 519672442] us [ 797469] |
||
84 | Jobs Number [ 189] |
||
85 | Min Job dTSC [ 2706411] us [ 4153] |
||
86 | Mean Job dTSC [ 2749589] us [ 4219] |
||
87 | Max Job dTSC [ 2809219] us [ 4310] |
||
88 | |||
89 | Min Arr. Delta dTSC [ 65129805] us [ 99945] |
||
90 | Mean Arr. Delta dTSC [ 65149366] us [ 99975] |
||
91 | Max Arr. Delta dTSC [ 65169337] us [ 100006] |
||
92 | |||
93 | - Task 5 "jetctrl" : Soft task |
||
94 | |||
95 | Periodical Task CTX [ 304] PID [ 6] |
||
96 | Total Execution dTSC [ 3204033] us [ 4916] |
||
97 | Mean CPU Bandwidth [ 0.026034%] |
||
98 | after first exec [ 0.026046%] |
||
99 | Execs Number [ 378] |
||
100 | Min Exec dTSC [ 2971] us [ 4] |
||
101 | Mean Exec dTSC [ 8476] us [ 13] |
||
102 | Max Exec dTSC [ 14336] us [ 21] |
||
103 | |||
104 | Total Job Exec dTSC [ 3204033] us [ 4916] |
||
105 | Jobs Number [ 189] |
||
106 | Min Job dTSC [ 16705] us [ 25] |
||
107 | Mean Job dTSC [ 16952] us [ 26] |
||
108 | Max Job dTSC [ 19797] us [ 30] |
||
109 | |||
110 | Min Arr. Delta dTSC [ 62335693] us [ 95657] |
||
111 | Mean Arr. Delta dTSC [ 65144811] us [ 99968] |
||
112 | Max Arr. Delta dTSC [ 67938428] us [ 104255] |
||
113 | |||
114 | - Task 6 "jetdummy" : Soft Task |
||
115 | |||
116 | Periodical Task CTX [ 312] PID [ 7] |
||
117 | Total Execution dTSC [ 147655794] us [ 226586] |
||
118 | Mean CPU Bandwidth [ 1.199765%] |
||
119 | after first exec [ 1.200323%] |
||
120 | Execs Number [ 601] |
||
121 | Min Exec dTSC [ 12701] us [ 19] |
||
122 | Mean Exec dTSC [ 245683] us [ 377] |
||
123 | Max Exec dTSC [ 767697] us [ 1178] |
||
124 | |||
125 | Total Job Exec dTSC [ 147655794] us [ 226586] |
||
126 | Jobs Number [ 189] |
||
127 | Min Job dTSC [ 778980] us [ 1195] |
||
128 | Mean Job dTSC [ 781247] us [ 1198] |
||
129 | Max Job dTSC [ 784959] us [ 1204] |
||
130 | |||
131 | Min Arr. Delta dTSC [ 62315034] us [ 95626] |
||
132 | Mean Arr. Delta dTSC [ 65144799] us [ 99968] |
||
133 | Max Arr. Delta dTSC [ 67955268] us [ 104281] |
||
134 | |||
135 | - Task 7 "jetslice": Soft Task |
||
136 | |||
137 | Periodical Task CTX [ 320] PID [ 8] |
||
138 | Total Execution dTSC [ 140490906] us [ 215591] |
||
139 | Mean CPU Bandwidth [ 1.141547%] |
||
140 | after first exec [ 1.141806%] |
||
141 | Execs Number [ 5667] |
||
142 | Min Exec dTSC [ 22227] us [ 34] |
||
143 | Mean Exec dTSC [ 24791] us [ 38] |
||
144 | Max Exec dTSC [ 35605] us [ 54] |
||
145 | |||
146 | Total Job Exec dTSC [ 140490906] us [ 215591] |
||
147 | Jobs Number [ 1889] |
||
148 | Min Job dTSC [ 73080] us [ 112] |
||
149 | Mean Job dTSC [ 74373] us [ 114] |
||
150 | Max Job dTSC [ 86976] us [ 133] |
||
151 | |||
152 | Min Arr. Delta dTSC [ 6509377] us [ 9989] |
||
153 | Mean Arr. Delta dTSC [ 6514942] us [ 9997] |
||
154 | Max Arr. Delta dTSC [ 6516991] us [ 10000] |
||
155 | |||
156 | - Task 8 "pallaEDF": Hard Task |
||
157 | |||
158 | Background Task CTX [ 264] PID [NODEF] |
||
159 | Total Execution dTSC [ 10800997729] us [ 16574794] |
||
160 | Mean CPU Bandwidth [ 87.762606%] |
||
161 | after first exec [ 87.809315%] |
||
162 | Execs Number [ 9520] |
||
163 | Min Exec dTSC [ 1077] us [ 1] |
||
164 | Mean Exec dTSC [ 1134558] us [ 1741] |
||
165 | Max Exec dTSC [ 6438142] us [ 9879] |
||
166 | |||
167 | - Task 1 "dummy": The dummy task, it represents the system |
||
168 | idle time, near 87% of mean value |
||
169 | |||
170 | Background Task CTX [ 280] PID [NODEF] |
||
171 | Total Execution dTSC [ 1826185] us [ 2802] |
||
172 | Mean CPU Bandwidth [ 0.014839%] |
||
173 | after first exec [ 0.014862%] |
||
174 | Execs Number [ 396] |
||
175 | Min Exec dTSC [ 2591] us [ 3] |
||
176 | Mean Exec dTSC [ 4611] us [ 7] |
||
177 | Max Exec dTSC [ 11683] us [ 17] |
||
178 | |||
179 | - Task 3 "interrupt server": The interrupt context |
||
180 | |||
181 | Periodical Task CTX [ 288] PID [NODEF] |
||
182 | Total Execution dTSC [ 230386] us [ 353] |
||
183 | Mean CPU Bandwidth [ 0.001872%] |
||
184 | after first exec [ 0.002440%] |
||
185 | Execs Number [ 44] |
||
186 | Min Exec dTSC [ 2064] us [ 3] |
||
187 | Mean Exec dTSC [ 5236] us [ 8] |
||
188 | Max Exec dTSC [ 23701] us [ 36] |
||
189 | |||
190 | Total Job Exec dTSC [ 230386] us [ 353] |
||
191 | Jobs Number [ 17] |
||
192 | Min Job dTSC [ 4577] us [ 7] |
||
193 | Mean Job dTSC [ 13552] us [ 20] |
||
194 | Max Job dTSC [ 31166] us [ 47] |
||
195 | |||
196 | Min Arr. Delta dTSC [ 75908314] us [ 116485] |
||
197 | Mean Arr. Delta dTSC [ 590133555] us [ 905596] |
||
198 | Max Arr. Delta dTSC [ 3892330642] us [ 5973020] |
||
199 | |||
200 | - Task 4 "key task": The task for keyboard handler |
||
201 | |||
202 | Periodical Task CTX [ 328] PID [ 10] |
||
203 | Total Execution dTSC [ 107254213] us [ 164588] |
||
204 | Mean CPU Bandwidth [ 0.871485%] |
||
205 | after first exec [ 1.178209%] |
||
206 | Execs Number [ 6595] |
||
207 | Min Exec dTSC [ 593] us [ 0] |
||
208 | Mean Exec dTSC [ 16262] us [ 24] |
||
209 | Max Exec dTSC [ 28584] us [ 43] |
||
210 | |||
211 | Total Job Exec dTSC [ 107254213] us [ 164588] |
||
212 | Jobs Number [ 1398] |
||
213 | Min Job dTSC [ 74241] us [ 113] |
||
214 | Mean Job dTSC [ 76719] us [ 117] |
||
215 | Max Job dTSC [ 80220] us [ 123] |
||
216 | |||
217 | Min Arr. Delta dTSC [ 4901729] us [ 7522] |
||
218 | Mean Arr. Delta dTSC [ 6514947] us [ 9997] |
||
219 | Max Arr. Delta dTSC [ 8128401] us [ 12473] |
||
220 | |||
221 | - Task 10 "palla1": Soft Task |
||
222 | |||
223 | Periodical Task CTX [ 336] PID [ 11] |
||
224 | Total Execution dTSC [ 99733351] us [ 153046] |
||
225 | Mean CPU Bandwidth [ 0.810375%] |
||
226 | after first exec [ 1.145890%] |
||
227 | Execs Number [ 5556] |
||
228 | Min Exec dTSC [ 593] us [ 0] |
||
229 | Mean Exec dTSC [ 17950] us [ 27] |
||
230 | Max Exec dTSC [ 28056] us [ 43] |
||
231 | |||
232 | Total Job Exec dTSC [ 99733351] us [ 153046] |
||
233 | Jobs Number [ 1336] |
||
234 | Min Job dTSC [ 74232] us [ 113] |
||
235 | Mean Job dTSC [ 74650] us [ 114] |
||
236 | Max Job dTSC [ 78187] us [ 119] |
||
237 | |||
238 | Min Arr. Delta dTSC [ 6513949] us [ 9996] |
||
239 | Mean Arr. Delta dTSC [ 6514947] us [ 9997] |
||
240 | Max Arr. Delta dTSC [ 6515750] us [ 9998] |
||
241 | |||
242 | - Task 11 "palla2": Soft Task |
||
243 | |||
244 | Periodical Task CTX [ 344] PID [ 12] |
||
245 | Total Execution dTSC [ 97526379] us [ 149660] |
||
246 | Mean CPU Bandwidth [ 0.792442%] |
||
247 | after first exec [ 1.168335%] |
||
248 | Execs Number [ 6365] |
||
249 | Min Exec dTSC [ 593] us [ 0] |
||
250 | Mean Exec dTSC [ 15322] us [ 23] |
||
251 | Max Exec dTSC [ 27742] us [ 42] |
||
252 | |||
253 | Total Job Exec dTSC [ 97526379] us [ 149660] |
||
254 | Jobs Number [ 1282] |
||
255 | Min Job dTSC [ 74299] us [ 114] |
||
256 | Mean Job dTSC [ 76073] us [ 116] |
||
257 | Max Job dTSC [ 80211] us [ 123] |
||
258 | |||
259 | Min Arr. Delta dTSC [ 4715741] us [ 7236] |
||
260 | Mean Arr. Delta dTSC [ 6514947] us [ 9997] |
||
261 | Max Arr. Delta dTSC [ 8314456] us [ 12759] |
||
262 | |||
263 | - Task 12 "palla3": Soft Task |
||
264 | |||
265 | Periodical Task CTX [ 352] PID [ 13] |
||
266 | Total Execution dTSC [ 92464627] us [ 141892] |
||
267 | Mean CPU Bandwidth [ 0.751314%] |
||
268 | after first exec [ 1.154592%] |
||
269 | Execs Number [ 5124] |
||
270 | Min Exec dTSC [ 593] us [ 0] |
||
271 | Mean Exec dTSC [ 18045] us [ 27] |
||
272 | Max Exec dTSC [ 28894] us [ 44] |
||
273 | |||
274 | Total Job Exec dTSC [ 92464627] us [ 141892] |
||
275 | Jobs Number [ 1230] |
||
276 | Min Job dTSC [ 74777] us [ 114] |
||
277 | Mean Job dTSC [ 75174] us [ 115] |
||
278 | Max Job dTSC [ 77966] us [ 119] |
||
279 | |||
280 | Min Arr. Delta dTSC [ 5278519] us [ 8100] |
||
281 | Mean Arr. Delta dTSC [ 6514946] us [ 9997] |
||
282 | Max Arr. Delta dTSC [ 7751458] us [ 11895] |
||
283 | |||
284 | - Task 13 "palla4": Soft Task |
||
285 | |||
286 | Periodical Task CTX [ 360] PID [ 14] |
||
287 | Total Execution dTSC [ 46390637] us [ 71189] |
||
288 | Mean CPU Bandwidth [ 0.376943%] |
||
289 | after first exec [ 1.148241%] |
||
290 | Execs Number [ 2582] |
||
291 | Min Exec dTSC [ 594] us [ 0] |
||
292 | Mean Exec dTSC [ 17966] us [ 27] |
||
293 | Max Exec dTSC [ 29618] us [ 45] |
||
294 | |||
295 | Total Job Exec dTSC [ 46390637] us [ 71189] |
||
296 | Jobs Number [ 621] |
||
297 | Min Job dTSC [ 74331] us [ 114] |
||
298 | Mean Job dTSC [ 74703] us [ 114] |
||
299 | Max Job dTSC [ 79147] us [ 121] |
||
300 | |||
301 | Min Arr. Delta dTSC [ 6044798] us [ 9276] |
||
302 | Mean Arr. Delta dTSC [ 6514943] us [ 9997] |
||
303 | Max Arr. Delta dTSC [ 6985324] us [ 10719] |
||
304 | |||
305 | - Task 14 "palla5": Soft Task |
||
306 | |||
307 | Periodical Task CTX [ 368] PID [ 15] |
||
308 | Total Execution dTSC [ 41635493] us [ 63892] |
||
309 | Mean CPU Bandwidth [ 0.338306%] |
||
310 | after first exec [ 1.143778%] |
||
311 | Execs Number [ 2323] |
||
312 | Min Exec dTSC [ 595] us [ 0] |
||
313 | Mean Exec dTSC [ 17923] us [ 27] |
||
314 | Max Exec dTSC [ 28879] us [ 44] |
||
315 | |||
316 | Total Job Exec dTSC [ 41635493] us [ 63892] |
||
317 | Jobs Number [ 559] |
||
318 | Min Job dTSC [ 74213] us [ 113] |
||
319 | Mean Job dTSC [ 74482] us [ 114] |
||
320 | Max Job dTSC [ 77867] us [ 119] |
||
321 | |||
322 | Min Arr. Delta dTSC [ 6514111] us [ 9996] |
||
323 | Mean Arr. Delta dTSC [ 6514946] us [ 9997] |
||
324 | Max Arr. Delta dTSC [ 6515625] us [ 9998] |
||
325 | |||
326 | - Task 15 "palla6": Soft Task |
||
327 | |||
328 | Periodical Task CTX [ 376] PID [ 16] |
||
329 | Total Execution dTSC [ 38373232] us [ 58886] |
||
330 | Mean CPU Bandwidth [ 0.311798%] |
||
331 | after first exec [ 1.150347%] |
||
332 | Execs Number [ 2184] |
||
333 | Min Exec dTSC [ 595] us [ 0] |
||
334 | Mean Exec dTSC [ 17570] us [ 26] |
||
335 | Max Exec dTSC [ 28905] us [ 44] |
||
336 | |||
337 | Total Job Exec dTSC [ 38373232] us [ 58886] |
||
338 | Jobs Number [ 513] |
||
339 | Min Job dTSC [ 74175] us [ 113] |
||
340 | Mean Job dTSC [ 74801] us [ 114] |
||
341 | Max Job dTSC [ 78805] us [ 120] |
||
342 | |||
343 | Min Arr. Delta dTSC [ 6513761] us [ 9995] |
||
344 | Mean Arr. Delta dTSC [ 6514945] us [ 9997] |
||
345 | Max Arr. Delta dTSC [ 6516034] us [ 9999] |
||
346 | |||
347 | - Task 16 "palla7": Soft Task |
||
348 | |||
349 | Background Task CTX [ 248] PID [NODEF] |
||
350 | Total Execution dTSC [ 17867] us [ 27] |
||
351 | Mean CPU Bandwidth [ 0.000145%] |
||
352 | after first exec [ 57.157939%] |
||
353 | Execs Number [ 1] |
||
354 | Min Exec dTSC [ 17867] us [ 27] |
||
355 | Mean Exec dTSC [ 17867] us [ 27] |
||
356 | Max Exec dTSC [ 17867] us [ 27] |
||
357 | |||
358 | - Global context: the kernel context |
||
359 | |||
360 | Background Task CTX [ 272] PID [NODEF] |
||
361 | Total Execution dTSC [ 2375] us [ 3] |
||
362 | Mean CPU Bandwidth [ 0.000019%] |
||
363 | after first exec [ 100.000000%] |
||
364 | Execs Number [ 1] |
||
365 | Min Exec dTSC [ 2375] us [ 3] |
||
366 | Mean Exec dTSC [ 2375] us [ 3] |
||
367 | Max Exec dTSC [ 2375] us [ 3] |
||
368 | |||
369 | - Task 2 "shutdown task": Shutting down the system. It closes |
||
370 | the tracer too (this is the reason of only 3 us exec time). |