WebSVN - shark - Blame - Rev 1509 - /demos/trunk/newtrace/utils/example

Rev	Author	Line No.	Line
1509	giacomo	1	This is an example file from "extract" utility,
1506	giacomo	2	some comments are added to make it clearer.
		3
		4	Jumpball demo (ecp) statistics
		5
1509	giacomo	6	Clk/msec = 651493 (CPU Pentium 3 - 650 MHz)
1506	giacomo	7
1509	giacomo	8	EVT:Log starts at [ 56030135789]
		9	EVT:Log ends at [ 66409519772]
1506	giacomo	10
1509	giacomo	11	Total dTSC [ 10379383983] us [ 15931689]
		12	Events [ 62210]
		13	Execs [ 53868]
		14	EndCycles [ 8317]
1506	giacomo	15
1509	giacomo	16	Exec TSC sum = 10379383983 (100.00)
1506	giacomo	17
		18	Preemption Removing....
		19
		20	Compute Task Statistics....
		21
		22	Background Task CTX [ 0] PID [NODEF]
1509	giacomo	23	Total Execution dTSC [ 329692] us [ 506]
		24	Mean CPU Bandwidth [ 0.003176%]
		25	after first exec [ 0.003176%]
1506	giacomo	26	Execs Number [ 1]
1509	giacomo	27	Min Exec dTSC [ 329692] us [ 506]
		28	Mean Exec dTSC [ 329692] us [ 506]
		29	Max Exec dTSC [ 329692] us [ 506]
1506	giacomo	30
		31	- Unknown context: this is the context running when the tracer
		32	is enabled. Before the first context switch, the tracer doesn't
1509	giacomo	33	know who is executing... 506 unkwnown microseconds
1506	giacomo	34
		35	Interrupts
1509	giacomo	36	Total Execution dTSC [ 41464053] us [ 63644]
		37	Mean CPU Bandwidth [ 0.399485%]
		38	after first int [ 0.399497%]
		39	Interrupts Number [ 15114]
1506	giacomo	40	Min Interrupt dTSC [ 606] us [ 0]
1509	giacomo	41	Mean Interrupt dTSC [ 2743] us [ 4]
		42	Max Interrupt dTSC [ 17730] us [ 27]
1506	giacomo	43
1509	giacomo	44	Min Arr. Delta dTSC [ 1386] us [ 2]
		45	Mean Arr. Delta dTSC [ 686759] us [ 1054]
		46	Max Arr. Delta dTSC [ 7667924] us [ 11769]
1506	giacomo	47
		48	- System Interrupts: All the hardware interrupts
		49
1509	giacomo	50	Periodical Task CTX [ 256] PID [ 9]
		51	Total Execution dTSC [ 86633408] us [ 132976]
		52	Mean CPU Bandwidth [ 0.834668%]
		53	after first exec [ 1.155236%]
		54	Execs Number [ 4799]
		55	Min Exec dTSC [ 604] us [ 0]
		56	Mean Exec dTSC [ 18052] us [ 27]
		57	Max Exec dTSC [ 29466] us [ 45]
1506	giacomo	58
1509	giacomo	59	Total Job Exec dTSC [ 86633408] us [ 132976]
		60	Jobs Number [ 1152]
		61	Min Job dTSC [ 74829] us [ 114]
		62	Mean Job dTSC [ 75202] us [ 115]
		63	Max Job dTSC [ 78716] us [ 120]
1506	giacomo	64
1509	giacomo	65	Min Arr. Delta dTSC [ 6513566] us [ 9997]
		66	Mean Arr. Delta dTSC [ 6514935] us [ 10000]
		67	Max Arr. Delta dTSC [ 6515900] us [ 10001]
1506	giacomo	68
1509	giacomo	69	- Task 9 "palla0": Soft Task. The CTX 256 was at the beginning assigned
		70	to the "main" task. After the task death, the context was free until the
		71	new "palla0" task was created. "main" task context number is converted to
		72	1256 to make independent statistics.
1506	giacomo	73
1509	giacomo	74	Background Task CTX [ 264] PID [ 1]
		75	Total Execution dTSC [ 9204161436] us [ 14127797]
		76	Mean CPU Bandwidth [ 88.677338%]
		77	after first exec [ 88.733345%]
		78	Execs Number [ 7194]
		79	Min Exec dTSC [ 1071] us [ 1]
		80	Mean Exec dTSC [ 1279421] us [ 1963]
		81	Max Exec dTSC [ 6438158] us [ 9882]
		82
		83	- Task 1 "dummy": The dummy task, it represents the system
		84	idle time, near 88% as mean value
		85
		86	Background Task CTX [ 272] PID [ 2]
		87	Total Execution dTSC [ 2319] us [ 3]
		88	Mean CPU Bandwidth [ 0.000022%]
		89	after first exec [ 100.000000%]
		90	Execs Number [ 1]
		91	Min Exec dTSC [ 2319] us [ 3]
		92	Mean Exec dTSC [ 2319] us [ 3]
		93	Max Exec dTSC [ 2319] us [ 3]
		94
		95	- Task 2 "shutdown task": Shutting down the system. It closes
		96	the tracer too (this is the reason of only 3 us exec time).
		97
		98	Periodical Task CTX [ 280] PID [ 3]
		99	Total Execution dTSC [ 1590194] us [ 2440]
		100	Mean CPU Bandwidth [ 0.015321%]
		101	after first exec [ 0.015349%]
		102	Execs Number [ 334]
		103	Min Exec dTSC [ 2864] us [ 4]
		104	Mean Exec dTSC [ 4761] us [ 7]
		105	Max Exec dTSC [ 12342] us [ 18]
		106
		107	Total Job Exec dTSC [ 1590194] us [ 2440]
		108	Jobs Number [ 334]
		109	Min Job dTSC [ 2864] us [ 4]
		110	Mean Job dTSC [ 4761] us [ 7]
		111	Max Job dTSC [ 12342] us [ 18]
		112
		113	Min Arr. Delta dTSC [ 152610] us [ 234]
		114	Mean Arr. Delta dTSC [ 31110745] us [ 47752]
		115	Max Arr. Delta dTSC [ 32575381] us [ 50001]
		116
		117	- Task 3 "interrupt server": The interrupt context
		118
		119	Periodical Task CTX [ 288] PID [ 4]
		120	Total Execution dTSC [ 217057] us [ 333]
		121	Mean CPU Bandwidth [ 0.002091%]
		122	after first exec [ 0.002894%]
		123	Execs Number [ 38]
		124	Min Exec dTSC [ 2107] us [ 3]
		125	Mean Exec dTSC [ 5712] us [ 8]
		126	Max Exec dTSC [ 22741] us [ 34]
		127
		128	Total Job Exec dTSC [ 217057] us [ 333]
		129	Jobs Number [ 15]
		130	Min Job dTSC [ 4683] us [ 7]
		131	Mean Job dTSC [ 14470] us [ 22]
		132	Max Job dTSC [ 31155] us [ 47]
		133
		134	Min Arr. Delta dTSC [ 67049248] us [ 102916]
		135	Mean Arr. Delta dTSC [ 535655892] us [ 822197]
		136	Max Arr. Delta dTSC [ 2822877293] us [ 4332935]
		137
		138	- Task 4 "key task": The task for keyboard handler
		139
1506	giacomo	140	Periodical Task CTX [ 296] PID [ 5]
1509	giacomo	141	Total Execution dTSC [ 441386657] us [ 677500]
		142	Mean CPU Bandwidth [ 4.252532%]
		143	after first exec [ 4.253727%]
		144	Execs Number [ 412]
		145	Min Exec dTSC [ 21950] us [ 33]
		146	Mean Exec dTSC [ 1071326] us [ 1644]
		147	Max Exec dTSC [ 2809349] us [ 4312]
1506	giacomo	148
1509	giacomo	149	Total Job Exec dTSC [ 441386657] us [ 677500]
		150	Jobs Number [ 160]
		151	Min Job dTSC [ 2718736] us [ 4173]
		152	Mean Job dTSC [ 2758666] us [ 4234]
		153	Max Job dTSC [ 2809349] us [ 4312]
1506	giacomo	154
1509	giacomo	155	Min Arr. Delta dTSC [ 65129432] us [ 99969]
		156	Mean Arr. Delta dTSC [ 65149346] us [ 100000]
		157	Max Arr. Delta dTSC [ 65169241] us [ 100030]
1506	giacomo	158
		159	- Task 5 "jetctrl" : Soft task
		160
		161	Periodical Task CTX [ 304] PID [ 6]
1509	giacomo	162	Total Execution dTSC [ 2724296] us [ 4181]
		163	Mean CPU Bandwidth [ 0.026247%]
		164	after first exec [ 0.026262%]
		165	Execs Number [ 320]
		166	Min Exec dTSC [ 2887] us [ 4]
		167	Mean Exec dTSC [ 8513] us [ 13]
		168	Max Exec dTSC [ 14244] us [ 21]
1506	giacomo	169
1509	giacomo	170	Total Job Exec dTSC [ 2724296] us [ 4181]
		171	Jobs Number [ 160]
		172	Min Job dTSC [ 16496] us [ 25]
		173	Mean Job dTSC [ 17026] us [ 26]
		174	Max Job dTSC [ 20264] us [ 31]
1506	giacomo	175
1509	giacomo	176	Min Arr. Delta dTSC [ 62335736] us [ 95681]
		177	Mean Arr. Delta dTSC [ 65131657] us [ 99972]
		178	Max Arr. Delta dTSC [ 67938661] us [ 104281]
1506	giacomo	179
		180	- Task 6 "jetdummy" : Soft Task
		181
		182	Periodical Task CTX [ 312] PID [ 7]
1509	giacomo	183	Total Execution dTSC [ 124817741] us [ 191587]
		184	Mean CPU Bandwidth [ 1.202554%]
		185	after first exec [ 1.203219%]
		186	Execs Number [ 363]
		187	Min Exec dTSC [ 12744] us [ 19]
		188	Mean Exec dTSC [ 343850] us [ 527]
		189	Max Exec dTSC [ 767572] us [ 1178]
1506	giacomo	190
1509	giacomo	191	Total Job Exec dTSC [ 124817741] us [ 191587]
		192	Jobs Number [ 160]
		193	Min Job dTSC [ 778669] us [ 1195]
		194	Mean Job dTSC [ 780110] us [ 1197]
		195	Max Job dTSC [ 783045] us [ 1201]
1506	giacomo	196
1509	giacomo	197	Min Arr. Delta dTSC [ 62314571] us [ 95648]
		198	Mean Arr. Delta dTSC [ 65131531] us [ 99972]
		199	Max Arr. Delta dTSC [ 67955583] us [ 104307]
1506	giacomo	200
		201	- Task 7 "jetslice": Soft Task
		202
		203	Periodical Task CTX [ 320] PID [ 8]
1509	giacomo	204	Total Execution dTSC [ 118468086] us [ 181840]
		205	Mean CPU Bandwidth [ 1.141379%]
		206	after first exec [ 1.141687%]
		207	Execs Number [ 4779]
		208	Min Exec dTSC [ 22161] us [ 34]
		209	Mean Exec dTSC [ 24789] us [ 38]
		210	Max Exec dTSC [ 35479] us [ 54]
1506	giacomo	211
1509	giacomo	212	Total Job Exec dTSC [ 118468086] us [ 181840]
		213	Jobs Number [ 1593]
		214	Min Job dTSC [ 72879] us [ 111]
		215	Mean Job dTSC [ 74367] us [ 114]
		216	Max Job dTSC [ 86971] us [ 133]
1506	giacomo	217
1509	giacomo	218	Min Arr. Delta dTSC [ 6509004] us [ 9990]
		219	Mean Arr. Delta dTSC [ 6514929] us [ 9999]
		220	Max Arr. Delta dTSC [ 6516186] us [ 10001]
1506	giacomo	221
		222	- Task 8 "pallaEDF": Hard Task
		223
1509	giacomo	224	Background Task CTX [ 1256] PID [ 0]
		225	Total Execution dTSC [ 2515575] us [ 3861]
		226	Mean CPU Bandwidth [ 0.024236%]
		227	after first exec [ 0.024237%]
		228	Execs Number [ 4]
		229	Min Exec dTSC [ 24256] us [ 37]
		230	Mean Exec dTSC [ 628893] us [ 965]
		231	Max Exec dTSC [ 1296253] us [ 1989]
1506	giacomo	232
1509	giacomo	233	- Task 0 "main": Main task was killed, so the original context
		234	number 256 became 1256
1506	giacomo	235
		236	Periodical Task CTX [ 328] PID [ 10]
1509	giacomo	237	Total Execution dTSC [ 80067300] us [ 122898]
		238	Mean CPU Bandwidth [ 0.771407%]
		239	after first exec [ 1.145789%]
		240	Execs Number [ 4479]
		241	Min Exec dTSC [ 588] us [ 0]
		242	Mean Exec dTSC [ 17876] us [ 27]
		243	Max Exec dTSC [ 28734] us [ 44]
1506	giacomo	244
1509	giacomo	245	Total Job Exec dTSC [ 80067300] us [ 122898]
		246	Jobs Number [ 1073]
		247	Min Job dTSC [ 74133] us [ 113]
		248	Mean Job dTSC [ 74620] us [ 114]
		249	Max Job dTSC [ 78837] us [ 121]
1506	giacomo	250
1509	giacomo	251	Min Arr. Delta dTSC [ 3437857] us [ 5276]
		252	Mean Arr. Delta dTSC [ 6514935] us [ 10000]
		253	Max Arr. Delta dTSC [ 9593285] us [ 14725]
1506	giacomo	254
		255	- Task 10 "palla1": Soft Task
		256
		257	Periodical Task CTX [ 336] PID [ 11]
1509	giacomo	258	Total Execution dTSC [ 73395130] us [ 112656]
		259	Mean CPU Bandwidth [ 0.707124%]
		260	after first exec [ 1.145517%]
		261	Execs Number [ 4120]
		262	Min Exec dTSC [ 590] us [ 0]
		263	Mean Exec dTSC [ 17814] us [ 27]
		264	Max Exec dTSC [ 28349] us [ 43]
1506	giacomo	265
1509	giacomo	266	Total Job Exec dTSC [ 73395130] us [ 112656]
		267	Jobs Number [ 984]
		268	Min Job dTSC [ 73933] us [ 113]
		269	Mean Job dTSC [ 74588] us [ 114]
		270	Max Job dTSC [ 79602] us [ 122]
1506	giacomo	271
1509	giacomo	272	Min Arr. Delta dTSC [ 4602409] us [ 7064]
		273	Mean Arr. Delta dTSC [ 6514935] us [ 10000]
		274	Max Arr. Delta dTSC [ 8427197] us [ 12935]
1506	giacomo	275
		276	- Task 11 "palla2": Soft Task
		277
		278	Periodical Task CTX [ 344] PID [ 12]
1509	giacomo	279	Total Execution dTSC [ 67886363] us [ 104201]
		280	Mean CPU Bandwidth [ 0.654050%]
		281	after first exec [ 1.158283%]
		282	Execs Number [ 4482]
		283	Min Exec dTSC [ 584] us [ 0]
		284	Mean Exec dTSC [ 15146] us [ 23]
		285	Max Exec dTSC [ 29002] us [ 44]
1506	giacomo	286
1509	giacomo	287	Total Job Exec dTSC [ 67886363] us [ 104201]
		288	Jobs Number [ 900]
		289	Min Job dTSC [ 74442] us [ 114]
		290	Mean Job dTSC [ 75429] us [ 115]
		291	Max Job dTSC [ 79661] us [ 122]
1506	giacomo	292
1509	giacomo	293	Min Arr. Delta dTSC [ 3431395] us [ 5266]
		294	Mean Arr. Delta dTSC [ 6514935] us [ 10000]
		295	Max Arr. Delta dTSC [ 9598324] us [ 14732]
1506	giacomo	296
		297	- Task 12 "palla3": Soft Task
		298
		299	Periodical Task CTX [ 352] PID [ 13]
1509	giacomo	300	Total Execution dTSC [ 61391584] us [ 94232]
		301	Mean CPU Bandwidth [ 0.591476%]
		302	after first exec [ 1.153238%]
		303	Execs Number [ 3402]
		304	Min Exec dTSC [ 588] us [ 0]
1506	giacomo	305	Mean Exec dTSC [ 18045] us [ 27]
1509	giacomo	306	Max Exec dTSC [ 29668] us [ 45]
1506	giacomo	307
1509	giacomo	308	Total Job Exec dTSC [ 61391584] us [ 94232]
		309	Jobs Number [ 818]
		310	Min Job dTSC [ 74694] us [ 114]
		311	Mean Job dTSC [ 75050] us [ 115]
		312	Max Job dTSC [ 78298] us [ 120]
1506	giacomo	313
1509	giacomo	314	Min Arr. Delta dTSC [ 6513915] us [ 9998]
		315	Mean Arr. Delta dTSC [ 6514936] us [ 10000]
		316	Max Arr. Delta dTSC [ 6515731] us [ 10001]
1506	giacomo	317
		318	- Task 13 "palla4": Soft Task
		319
		320	Periodical Task CTX [ 360] PID [ 14]
1509	giacomo	321	Total Execution dTSC [ 39064984] us [ 59962]
		322	Mean CPU Bandwidth [ 0.376371%]
		323	after first exec [ 1.148329%]
		324	Execs Number [ 2176]
		325	Min Exec dTSC [ 588] us [ 0]
		326	Mean Exec dTSC [ 17952] us [ 27]
		327	Max Exec dTSC [ 27187] us [ 41]
1506	giacomo	328
1509	giacomo	329	Total Job Exec dTSC [ 39064984] us [ 59962]
		330	Jobs Number [ 523]
		331	Min Job dTSC [ 74198] us [ 113]
		332	Mean Job dTSC [ 74694] us [ 114]
		333	Max Job dTSC [ 81677] us [ 125]
1506	giacomo	334
1509	giacomo	335	Min Arr. Delta dTSC [ 6506703] us [ 9987]
		336	Mean Arr. Delta dTSC [ 6514934] us [ 10000]
		337	Max Arr. Delta dTSC [ 6523588] us [ 10013]
1506	giacomo	338
		339	- Task 14 "palla5": Soft Task
		340
		341	Periodical Task CTX [ 368] PID [ 15]
1509	giacomo	342	Total Execution dTSC [ 33250615] us [ 51037]
		343	Mean CPU Bandwidth [ 0.320352%]
		344	after first exec [ 1.147064%]
		345	Execs Number [ 1849]
		346	Min Exec dTSC [ 605] us [ 0]
		347	Mean Exec dTSC [ 17983] us [ 27]
		348	Max Exec dTSC [ 29733] us [ 45]
1506	giacomo	349
1509	giacomo	350	Total Job Exec dTSC [ 33250615] us [ 51037]
		351	Jobs Number [ 445]
		352	Min Job dTSC [ 74441] us [ 114]
		353	Mean Job dTSC [ 74720] us [ 114]
		354	Max Job dTSC [ 78640] us [ 120]
1506	giacomo	355
1509	giacomo	356	Min Arr. Delta dTSC [ 6513773] us [ 9998]
		357	Mean Arr. Delta dTSC [ 6514933] us [ 10000]
		358	Max Arr. Delta dTSC [ 6515594] us [ 10001]
1506	giacomo	359
		360	- Task 15 "palla6": Soft Task
		361
		362	Background Task CTX [ 248] PID [NODEF]
1509	giacomo	363	Total Execution dTSC [ 17493] us [ 26]
		364	Mean CPU Bandwidth [ 0.000169%]
		365	after first exec [ 63.226949%]
1506	giacomo	366	Execs Number [ 1]
1509	giacomo	367	Min Exec dTSC [ 17493] us [ 26]
		368	Mean Exec dTSC [ 17493] us [ 26]
		369	Max Exec dTSC [ 17493] us [ 26]
1506	giacomo	370
		371	- Global context: the kernel context

Subversion Repositories shark

(root)/demos/trunk/newtrace/utils/example_results @ 1510 - Rev 1509