Rev 1521 | Blame | Compare with Previous | Last modification | View Log | RSS feed
This is an example file from "extract" utility,some comments are added to make it clearer.Jumpball demo (ecp) statisticsClk/msec = 2202900 (CPU Athlon 64 3200+)EVT:Log starts at [147140694139]EVT:Log ends at [196093930505]Total dTSC [ 48953236366] ns [ 22222113928]Events [ 144915]Execs [ 131127]EndCycles [ 13759]Preemption Removing....Total nsec of jobs [ 3604781671]Total nsec of exec [ 22222113928]Total nsec considering last clk/msec [ 22222178204]Compute Task Statistics....InterruptsTotal Execution [ 29399348 ns]Mean CPU Bandwidth [ 0.132298% ]after first int [ 0.132298% ]Interrupts Number [ 45842 ]Min Interrupt [ 189 ns]Mean Interrupt [ 641 ns]Max Interrupt [ 13056 ns]Min Arr. Delta [ 1881 ns]Mean Arr. Delta [ 484763 ns]Max Arr. Delta [ 10000131 ns]- System Interrupts: All the hardware interruptsPeriodical Task CTX [ 256] PID [ 9]Total Execution [ 276253550 ns]Mean CPU Bandwidth [ 1.243147% ]after first exec [ 1.630252% ]Execs Number [ 9335 ]Min Exec [ 240 ns]Mean Exec [ 29593 ns]Max Exec [ 91661 ns]Total Job Exec [ 276253550 ns]Jobs Number [ 1695 ]Min Job [ 162239 ns]Mean Job [ 162981 ns]Max Job [ 164505 ns]Min Arr. Delta [ 4411818 ns]Mean Arr. Delta [ 9999965 ns]Max Arr. Delta [ 15588054 ns]- Task 9 "palla0": Soft Task. The CTX 256 was at the beginning assignedto the "main" task. After the task death, the context was free until thenew "palla0" task was created. "main" task context number is converted to1256 to make independent statistics.Background Task CTX [ 264] PID [ 1]Total Execution [ 18583350900 ns]Mean CPU Bandwidth [ 83.625487% ]after first exec [ 83.669980% ]Execs Number [ 16908 ]Min Exec [ 515 ns]Mean Exec [ 1099086 ns]Max Exec [ 9836143 ns]- Task 1 "dummy": The dummy task, it represents the systemidle time, near 83% as mean valueBackground Task CTX [ 272] PID [ 2]Total Execution [ 926 ns]Mean CPU Bandwidth [ 0.000004% ]after first exec [ 100.000000% ]Execs Number [ 1 ]Min Exec [ 926 ns]Mean Exec [ 926 ns]Max Exec [ 926 ns]- Task 2 "shutdown task": Shutting down the system. It closesthe tracer too (this is the reason of only 3 us exec time).Periodical Task CTX [ 280] PID [ 3]Total Execution [ 1445626 ns]Mean CPU Bandwidth [ 0.006505% ]after first exec [ 0.006512% ]Execs Number [ 461 ]Min Exec [ 2583 ns]Mean Exec [ 3135 ns]Max Exec [ 4206 ns]Total Job Exec [ 1445626 ns]Jobs Number [ 461 ]Min Job [ 2583 ns]Mean Job [ 3135 ns]Max Job [ 4206 ns]Min Arr. Delta [ 2021387 ns]Mean Arr. Delta [ 48260445 ns]Max Arr. Delta [ 50010461 ns]- Task 3 "interrupt server": The interrupt contextPeriodical Task CTX [ 288] PID [ 4]Total Execution [ 59506 ns]Mean CPU Bandwidth [ 0.000268% ]after first exec [ 0.000351% ]Execs Number [ 43 ]Min Exec [ 566 ns]Mean Exec [ 1383 ns]Max Exec [ 5850 ns]Total Job Exec [ 59506 ns]Jobs Number [ 17 ]Min Job [ 1217 ns]Mean Job [ 3500 ns]Max Job [ 9358 ns]Min Arr. Delta [ 65650086 ns]Mean Arr. Delta [ 1059090305 ns]Max Arr. Delta [ 7168628909 ns]- Task 4 "key task": The task for keyboard handlerPeriodical Task CTX [ 296] PID [ 5]Total Execution [ 1201275196 ns]Mean CPU Bandwidth [ 5.405765% ]after first exec [ 5.406917% ]Execs Number [ 825 ]Min Exec [ 11133 ns]Mean Exec [ 1456091 ns]Max Exec [ 4500554 ns]Total Job Exec [ 1201275196 ns]Jobs Number [ 223 ]Min Job [ 5296076 ns]Mean Job [ 5386884 ns]Max Job [ 5515636 ns]Min Arr. Delta [ 99757430 ns]Mean Arr. Delta [ 100006689 ns]Max Arr. Delta [ 101061945 ns]- Task 5 "jetctrl" : Soft taskPeriodical Task CTX [ 304] PID [ 6]Total Execution [ 4254996 ns]Mean CPU Bandwidth [ 0.019148% ]after first exec [ 0.019156% ]Execs Number [ 446 ]Min Exec [ 1111 ns]Mean Exec [ 9540 ns]Max Exec [ 18912 ns]Total Job Exec [ 4254996 ns]Jobs Number [ 223 ]Min Job [ 18971 ns]Mean Job [ 19080 ns]Max Job [ 21052 ns]Min Arr. Delta [ 95501610 ns]Mean Arr. Delta [ 99979413 ns]Max Arr. Delta [ 102456106 ns]- Task 6 "jetdummy" : Soft TaskPeriodical Task CTX [ 312] PID [ 7]Total Execution [ 341582118 ns]Mean CPU Bandwidth [ 1.537127% ]after first exec [ 1.537767% ]Execs Number [ 936 ]Min Exec [ 520 ns]Mean Exec [ 364938 ns]Max Exec [ 1300811 ns]Total Job Exec [ 341582118 ns]Jobs Number [ 223 ]Min Job [ 1530776 ns]Mean Job [ 1531758 ns]Max Job [ 1533632 ns]Min Arr. Delta [ 95494710 ns]Mean Arr. Delta [ 99979490 ns]Max Arr. Delta [ 102948741 ns]- Task 7 "jetslice": Soft TaskPeriodical Task CTX [ 320] PID [ 8]Total Execution [ 361875645 ns]Mean CPU Bandwidth [ 1.628448% ]after first exec [ 1.628782% ]Execs Number [ 6750 ]Min Exec [ 2540 ns]Mean Exec [ 53611 ns]Max Exec [ 92451 ns]Total Job Exec [ 361875645 ns]Jobs Number [ 2222 ]Min Job [ 162349 ns]Mean Job [ 162860 ns]Max Job [ 166904 ns]Min Arr. Delta [ 9989352 ns]Mean Arr. Delta [ 9999971 ns]Max Arr. Delta [ 10010719 ns]- Task 8 "pallaEDF": Hard TaskBackground Task CTX [ 1256] PID [ 0]Total Execution [ 4573409 ns]Mean CPU Bandwidth [ 0.020580% ]after first exec [ 0.020580% ]Execs Number [ 6 ]Min Exec [ 7166 ns]Mean Exec [ 762234 ns]Max Exec [ 2000656 ns]- Task 0 "main": Main task was killed, so the original contextnumber 256 became 1256Periodical Task CTX [ 328] PID [ 10]Total Execution [ 270267226 ns]Mean CPU Bandwidth [ 1.216208% ]after first exec [ 1.629184% ]Execs Number [ 8846 ]Min Exec [ 241 ns]Mean Exec [ 30552 ns]Max Exec [ 91592 ns]Total Job Exec [ 270267226 ns]Jobs Number [ 1659 ]Min Job [ 162232 ns]Mean Job [ 162909 ns]Max Job [ 164270 ns]Min Arr. Delta [ 9988124 ns]Mean Arr. Delta [ 9999982 ns]Max Arr. Delta [ 10032718 ns]- Task 10 "palla1": Soft TaskPeriodical Task CTX [ 336] PID [ 11]Total Execution [ 264105857 ns]Mean CPU Bandwidth [ 1.188482% ]after first exec [ 1.630505% ]Execs Number [ 8949 ]Min Exec [ 239 ns]Mean Exec [ 29512 ns]Max Exec [ 91573 ns]Total Job Exec [ 264105857 ns]Jobs Number [ 1620 ]Min Job [ 162271 ns]Mean Job [ 163028 ns]Max Job [ 164299 ns]Min Arr. Delta [ 9989381 ns]Mean Arr. Delta [ 9999964 ns]Max Arr. Delta [ 10010620 ns]- Task 11 "palla2": Soft TaskPeriodical Task CTX [ 344] PID [ 12]Total Execution [ 257971822 ns]Mean CPU Bandwidth [ 1.160879% ]after first exec [ 1.634880% ]Execs Number [ 10631 ]Min Exec [ 238 ns]Mean Exec [ 24265 ns]Max Exec [ 63831 ns]Total Job Exec [ 257971822 ns]Jobs Number [ 1578 ]Min Job [ 162684 ns]Mean Job [ 163480 ns]Max Job [ 164782 ns]Min Arr. Delta [ 9989399 ns]Mean Arr. Delta [ 9999963 ns]Max Arr. Delta [ 10010599 ns]- Task 12 "palla3": Soft TaskPeriodical Task CTX [ 352] PID [ 13]Total Execution [ 250406676 ns]Mean CPU Bandwidth [ 1.126836% ]after first exec [ 1.630586% ]Execs Number [ 8461 ]Min Exec [ 240 ns]Mean Exec [ 29595 ns]Max Exec [ 91753 ns]Total Job Exec [ 250406676 ns]Jobs Number [ 1536 ]Min Job [ 162225 ns]Mean Job [ 163025 ns]Max Job [ 164557 ns]Min Arr. Delta [ 3184436 ns]Mean Arr. Delta [ 9999970 ns]Max Arr. Delta [ 16815492 ns]- Task 13 "palla4": Soft TaskPeriodical Task CTX [ 360] PID [ 14]Total Execution [ 132048658 ns]Mean CPU Bandwidth [ 0.594222% ]after first exec [ 1.631180% ]Execs Number [ 4462 ]Min Exec [ 244 ns]Mean Exec [ 29594 ns]Max Exec [ 91702 ns]Total Job Exec [ 132048658 ns]Jobs Number [ 810 ]Min Job [ 162240 ns]Mean Job [ 163023 ns]Max Job [ 164337 ns]Min Arr. Delta [ 4934088 ns]Mean Arr. Delta [ 9999968 ns]Max Arr. Delta [ 15066505 ns]- Task 14 "palla5": Soft TaskPeriodical Task CTX [ 368] PID [ 15]Total Execution [ 125033245 ns]Mean CPU Bandwidth [ 0.562652% ]after first exec [ 1.632032% ]Execs Number [ 4239 ]Min Exec [ 242 ns]Mean Exec [ 29495 ns]Max Exec [ 91828 ns]Total Job Exec [ 125033245 ns]Jobs Number [ 767 ]Min Job [ 162266 ns]Mean Job [ 163015 ns]Max Job [ 164284 ns]Min Arr. Delta [ 9187114 ns]Mean Arr. Delta [ 9999969 ns]Max Arr. Delta [ 10802835 ns]- Task 15 "palla6": Soft TaskPeriodical Task CTX [ 376] PID [ 16]Total Execution [ 118201550 ns]Mean CPU Bandwidth [ 0.531910% ]after first exec [ 1.631159% ]Execs Number [ 3985 ]Min Exec [ 244 ns]Mean Exec [ 29661 ns]Max Exec [ 91622 ns]Total Job Exec [ 118201550 ns]Jobs Number [ 725 ]Min Job [ 162270 ns]Mean Job [ 163036 ns]Max Job [ 164089 ns]Min Arr. Delta [ 7797571 ns]Mean Arr. Delta [ 9999955 ns]Max Arr. Delta [ 12192001 ns]- Task 16 "palla7": Soft TaskBackground Task CTX [ 248] PID [NODEF]Total Execution [ 7674 ns]Mean CPU Bandwidth [ 0.000035% ]after first exec [ 72.711768% ]Execs Number [ 1 ]Min Exec [ 7674 ns]Mean Exec [ 7674 ns]Max Exec [ 7674 ns]- Global context: the kernel context