1 | # |
---|
2 | # Timing Test Suite Results for the MVME167 |
---|
3 | # |
---|
4 | # $Id$ |
---|
5 | # |
---|
6 | |
---|
7 | Board: MVME167 |
---|
8 | CPU: MC68040 |
---|
9 | Clock Speed: 25 MHz |
---|
10 | Memory Configuration: 4MB DRAM, data/instruction caches on, copyback mode |
---|
11 | Wait States: |
---|
12 | |
---|
13 | Times Reported in: microseconds |
---|
14 | Timer Source: VME Tick Timer 1 |
---|
15 | |
---|
16 | |
---|
17 | *** TIME TEST 1 *** |
---|
18 | rtems_semaphore_create 24 |
---|
19 | rtems_semaphore_delete 22 |
---|
20 | rtems_semaphore_obtain: available 8 |
---|
21 | rtems_semaphore_obtain: not available -- NO_WAIT 8 |
---|
22 | rtems_semaphore_release: no waiting tasks 11 |
---|
23 | *** END OF TEST 1 *** |
---|
24 | |
---|
25 | |
---|
26 | *** TIME TEST 2 *** |
---|
27 | rtems_semaphore_obtain: not available -- caller blocks 36 |
---|
28 | *** END OF TEST 2 *** |
---|
29 | |
---|
30 | |
---|
31 | *** TIME TEST 3 *** |
---|
32 | rtems_semaphore_release: task readied -- preempts caller 28 |
---|
33 | *** END OF TEST 3 *** |
---|
34 | |
---|
35 | |
---|
36 | *** TIME TEST 4 *** |
---|
37 | rtems_task_restart: blocked task -- preempts caller 57 |
---|
38 | rtems_task_restart: ready task -- preempts caller 53 |
---|
39 | rtems_semaphore_release: task readied -- returns to caller 16 |
---|
40 | rtems_task_create 67 |
---|
41 | rtems_task_start 21 |
---|
42 | rtems_task_restart: suspended task -- returns to caller 27 |
---|
43 | rtems_task_delete: suspended task 59 |
---|
44 | rtems_task_restart: ready task -- returns to caller 28 |
---|
45 | rtems_task_restart: blocked task -- returns to caller 35 |
---|
46 | rtems_task_delete: blocked task 60 |
---|
47 | *** END OF TEST 4 *** |
---|
48 | |
---|
49 | |
---|
50 | *** TIME TEST 5 *** |
---|
51 | rtems_task_suspend: calling task 26 |
---|
52 | rtems_task_resume: task readied -- preempts caller 23 |
---|
53 | *** END OF TEST 5 *** |
---|
54 | |
---|
55 | |
---|
56 | *** TIME TEST 6 *** |
---|
57 | rtems_task_restart: calling task 26 |
---|
58 | rtems_task_suspend: returns to caller 11 |
---|
59 | rtems_task_resume: task readied -- returns to caller 12 |
---|
60 | rtems_task_delete: ready task 61 |
---|
61 | *** END OF TEST 6 *** |
---|
62 | |
---|
63 | |
---|
64 | *** TIME TEST 7 *** |
---|
65 | rtems_task_restart: suspended task -- preempts caller 44 |
---|
66 | *** END OF TEST 7 *** |
---|
67 | |
---|
68 | *** TIME TEST 8 *** |
---|
69 | rtems_task_set_priority: obtain current priority 7 |
---|
70 | rtems_task_set_priority: returns to caller 16 |
---|
71 | rtems_task_mode: obtain current mode 4 |
---|
72 | rtems_task_mode: no reschedule 5 |
---|
73 | rtems_task_mode: reschedule -- returns to caller 11 |
---|
74 | rtems_task_mode: reschedule -- preempts caller 29 |
---|
75 | rtems_task_set_note 7 |
---|
76 | rtems_task_get_note 7 |
---|
77 | rtems_clock_set 21 |
---|
78 | rtems_clock_get 0 |
---|
79 | *** END OF TEST 8 *** |
---|
80 | |
---|
81 | |
---|
82 | *** TIME TEST 9 *** |
---|
83 | rtems_message_queue_create 89 |
---|
84 | rtems_message_queue_send: no waiting tasks 18 |
---|
85 | rtems_message_queue_urgent: no waiting tasks 18 |
---|
86 | rtems_message_queue_receive: available 18 |
---|
87 | rtems_message_queue_flush: no messages flushed 6 |
---|
88 | rtems_message_queue_flush: messages flushed 8 |
---|
89 | rtems_message_queue_delete 31 |
---|
90 | *** END OF TEST 9 *** |
---|
91 | |
---|
92 | |
---|
93 | *** TIME TEST 10 *** |
---|
94 | rtems_message_queue_receive: not available -- NO_WAIT 9 |
---|
95 | rtems_message_queue_receive: not available -- caller blocks 37 |
---|
96 | *** END OF TEST 10 *** |
---|
97 | |
---|
98 | |
---|
99 | *** TIME TEST 11 *** |
---|
100 | rtems_message_queue_send: task readied -- preempts caller 34 |
---|
101 | *** END OF TEST 11 *** |
---|
102 | |
---|
103 | |
---|
104 | *** TIME TEST 12 *** |
---|
105 | rtems_message_queue_send: task readied -- returns to caller 22 |
---|
106 | *** END OF TEST 12 *** |
---|
107 | |
---|
108 | |
---|
109 | *** TIME TEST 13 *** |
---|
110 | rtems_message_queue_urgent: task readied -- preempts caller 35 |
---|
111 | *** END OF TEST 13 *** |
---|
112 | |
---|
113 | |
---|
114 | *** TIME TEST 14 *** |
---|
115 | rtems_message_queue_urgent: task readied -- returns to caller 22 |
---|
116 | *** END OF TEST 14 *** |
---|
117 | |
---|
118 | |
---|
119 | *** TIME TEST 15 *** |
---|
120 | rtems_event_receive: obtain current events -1 |
---|
121 | rtems_event_receive: not available -- NO_WAIT 5 |
---|
122 | rtems_event_receive: not available -- caller blocks 30 |
---|
123 | rtems_event_send: no task readied 7 |
---|
124 | rtems_event_receive: available 10 |
---|
125 | rtems_event_send: task readied -- returns to caller 17 |
---|
126 | *** END OF TEST 15 *** |
---|
127 | |
---|
128 | |
---|
129 | *** TIME TEST 16 *** |
---|
130 | rtems_event_send: task readied -- preempts caller 30 |
---|
131 | *** END OF TEST 16 *** |
---|
132 | |
---|
133 | |
---|
134 | *** TIME TEST 17 *** |
---|
135 | rtems_task_set_priority: preempts caller 36 |
---|
136 | *** END OF TEST 17 *** |
---|
137 | |
---|
138 | |
---|
139 | *** TIME TEST 18 *** |
---|
140 | rtems_task_delete: calling task 79 |
---|
141 | *** END OF TEST 18 *** |
---|
142 | |
---|
143 | |
---|
144 | *** TIME TEST 19 *** |
---|
145 | rtems_signal_catch 5 |
---|
146 | rtems_signal_send: returns to caller 15 |
---|
147 | rtems_signal_send: signal to self 24 |
---|
148 | exit ASR overhead: returns to calling task 22 |
---|
149 | exit ASR overhead: returns to preempting task 25 |
---|
150 | *** END OF TEST 19 *** |
---|
151 | |
---|
152 | |
---|
153 | *** TIME TEST 20 *** |
---|
154 | rtems_partition_create 30 |
---|
155 | rtems_region_create 26 |
---|
156 | rtems_partition_get_buffer: available 13 |
---|
157 | rtems_partition_get_buffer: not available 8 |
---|
158 | rtems_partition_return_buffer 15 |
---|
159 | rtems_partition_delete 14 |
---|
160 | rtems_region_get_segment: available 14 |
---|
161 | rtems_region_get_segment: not available -- NO_WAIT 17 |
---|
162 | rtems_region_return_segment: no waiting tasks 15 |
---|
163 | rtems_region_get_segment: not available -- caller blocks 51 |
---|
164 | rtems_region_return_segment: task readied -- preempts caller 60 |
---|
165 | rtems_region_return_segment: task readied -- returns to caller 33 |
---|
166 | rtems_region_delete 14 |
---|
167 | rtems_io_initialize 0 |
---|
168 | rtems_io_open 0 |
---|
169 | rtems_io_close 0 |
---|
170 | rtems_io_read 0 |
---|
171 | rtems_io_write 0 |
---|
172 | rtems_io_control 0 |
---|
173 | *** END OF TEST 20 *** |
---|
174 | |
---|
175 | |
---|
176 | *** TIME TEST 21 *** |
---|
177 | rtems_task_ident 118 |
---|
178 | rtems_message_queue_ident 115 |
---|
179 | rtems_semaphore_ident 132 |
---|
180 | rtems_partition_ident 115 |
---|
181 | rtems_region_ident 117 |
---|
182 | rtems_port_ident 115 |
---|
183 | rtems_timer_ident 116 |
---|
184 | rtems_rate_monotonic_ident 115 |
---|
185 | *** END OF TEST 21 *** |
---|
186 | |
---|
187 | |
---|
188 | *** TIME TEST 22 *** |
---|
189 | rtems_message_queue_broadcast: task readied -- returns to caller 31 |
---|
190 | rtems_message_queue_broadcast: no waiting tasks 12 |
---|
191 | rtems_message_queue_broadcast: task readied -- preempts caller 39 |
---|
192 | *** END OF TEST 22 *** |
---|
193 | |
---|
194 | |
---|
195 | *** TIME TEST 23 *** |
---|
196 | rtems_timer_create 9 |
---|
197 | rtems_timer_fire_after: inactive 17 |
---|
198 | rtems_timer_fire_after: active 18 |
---|
199 | rtems_timer_cancel: active 9 |
---|
200 | rtems_timer_cancel: inactive 8 |
---|
201 | rtems_timer_reset: inactive 16 |
---|
202 | rtems_timer_reset: active 17 |
---|
203 | rtems_timer_fire_when: inactive 22 |
---|
204 | rtems_timer_fire_when: active 23 |
---|
205 | rtems_timer_delete: active 14 |
---|
206 | rtems_timer_delete: inactive 12 |
---|
207 | rtems_task_wake_when 38 |
---|
208 | *** END OF TEST 23 *** |
---|
209 | |
---|
210 | |
---|
211 | *** TIME TEST 24 *** |
---|
212 | rtems_task_wake_after: yield -- returns to caller 5 |
---|
213 | rtems_task_wake_after: yields -- preempts caller 22 |
---|
214 | *** END OF TEST 24 *** |
---|
215 | |
---|
216 | |
---|
217 | *** TIME TEST 25 *** |
---|
218 | rtems_clock_tick 7 |
---|
219 | *** END OF TEST 25 *** |
---|
220 | |
---|
221 | |
---|
222 | *** TIME TEST 26 *** |
---|
223 | _ISR_Disable 1 |
---|
224 | _ISR_Flash 0 |
---|
225 | _ISR_Enable 0 |
---|
226 | _Thread_Disable_dispatch 0 |
---|
227 | _Thread_Enable_dispatch 3 |
---|
228 | _Thread_Set_state 8 |
---|
229 | _Thread_Disptach (NO FP) 19 |
---|
230 | context switch: no floating point contexts 13 |
---|
231 | context switch: self 2 |
---|
232 | context switch: to another task 3 |
---|
233 | fp context switch: restore 1st FP task 15 |
---|
234 | fp context switch: save idle, restore initialized 5 |
---|
235 | fp context switch: save idle, restore idle 16 |
---|
236 | fp context switch: save initialized, restore initialized 5 |
---|
237 | _Thread_Resume 8 |
---|
238 | _Thread_Unblock 6 |
---|
239 | _Thread_Ready 6 |
---|
240 | _Thread_Get 2 |
---|
241 | _Semaphore_Get 2 |
---|
242 | _Thread_Get: invalid id 0 |
---|
243 | *** END OF TEST 26 *** |
---|
244 | |
---|
245 | |
---|
246 | *** TIME TEST 27 *** |
---|
247 | interrupt entry overhead: returns to interrupted task 8 |
---|
248 | interrupt exit overhead: returns to interrupted task 2 |
---|
249 | interrupt entry overhead: returns to nested interrupt 5 |
---|
250 | interrupt exit overhead: returns to nested interrupt 1 |
---|
251 | interrupt entry overhead: returns to preempting task 7 |
---|
252 | interrupt exit overhead: returns to preempting task 30 |
---|
253 | *** END OF TEST 27 *** |
---|
254 | |
---|
255 | |
---|
256 | *** TIME TEST 28 *** |
---|
257 | rtems_port_create 14 |
---|
258 | rtems_port_external_to_internal 5 |
---|
259 | rtems_port_internal_to_external 5 |
---|
260 | rtems_port_delete 16 |
---|
261 | *** END OF TEST 28 *** |
---|
262 | |
---|
263 | |
---|
264 | *** TIME TEST 29 *** |
---|
265 | rtems_rate_monotonic_create 17 |
---|
266 | rtems_rate_monotonic_period: initiate period -- returns to caller 23 |
---|
267 | rtems_rate_monotonic_period: obtain status 11 |
---|
268 | rtems_rate_monotonic_cancel 15 |
---|
269 | rtems_rate_monotonic_delete: inactive 21 |
---|
270 | rtems_rate_monotonic_delete: active 18 |
---|
271 | rtems_rate_monotonic_period: conclude periods -- caller blocks 25 |
---|
272 | *** END OF TEST 29 *** |
---|
273 | |
---|
274 | |
---|
275 | *** INITIAL TIMING TEST RESULTS *** |
---|
276 | |
---|
277 | *** TIME CHECKER *** |
---|
278 | Units may not be in microseconds for this test!!! |
---|
279 | 3 87999 |
---|
280 | 4 12001 |
---|
281 | Total time = 312001 |
---|
282 | Average time = 3 |
---|
283 | <pause> |
---|
284 | NULL timer stopped at 4 |
---|
285 | LOOP (1000) timer stopped at 645 |
---|
286 | LOOP (10000) timer stopped at 6405 |
---|
287 | LOOP (50000) timer stopped at 30020 |
---|
288 | LOOP (100000) timer stopped at 60034 |
---|
289 | *** END OF TIME CHECKER *** |
---|
290 | |
---|
291 | |
---|
292 | *** TIME TEST OVERHEAD *** |
---|
293 | rtems_initialize_executive 0 |
---|
294 | rtems_shutdown_executive 0 |
---|
295 | rtems_task_create 1 |
---|
296 | rtems_task_ident 0 |
---|
297 | rtems_task_start 1 |
---|
298 | rtems_task_restart 0 |
---|
299 | rtems_task_delete 0 |
---|
300 | rtems_task_suspend 0 |
---|
301 | rtems_task_resume 0 |
---|
302 | rtems_task_set_priority 0 |
---|
303 | rtems_task_mode 0 |
---|
304 | rtems_task_get_note 0 |
---|
305 | rtems_task_set_note 0 |
---|
306 | rtems_task_wake_when 1 |
---|
307 | rtems_task_wake_after 0 |
---|
308 | rtems_interrupt_catch 0 |
---|
309 | rtems_clock_get 1 |
---|
310 | rtems_clock_set 1 |
---|
311 | rtems_clock_tick 0 |
---|
312 | <pause> |
---|
313 | rtems_timer_create 0 |
---|
314 | rtems_timer_delete 0 |
---|
315 | rtems_timer_ident 0 |
---|
316 | rtems_timer_fire_after 0 |
---|
317 | rtems_timer_fire_when 1 |
---|
318 | rtems_timer_reset 0 |
---|
319 | rtems_timer_cancel 0 |
---|
320 | rtems_semaphore_create 1 |
---|
321 | rtems_semaphore_delete 0 |
---|
322 | rtems_semaphore_ident 0 |
---|
323 | rtems_semaphore_obtain 0 |
---|
324 | rtems_semaphore_release 0 |
---|
325 | rtems_message_queue_create 1 |
---|
326 | rtems_message_queue_ident 0 |
---|
327 | rtems_message_queue_delete 0 |
---|
328 | rtems_message_queue_send 0 |
---|
329 | rtems_message_queue_urgent 0 |
---|
330 | rtems_message_queue_broadcast 0 |
---|
331 | rtems_message_queue_receive 0 |
---|
332 | rtems_message_queue_flush 0 |
---|
333 | <pause> |
---|
334 | rtems_event_send 0 |
---|
335 | rtems_event_receive 0 |
---|
336 | rtems_signal_catch 0 |
---|
337 | rtems_signal_send 0 |
---|
338 | rtems_partition_create 1 |
---|
339 | rtems_partition_ident 0 |
---|
340 | rtems_partition_delete 0 |
---|
341 | rtems_partition_get_buffer 0 |
---|
342 | rtems_partition_return_buffer 0 |
---|
343 | rtems_region_create 1 |
---|
344 | rtems_region_ident 0 |
---|
345 | rtems_region_delete 0 |
---|
346 | rtems_region_get_segment 1 |
---|
347 | rtems_region_return_segment 0 |
---|
348 | rtems_port_create 1 |
---|
349 | rtems_port_ident 0 |
---|
350 | rtems_port_delete 0 |
---|
351 | rtems_port_external_to_internal 0 |
---|
352 | rtems_port_internal_to_external 0 |
---|
353 | <pause> |
---|
354 | rtems_io_initialize 1 |
---|
355 | rtems_io_open 1 |
---|
356 | rtems_io_close 1 |
---|
357 | rtems_io_read 1 |
---|
358 | rtems_io_write 1 |
---|
359 | rtems_io_control 1 |
---|
360 | rtems_fatal_error_occurred 0 |
---|
361 | rtems_rate_monotonic_create 0 |
---|
362 | rtems_rate_monotonic_ident 0 |
---|
363 | rtems_rate_monotonic_delete 0 |
---|
364 | rtems_rate_monotonic_cancel 0 |
---|
365 | rtems_rate_monotonic_period 0 |
---|
366 | rtems_multiprocessing_announce 0 |
---|
367 | *** END OF TIME OVERHEAD *** |
---|