Subversion Repositories shark

Compare Revisions

Ignore whitespace Rev 1505 → Rev 1506

/demos/trunk/newtrace/utils/example_results
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).