| 1 |
1026 |
ivang |
#
|
| 2 |
|
|
# Timing Test Suite Results for the MVME167
|
| 3 |
|
|
#
|
| 4 |
|
|
# times,v 1.2 2000/08/11 19:26:31 joel Exp
|
| 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 |
|
|
|
| 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 |
|
|
|
| 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 |
|
|
|
| 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 |
|
|
|
| 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 ***
|
