ivan@137: /**************************************************************************
ivan@137:  *
ivan@137:  * File name: ompprof.c
ivan@137:  *
ivan@137:  * Ron Rubinstein
ivan@137:  * Computer Science Department
ivan@137:  * Technion, Haifa 32000 Israel
ivan@137:  * ronrubin@cs
ivan@137:  *
ivan@137:  * Last Updated: 11.4.2009
ivan@137:  *
ivan@137:  *************************************************************************/
ivan@137: 
ivan@137: 
ivan@137: #include "ompprof.h"
ivan@137: 
ivan@137: 
ivan@137: /* initialize profiling information */
ivan@137: 
ivan@137: void initprofdata(profdata *pd)
ivan@137: {
ivan@137:   pd->DtX_time = 0;
ivan@137:   pd->XtX_time = 0;
ivan@137:   pd->DtR_time = 0;
ivan@137:   pd->maxabs_time = 0;
ivan@137:   pd->DtD_time = 0;
ivan@137:   pd->Lchol_time = 0;
ivan@137:   pd->compcoef_time = 0;
ivan@137:   pd->update_DtR_time = 0;
ivan@137:   pd->update_resnorm_time = 0;
ivan@137:   pd->compres_time = 0;
ivan@137:   pd->indexsort_time = 0;
ivan@137:   
ivan@137:   pd->DtX_time_counted = 0;
ivan@137:   pd->XtX_time_counted = 0;
ivan@137:   pd->DtR_time_counted = 0;
ivan@137:   pd->DtD_time_counted = 0;
ivan@137:   pd->update_DtR_time_counted = 0;
ivan@137:   pd->resnorm_time_counted = 0;
ivan@137:   pd->compres_time_counted = 0;
ivan@137:   pd->indexsort_time_counted = 0;
ivan@137:   
ivan@137:   pd->prevtime = clock();
ivan@137: }
ivan@137: 
ivan@137: 
ivan@137: /* add elapsed time to profiling data according to specified computation */
ivan@137: 
ivan@137: void addproftime(profdata *pd, int comptype)
ivan@137: {
ivan@137:   switch(comptype) {
ivan@137:     case DtX_TIME:            pd->DtX_time            += clock()-pd->prevtime; pd->DtX_time_counted = 1; break;
ivan@137:     case XtX_TIME:            pd->XtX_time            += clock()-pd->prevtime; pd->XtX_time_counted = 1; break;
ivan@137:     case DtR_TIME:            pd->DtR_time            += clock()-pd->prevtime; pd->DtR_time_counted = 1; break;
ivan@137:     case DtD_TIME:            pd->DtD_time            += clock()-pd->prevtime; pd->DtD_time_counted = 1; break;
ivan@137:     case COMPRES_TIME:        pd->compres_time        += clock()-pd->prevtime; pd->compres_time_counted = 1; break;
ivan@137:     case UPDATE_DtR_TIME:     pd->update_DtR_time     += clock()-pd->prevtime; pd->update_DtR_time_counted = 1; break;
ivan@137:     case UPDATE_RESNORM_TIME: pd->update_resnorm_time += clock()-pd->prevtime; pd->resnorm_time_counted = 1; break;
ivan@137:     case INDEXSORT_TIME:      pd->indexsort_time      += clock()-pd->prevtime; pd->indexsort_time_counted = 1; break;
ivan@137:     case MAXABS_TIME:         pd->maxabs_time         += clock()-pd->prevtime; break;
ivan@137:     case LCHOL_TIME:          pd->Lchol_time          += clock()-pd->prevtime; break;
ivan@137:     case COMPCOEF_TIME:       pd->compcoef_time       += clock()-pd->prevtime; break;
ivan@137:   }
ivan@137:   pd->prevtime = clock();
ivan@137: }
ivan@137: 
ivan@137: 
ivan@137: /* print profiling info */
ivan@137: 
ivan@137: void printprofinfo(profdata *pd, int erroromp, int batchomp, int signum)
ivan@137: {
ivan@137:   clock_t tottime;
ivan@137:   
ivan@137:   tottime = pd->DtX_time + pd->XtX_time + pd->DtR_time + pd->DtD_time + pd->compres_time + pd->maxabs_time + 
ivan@137:             pd->Lchol_time + pd->compcoef_time + pd->update_DtR_time + pd->update_resnorm_time + pd->indexsort_time;
ivan@137:   
ivan@137:   mexPrintf("\n\n*****  Profiling information for %s  *****\n\n", erroromp? "OMP2" : "OMP");
ivan@137:   
ivan@137:   mexPrintf("OMP mode: %s\n\n", batchomp? "Batch-OMP" : "OMP-Cholesky");
ivan@137:   
ivan@137:   mexPrintf("Total signals processed: %d\n\n", signum);
ivan@137:   
ivan@137:   if (pd->DtX_time_counted) {
ivan@137:     mexPrintf("Compute DtX time:      %7.3lf seconds\n", pd->DtX_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   if (pd->XtX_time_counted) {
ivan@137:     mexPrintf("Compute XtX time:      %7.3lf seconds\n", pd->XtX_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   mexPrintf("Max abs time:          %7.3lf seconds\n", pd->maxabs_time/(double)CLOCKS_PER_SEC);
ivan@137:   if (pd->DtD_time_counted) {
ivan@137:     mexPrintf("Compute DtD time:      %7.3lf seconds\n", pd->DtD_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   mexPrintf("Lchol update time:     %7.3lf seconds\n", pd->Lchol_time/(double)CLOCKS_PER_SEC);
ivan@137:   mexPrintf("Compute coef time:     %7.3lf seconds\n", pd->compcoef_time/(double)CLOCKS_PER_SEC);
ivan@137:   if (pd->compres_time_counted) {
ivan@137:     mexPrintf("Compute R time:        %7.3lf seconds\n", pd->compres_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   if (pd->DtR_time_counted) {
ivan@137:     mexPrintf("Compute DtR time:      %7.3lf seconds\n", pd->DtR_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   if (pd->update_DtR_time_counted) {
ivan@137:     mexPrintf("Update DtR time:       %7.3lf seconds\n", pd->update_DtR_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   if (pd->resnorm_time_counted) {
ivan@137:     mexPrintf("Update resnorm time:   %7.3lf seconds\n", pd->update_resnorm_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   if (pd->indexsort_time_counted) {
ivan@137:     mexPrintf("Index sort time:       %7.3lf seconds\n", pd->indexsort_time/(double)CLOCKS_PER_SEC);
ivan@137:   }
ivan@137:   mexPrintf("---------------------------------------\n");
ivan@137:   mexPrintf("Total time:            %7.3lf seconds\n\n", tottime/(double)CLOCKS_PER_SEC);
ivan@137: }
ivan@137: