Subversion Repositories shark

Rev

Rev 1509 | Go to most recent revision | Blame | Compare with Previous | Last modification | View Log | RSS feed

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).