0,0 → 1,370 |
This is an example result file made through "extract" utility, |
some comments are added to make it clearer. |
|
Jumpball demo (ecp) statistics |
|
Clk/msec = 651652 (CPU Pentium 3 - 650 MHz) |
|
EVT:Log starts at [227521097059] |
EVT:Log ends at [239828158320] |
|
Total dTSC [ 12307061261] us [ 18885941] |
Events [ 85737] |
Execs [ 74832] |
EndCycles [ 10862] |
|
Exec TSC sum = 12307061261 (100.00) |
|
Preemption Removing.... |
|
Compute Task Statistics.... |
|
Background Task CTX [ 0] PID [NODEF] |
Total Execution dTSC [ 516556] us [ 792] |
Mean CPU Bandwidth [ 0.004197%] |
after first exec [ 0.004197%] |
Execs Number [ 1] |
Min Exec dTSC [ 516556] us [ 792] |
Mean Exec dTSC [ 516556] us [ 792] |
Max Exec dTSC [ 516556] us [ 792] |
|
- Unknown context: this is the context running when the tracer |
is enabled. Before the first context switch, the tracer doesn't |
know who is executing... 792 unkwnown microseconds |
|
Interrupts |
Total Execution dTSC [ 57502840] us [ 88241] |
Mean CPU Bandwidth [ 0.467235%] |
after first int [ 0.467254%] |
Interrupts Number [ 20880] |
Min Interrupt dTSC [ 606] us [ 0] |
Mean Interrupt dTSC [ 2753] us [ 4] |
Max Interrupt dTSC [ 17724] us [ 27] |
|
Min Arr. Delta dTSC [ 1108] us [ 1] |
Mean Arr. Delta dTSC [ 589421] us [ 904] |
Max Arr. Delta dTSC [ 7478507] us [ 11476] |
|
- System Interrupts: All the hardware interrupts |
|
Periodical Task CTX [ 256] PID [NODEF] |
Total Execution dTSC [ 111566216] us [ 171205] |
Mean CPU Bandwidth [ 0.906522%] |
after first exec [ 0.906560%] |
Execs Number [ 6053] |
Min Exec dTSC [ 593] us [ 0] |
Mean Exec dTSC [ 18431] us [ 28] |
Max Exec dTSC [ 1296917] us [ 1990] |
|
Total Job Exec dTSC [ 111566216] us [ 171205] |
Jobs Number [ 1450] |
Min Job dTSC [ 74908] us [ 114] |
Mean Job dTSC [ 76942] us [ 118] |
Max Job dTSC [ 2404229] us [ 3689] |
|
Min Arr. Delta dTSC [ 4664213] us [ 7157] |
Mean Arr. Delta dTSC [ 8491748] us [ 13031] |
Max Arr. Delta dTSC [ 2870899538] us [ 4405571] |
|
- Main task + palla0: Actually the extract utility doesn't elaborate |
correctly this situation. The "main" task (pid 0) terminated after the system |
beginning. Palla0 was created after this event, so it got the same context |
number... statistics are not useful for this context |
|
Periodical Task CTX [ 296] PID [ 5] |
Total Execution dTSC [ 519672442] us [ 797469] |
Mean CPU Bandwidth [ 4.222555%] |
after first exec [ 4.223554%] |
Execs Number [ 561] |
Min Exec dTSC [ 23230] us [ 35] |
Mean Exec dTSC [ 926332] us [ 1421] |
Max Exec dTSC [ 2809219] us [ 4310] |
|
Total Job Exec dTSC [ 519672442] us [ 797469] |
Jobs Number [ 189] |
Min Job dTSC [ 2706411] us [ 4153] |
Mean Job dTSC [ 2749589] us [ 4219] |
Max Job dTSC [ 2809219] us [ 4310] |
|
Min Arr. Delta dTSC [ 65129805] us [ 99945] |
Mean Arr. Delta dTSC [ 65149366] us [ 99975] |
Max Arr. Delta dTSC [ 65169337] us [ 100006] |
|
- Task 5 "jetctrl" : Soft task |
|
Periodical Task CTX [ 304] PID [ 6] |
Total Execution dTSC [ 3204033] us [ 4916] |
Mean CPU Bandwidth [ 0.026034%] |
after first exec [ 0.026046%] |
Execs Number [ 378] |
Min Exec dTSC [ 2971] us [ 4] |
Mean Exec dTSC [ 8476] us [ 13] |
Max Exec dTSC [ 14336] us [ 21] |
|
Total Job Exec dTSC [ 3204033] us [ 4916] |
Jobs Number [ 189] |
Min Job dTSC [ 16705] us [ 25] |
Mean Job dTSC [ 16952] us [ 26] |
Max Job dTSC [ 19797] us [ 30] |
|
Min Arr. Delta dTSC [ 62335693] us [ 95657] |
Mean Arr. Delta dTSC [ 65144811] us [ 99968] |
Max Arr. Delta dTSC [ 67938428] us [ 104255] |
|
- Task 6 "jetdummy" : Soft Task |
|
Periodical Task CTX [ 312] PID [ 7] |
Total Execution dTSC [ 147655794] us [ 226586] |
Mean CPU Bandwidth [ 1.199765%] |
after first exec [ 1.200323%] |
Execs Number [ 601] |
Min Exec dTSC [ 12701] us [ 19] |
Mean Exec dTSC [ 245683] us [ 377] |
Max Exec dTSC [ 767697] us [ 1178] |
|
Total Job Exec dTSC [ 147655794] us [ 226586] |
Jobs Number [ 189] |
Min Job dTSC [ 778980] us [ 1195] |
Mean Job dTSC [ 781247] us [ 1198] |
Max Job dTSC [ 784959] us [ 1204] |
|
Min Arr. Delta dTSC [ 62315034] us [ 95626] |
Mean Arr. Delta dTSC [ 65144799] us [ 99968] |
Max Arr. Delta dTSC [ 67955268] us [ 104281] |
|
- Task 7 "jetslice": Soft Task |
|
Periodical Task CTX [ 320] PID [ 8] |
Total Execution dTSC [ 140490906] us [ 215591] |
Mean CPU Bandwidth [ 1.141547%] |
after first exec [ 1.141806%] |
Execs Number [ 5667] |
Min Exec dTSC [ 22227] us [ 34] |
Mean Exec dTSC [ 24791] us [ 38] |
Max Exec dTSC [ 35605] us [ 54] |
|
Total Job Exec dTSC [ 140490906] us [ 215591] |
Jobs Number [ 1889] |
Min Job dTSC [ 73080] us [ 112] |
Mean Job dTSC [ 74373] us [ 114] |
Max Job dTSC [ 86976] us [ 133] |
|
Min Arr. Delta dTSC [ 6509377] us [ 9989] |
Mean Arr. Delta dTSC [ 6514942] us [ 9997] |
Max Arr. Delta dTSC [ 6516991] us [ 10000] |
|
- Task 8 "pallaEDF": Hard Task |
|
Background Task CTX [ 264] PID [NODEF] |
Total Execution dTSC [ 10800997729] us [ 16574794] |
Mean CPU Bandwidth [ 87.762606%] |
after first exec [ 87.809315%] |
Execs Number [ 9520] |
Min Exec dTSC [ 1077] us [ 1] |
Mean Exec dTSC [ 1134558] us [ 1741] |
Max Exec dTSC [ 6438142] us [ 9879] |
|
- Task 1 "dummy": The dummy task, it represents the system |
idle time, near 87% of mean value |
|
Background Task CTX [ 280] PID [NODEF] |
Total Execution dTSC [ 1826185] us [ 2802] |
Mean CPU Bandwidth [ 0.014839%] |
after first exec [ 0.014862%] |
Execs Number [ 396] |
Min Exec dTSC [ 2591] us [ 3] |
Mean Exec dTSC [ 4611] us [ 7] |
Max Exec dTSC [ 11683] us [ 17] |
|
- Task 3 "interrupt server": The interrupt context |
|
Periodical Task CTX [ 288] PID [NODEF] |
Total Execution dTSC [ 230386] us [ 353] |
Mean CPU Bandwidth [ 0.001872%] |
after first exec [ 0.002440%] |
Execs Number [ 44] |
Min Exec dTSC [ 2064] us [ 3] |
Mean Exec dTSC [ 5236] us [ 8] |
Max Exec dTSC [ 23701] us [ 36] |
|
Total Job Exec dTSC [ 230386] us [ 353] |
Jobs Number [ 17] |
Min Job dTSC [ 4577] us [ 7] |
Mean Job dTSC [ 13552] us [ 20] |
Max Job dTSC [ 31166] us [ 47] |
|
Min Arr. Delta dTSC [ 75908314] us [ 116485] |
Mean Arr. Delta dTSC [ 590133555] us [ 905596] |
Max Arr. Delta dTSC [ 3892330642] us [ 5973020] |
|
- Task 4 "key task": The task for keyboard handler |
|
Periodical Task CTX [ 328] PID [ 10] |
Total Execution dTSC [ 107254213] us [ 164588] |
Mean CPU Bandwidth [ 0.871485%] |
after first exec [ 1.178209%] |
Execs Number [ 6595] |
Min Exec dTSC [ 593] us [ 0] |
Mean Exec dTSC [ 16262] us [ 24] |
Max Exec dTSC [ 28584] us [ 43] |
|
Total Job Exec dTSC [ 107254213] us [ 164588] |
Jobs Number [ 1398] |
Min Job dTSC [ 74241] us [ 113] |
Mean Job dTSC [ 76719] us [ 117] |
Max Job dTSC [ 80220] us [ 123] |
|
Min Arr. Delta dTSC [ 4901729] us [ 7522] |
Mean Arr. Delta dTSC [ 6514947] us [ 9997] |
Max Arr. Delta dTSC [ 8128401] us [ 12473] |
|
- Task 10 "palla1": Soft Task |
|
Periodical Task CTX [ 336] PID [ 11] |
Total Execution dTSC [ 99733351] us [ 153046] |
Mean CPU Bandwidth [ 0.810375%] |
after first exec [ 1.145890%] |
Execs Number [ 5556] |
Min Exec dTSC [ 593] us [ 0] |
Mean Exec dTSC [ 17950] us [ 27] |
Max Exec dTSC [ 28056] us [ 43] |
|
Total Job Exec dTSC [ 99733351] us [ 153046] |
Jobs Number [ 1336] |
Min Job dTSC [ 74232] us [ 113] |
Mean Job dTSC [ 74650] us [ 114] |
Max Job dTSC [ 78187] us [ 119] |
|
Min Arr. Delta dTSC [ 6513949] us [ 9996] |
Mean Arr. Delta dTSC [ 6514947] us [ 9997] |
Max Arr. Delta dTSC [ 6515750] us [ 9998] |
|
- Task 11 "palla2": Soft Task |
|
Periodical Task CTX [ 344] PID [ 12] |
Total Execution dTSC [ 97526379] us [ 149660] |
Mean CPU Bandwidth [ 0.792442%] |
after first exec [ 1.168335%] |
Execs Number [ 6365] |
Min Exec dTSC [ 593] us [ 0] |
Mean Exec dTSC [ 15322] us [ 23] |
Max Exec dTSC [ 27742] us [ 42] |
|
Total Job Exec dTSC [ 97526379] us [ 149660] |
Jobs Number [ 1282] |
Min Job dTSC [ 74299] us [ 114] |
Mean Job dTSC [ 76073] us [ 116] |
Max Job dTSC [ 80211] us [ 123] |
|
Min Arr. Delta dTSC [ 4715741] us [ 7236] |
Mean Arr. Delta dTSC [ 6514947] us [ 9997] |
Max Arr. Delta dTSC [ 8314456] us [ 12759] |
|
- Task 12 "palla3": Soft Task |
|
Periodical Task CTX [ 352] PID [ 13] |
Total Execution dTSC [ 92464627] us [ 141892] |
Mean CPU Bandwidth [ 0.751314%] |
after first exec [ 1.154592%] |
Execs Number [ 5124] |
Min Exec dTSC [ 593] us [ 0] |
Mean Exec dTSC [ 18045] us [ 27] |
Max Exec dTSC [ 28894] us [ 44] |
|
Total Job Exec dTSC [ 92464627] us [ 141892] |
Jobs Number [ 1230] |
Min Job dTSC [ 74777] us [ 114] |
Mean Job dTSC [ 75174] us [ 115] |
Max Job dTSC [ 77966] us [ 119] |
|
Min Arr. Delta dTSC [ 5278519] us [ 8100] |
Mean Arr. Delta dTSC [ 6514946] us [ 9997] |
Max Arr. Delta dTSC [ 7751458] us [ 11895] |
|
- Task 13 "palla4": Soft Task |
|
Periodical Task CTX [ 360] PID [ 14] |
Total Execution dTSC [ 46390637] us [ 71189] |
Mean CPU Bandwidth [ 0.376943%] |
after first exec [ 1.148241%] |
Execs Number [ 2582] |
Min Exec dTSC [ 594] us [ 0] |
Mean Exec dTSC [ 17966] us [ 27] |
Max Exec dTSC [ 29618] us [ 45] |
|
Total Job Exec dTSC [ 46390637] us [ 71189] |
Jobs Number [ 621] |
Min Job dTSC [ 74331] us [ 114] |
Mean Job dTSC [ 74703] us [ 114] |
Max Job dTSC [ 79147] us [ 121] |
|
Min Arr. Delta dTSC [ 6044798] us [ 9276] |
Mean Arr. Delta dTSC [ 6514943] us [ 9997] |
Max Arr. Delta dTSC [ 6985324] us [ 10719] |
|
- Task 14 "palla5": Soft Task |
|
Periodical Task CTX [ 368] PID [ 15] |
Total Execution dTSC [ 41635493] us [ 63892] |
Mean CPU Bandwidth [ 0.338306%] |
after first exec [ 1.143778%] |
Execs Number [ 2323] |
Min Exec dTSC [ 595] us [ 0] |
Mean Exec dTSC [ 17923] us [ 27] |
Max Exec dTSC [ 28879] us [ 44] |
|
Total Job Exec dTSC [ 41635493] us [ 63892] |
Jobs Number [ 559] |
Min Job dTSC [ 74213] us [ 113] |
Mean Job dTSC [ 74482] us [ 114] |
Max Job dTSC [ 77867] us [ 119] |
|
Min Arr. Delta dTSC [ 6514111] us [ 9996] |
Mean Arr. Delta dTSC [ 6514946] us [ 9997] |
Max Arr. Delta dTSC [ 6515625] us [ 9998] |
|
- Task 15 "palla6": Soft Task |
|
Periodical Task CTX [ 376] PID [ 16] |
Total Execution dTSC [ 38373232] us [ 58886] |
Mean CPU Bandwidth [ 0.311798%] |
after first exec [ 1.150347%] |
Execs Number [ 2184] |
Min Exec dTSC [ 595] us [ 0] |
Mean Exec dTSC [ 17570] us [ 26] |
Max Exec dTSC [ 28905] us [ 44] |
|
Total Job Exec dTSC [ 38373232] us [ 58886] |
Jobs Number [ 513] |
Min Job dTSC [ 74175] us [ 113] |
Mean Job dTSC [ 74801] us [ 114] |
Max Job dTSC [ 78805] us [ 120] |
|
Min Arr. Delta dTSC [ 6513761] us [ 9995] |
Mean Arr. Delta dTSC [ 6514945] us [ 9997] |
Max Arr. Delta dTSC [ 6516034] us [ 9999] |
|
- Task 16 "palla7": Soft Task |
|
Background Task CTX [ 248] PID [NODEF] |
Total Execution dTSC [ 17867] us [ 27] |
Mean CPU Bandwidth [ 0.000145%] |
after first exec [ 57.157939%] |
Execs Number [ 1] |
Min Exec dTSC [ 17867] us [ 27] |
Mean Exec dTSC [ 17867] us [ 27] |
Max Exec dTSC [ 17867] us [ 27] |
|
- Global context: the kernel context |
|
Background Task CTX [ 272] PID [NODEF] |
Total Execution dTSC [ 2375] us [ 3] |
Mean CPU Bandwidth [ 0.000019%] |
after first exec [ 100.000000%] |
Execs Number [ 1] |
Min Exec dTSC [ 2375] us [ 3] |
Mean Exec dTSC [ 2375] us [ 3] |
Max Exec dTSC [ 2375] us [ 3] |
|
- Task 2 "shutdown task": Shutting down the system. It closes |
the tracer too (this is the reason of only 3 us exec time). |