ghc/rts/Ticky.c

   1 /* -----------------------------------------------------------------------------
   2  * $Id: Ticky.c,v 1.18 2004/08/13 13:11:08 simonmar Exp $
   3  *
   4  * (c) The AQUA project, Glasgow University, 1992-1997
   5  * (c) The GHC Team, 1998-1999
   6  *
   7  * Ticky-ticky profiling
   8  *-------------------------------------------------------------------------- */
   9
  10 #if defined(TICKY_TICKY)
  11
  12 #define TICKY_C                 /* define those variables */
  13 #include "PosixSource.h"
  14 #include "Rts.h"
  15 #include "RtsFlags.h"
  16 #include "Ticky.h"
  17
  18 /* -----------------------------------------------------------------------------
  19    Print out all the counters
  20    -------------------------------------------------------------------------- */
  21
  22 static void printRegisteredCounterInfo (FILE *); /* fwd decl */
  23
  24 #define INTAVG(a,b) ((b == 0) ? 0.0 : ((double) (a) / (double) (b)))
  25 #define PC(a)       (100.0 * a)
  26
  27 #define AVG(thing) \
  28         StgDouble avg##thing  = INTAVG(tot##thing,ctr##thing)
  29
  30 void
  31 PrintTickyInfo(void)
  32 {
  33   unsigned long i;
  34   unsigned long tot_allocs = /* total number of things allocated */
  35         ALLOC_FUN_ctr + ALLOC_SE_THK_ctr + ALLOC_UP_THK_ctr + ALLOC_CON_ctr + ALLOC_TUP_ctr +
  36         + ALLOC_TSO_ctr + ALLOC_BH_ctr  + ALLOC_PAP_ctr + ALLOC_PRIM_ctr
  37 #ifdef PAR
  38         + ALLOC_FMBQ_ctr + ALLOC_FME_ctr + ALLOC_BF_ctr
  39 #endif
  40       ;
  41
  42   unsigned long tot_adm_wds = /* total number of admin words allocated */
  43         ALLOC_FUN_adm + ALLOC_THK_adm + ALLOC_CON_adm + ALLOC_TUP_adm
  44         + ALLOC_TSO_adm + ALLOC_BH_adm  + ALLOC_PAP_adm + ALLOC_PRIM_adm
  45 #ifdef PAR
  46         + ALLOC_FMBQ_adm + ALLOC_FME_adm + ALLOC_BF_adm
  47 #endif
  48       ;
  49
  50   unsigned long tot_gds_wds = /* total number of words of ``good stuff'' allocated */
  51         ALLOC_FUN_gds + ALLOC_THK_gds + ALLOC_CON_gds + ALLOC_TUP_gds
  52         + ALLOC_TSO_gds + ALLOC_BH_gds  + ALLOC_PAP_gds + ALLOC_PRIM_gds
  53 #ifdef PAR
  54         + ALLOC_FMBQ_gds + ALLOC_FME_gds + ALLOC_BF_gds
  55 #endif
  56       ;
  57
  58   unsigned long tot_slp_wds = /* total number of ``slop'' words allocated */
  59         ALLOC_FUN_slp + ALLOC_THK_slp + ALLOC_CON_slp + ALLOC_TUP_slp
  60         + ALLOC_TSO_slp + ALLOC_BH_slp  + ALLOC_PAP_slp + ALLOC_PRIM_slp
  61 #ifdef PAR
  62         + ALLOC_FMBQ_slp + ALLOC_FME_slp + ALLOC_BF_slp
  63 #endif
  64       ;
  65
  66   unsigned long tot_wds = /* total words */
  67         tot_adm_wds + tot_gds_wds + tot_slp_wds;
  68
  69   unsigned long tot_thk_enters = ENT_STATIC_THK_ctr + ENT_DYN_THK_ctr;
  70   unsigned long tot_con_enters = ENT_STATIC_CON_ctr + ENT_DYN_CON_ctr;
  71   unsigned long tot_fun_direct_enters = ENT_STATIC_FUN_DIRECT_ctr + ENT_DYN_FUN_DIRECT_ctr;
  72   unsigned long tot_ind_enters = ENT_STATIC_IND_ctr + ENT_DYN_IND_ctr;
  73
  74   // This is the number of times we entered a function via some kind
  75   // of slow call.  It amounts to all the slow applications, not
  76   // counting those that were to too few arguments.
  77   unsigned long tot_fun_slow_enters =
  78       SLOW_CALL_ctr -
  79       SLOW_CALL_FUN_TOO_FEW_ctr -
  80       SLOW_CALL_PAP_TOO_FEW_ctr;
  81
  82   unsigned long tot_known_calls =
  83       KNOWN_CALL_ctr + KNOWN_CALL_TOO_FEW_ARGS_ctr +
  84       + KNOWN_CALL_EXTRA_ARGS_ctr;
  85   unsigned long tot_tail_calls =
  86       UNKNOWN_CALL_ctr + tot_known_calls;
  87
  88   unsigned long tot_enters =
  89         tot_con_enters + tot_fun_direct_enters +
  90         tot_ind_enters + ENT_PERM_IND_ctr + ENT_PAP_ctr + tot_thk_enters;
  91   unsigned long jump_direct_enters =
  92         tot_enters - ENT_VIA_NODE_ctr;
  93
  94   unsigned long tot_returns =
  95       RET_NEW_ctr + RET_OLD_ctr + RET_UNBOXED_TUP_ctr;
  96
  97   unsigned long tot_returns_of_new = RET_NEW_ctr;
  98
  99   unsigned long con_updates = UPD_CON_IN_NEW_ctr + UPD_CON_IN_PLACE_ctr;
 100   unsigned long pap_updates = UPD_PAP_IN_NEW_ctr + UPD_PAP_IN_PLACE_ctr;
 101
 102   unsigned long tot_updates = UPD_SQUEEZED_ctr + pap_updates + con_updates;
 103
 104   unsigned long tot_new_updates   = UPD_NEW_IND_ctr + UPD_NEW_PERM_IND_ctr;
 105   unsigned long tot_old_updates   = UPD_OLD_IND_ctr + UPD_OLD_PERM_IND_ctr;
 106   unsigned long tot_gengc_updates = tot_new_updates + tot_old_updates;
 107
 108   FILE *tf = RtsFlags.TickyFlags.tickyFile;
 109
 110   fprintf(tf,"\n\nALLOCATIONS: %ld (%ld words total: %ld admin, %ld goods, %ld slop)\n",
 111           tot_allocs, tot_wds, tot_adm_wds, tot_gds_wds, tot_slp_wds);
 112   fprintf(tf,"\t\t\t\ttotal words:\t    2     3     4     5    6+\n");
 113
 114 #define ALLOC_HISTO_MAGIC(categ) \
 115         (PC(INTAVG(ALLOC_##categ##_hst[0], ALLOC_##categ##_ctr))), \
 116         (PC(INTAVG(ALLOC_##categ##_hst[1], ALLOC_##categ##_ctr))), \
 117         (PC(INTAVG(ALLOC_##categ##_hst[2], ALLOC_##categ##_ctr))), \
 118         (PC(INTAVG(ALLOC_##categ##_hst[3], ALLOC_##categ##_ctr))), \
 119         (PC(INTAVG(ALLOC_##categ##_hst[4], ALLOC_##categ##_ctr)))
 120
 121   fprintf(tf,"%7ld (%5.1f%%) function values",
 122         ALLOC_FUN_ctr,
 123         PC(INTAVG(ALLOC_FUN_ctr, tot_allocs)));
 124   if (ALLOC_FUN_ctr != 0)
 125       fprintf(tf,"\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(FUN));
 126
 127   fprintf(tf,"\n%7ld (%5.1f%%) thunks",
 128         ALLOC_SE_THK_ctr + ALLOC_UP_THK_ctr,
 129         PC(INTAVG(ALLOC_SE_THK_ctr + ALLOC_UP_THK_ctr, tot_allocs)));
 130
 131 #define ALLOC_THK_ctr (ALLOC_UP_THK_ctr + ALLOC_SE_THK_ctr)
 132   /* hack to make ALLOC_HISTO_MAGIC still work for THK */
 133   if ((ALLOC_SE_THK_ctr + ALLOC_UP_THK_ctr) != 0)
 134       fprintf(tf,"\t\t\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(THK));
 135 #undef ALLOC_THK_ctr
 136
 137   fprintf(tf,"\n%7ld (%5.1f%%) data values",
 138         ALLOC_CON_ctr,
 139         PC(INTAVG(ALLOC_CON_ctr, tot_allocs)));
 140   if (ALLOC_CON_ctr != 0)
 141       fprintf(tf,"\t\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(CON));
 142
 143   fprintf(tf,"\n%7ld (%5.1f%%) big tuples",
 144         ALLOC_TUP_ctr,
 145         PC(INTAVG(ALLOC_TUP_ctr, tot_allocs)));
 146   if (ALLOC_TUP_ctr != 0)
 147       fprintf(tf,"\t\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(TUP));
 148
 149   fprintf(tf,"\n%7ld (%5.1f%%) black holes",
 150         ALLOC_BH_ctr,
 151         PC(INTAVG(ALLOC_BH_ctr, tot_allocs)));
 152   if (ALLOC_BH_ctr != 0)
 153       fprintf(tf,"\t\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(BH));
 154
 155   fprintf(tf,"\n%7ld (%5.1f%%) prim things",
 156         ALLOC_PRIM_ctr,
 157         PC(INTAVG(ALLOC_PRIM_ctr, tot_allocs)));
 158   if (ALLOC_PRIM_ctr != 0)
 159       fprintf(tf,"\t\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(PRIM));
 160
 161   fprintf(tf,"\n%7ld (%5.1f%%) partial applications",
 162         ALLOC_PAP_ctr,
 163         PC(INTAVG(ALLOC_PAP_ctr, tot_allocs)));
 164   if (ALLOC_PAP_ctr != 0)
 165       fprintf(tf,"\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(PAP));
 166
 167   fprintf(tf,"\n%7ld (%5.1f%%) thread state objects",
 168         ALLOC_TSO_ctr,
 169         PC(INTAVG(ALLOC_TSO_ctr, tot_allocs)));
 170   if (ALLOC_TSO_ctr != 0)
 171       fprintf(tf,"\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(TSO));
 172 #ifdef PAR
 173   fprintf(tf,"\n%7ld (%5.1f%%) thread state objects",
 174         ALLOC_FMBQ_ctr,
 175         PC(INTAVG(ALLOC_FMBQ_ctr, tot_allocs)));
 176   if (ALLOC_FMBQ_ctr != 0)
 177       fprintf(tf,"\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(FMBQ));
 178   fprintf(tf,"\n%7ld (%5.1f%%) thread state objects",
 179         ALLOC_FME_ctr,
 180         PC(INTAVG(ALLOC_FME_ctr, tot_allocs)));
 181   if (ALLOC_FME_ctr != 0)
 182       fprintf(tf,"\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(FME));
 183   fprintf(tf,"\n%7ld (%5.1f%%) thread state objects",
 184         ALLOC_BF_ctr,
 185         PC(INTAVG(ALLOC_BF_ctr, tot_allocs)));
 186   if (ALLOC_BF_ctr != 0)
 187       fprintf(tf,"\t\t%5.1f %5.1f %5.1f %5.1f %5.1f", ALLOC_HISTO_MAGIC(BF));
 188 #endif
 189   fprintf(tf,"\n");
 190
 191   fprintf(tf,"\nTotal storage-manager allocations: %ld (%ld words)\n\t[%ld words lost to speculative heap-checks]\n", ALLOC_HEAP_ctr, ALLOC_HEAP_tot, ALLOC_HEAP_tot - tot_wds);
 192
 193   fprintf(tf,"\nSTACK USAGE:\n"); /* NB: some bits are direction sensitive */
 194
 195   fprintf(tf,"\nENTERS: %ld  of which %ld (%.1f%%) direct to the entry code\n\t\t  [the rest indirected via Node's info ptr]\n",
 196         tot_enters,
 197         jump_direct_enters,
 198         PC(INTAVG(jump_direct_enters,tot_enters)));
 199   fprintf(tf,"%7ld (%5.1f%%) thunks\n",
 200         tot_thk_enters,
 201         PC(INTAVG(tot_thk_enters,tot_enters)));
 202   fprintf(tf,"%7ld (%5.1f%%) data values\n",
 203         tot_con_enters,
 204         PC(INTAVG(tot_con_enters,tot_enters)));
 205   fprintf(tf,"%7ld (%5.1f%%) normal indirections\n",
 206         tot_ind_enters,
 207         PC(INTAVG(tot_ind_enters,tot_enters)));
 208   fprintf(tf,"%7ld (%5.1f%%) permanent indirections\n",
 209         ENT_PERM_IND_ctr,
 210         PC(INTAVG(ENT_PERM_IND_ctr,tot_enters)));
 211
 212   fprintf(tf,"\nFUNCTION ENTRIES: %ld\n", tot_fun_direct_enters);
 213
 214   fprintf(tf, "\nTAIL CALLS: %ld, of which %ld (%.lf%%) were to known functions\n",
 215           tot_tail_calls, tot_known_calls,
 216           PC(INTAVG(tot_known_calls,tot_tail_calls)));
 217
 218   fprintf(tf, "\nSLOW APPLICATIONS: %ld evaluated, %ld unevaluated\n",
 219           SLOW_CALL_ctr, SLOW_CALL_UNEVALD_ctr);
 220   fprintf(tf, "\n");
 221   fprintf(tf, "         Too few args   Correct args   Too many args\n");
 222   fprintf(tf, "   FUN     %8ld       %8ld        %8ld\n",
 223           SLOW_CALL_FUN_TOO_FEW_ctr, SLOW_CALL_FUN_CORRECT_ctr, SLOW_CALL_FUN_TOO_MANY_ctr);
 224   fprintf(tf, "   PAP     %8ld       %8ld        %8ld\n",
 225           SLOW_CALL_PAP_TOO_FEW_ctr, SLOW_CALL_PAP_CORRECT_ctr, SLOW_CALL_PAP_TOO_MANY_ctr);
 226   fprintf(tf, "\n");
 227
 228   fprintf(tf,"\nRETURNS: %ld\n", tot_returns);
 229   fprintf(tf,"%7ld (%5.1f%%) from entering a new constructor\n\t\t  [the rest from entering an existing constructor]\n",
 230         tot_returns_of_new,
 231         PC(INTAVG(tot_returns_of_new,tot_returns)));
 232   fprintf(tf,"%7ld (%5.1f%%) vectored [the rest unvectored]\n",
 233         VEC_RETURN_ctr,
 234         PC(INTAVG(VEC_RETURN_ctr,tot_returns)));
 235
 236   fprintf(tf, "\nRET_NEW:         %7ld: ", RET_NEW_ctr);
 237   for (i = 0; i < 9; i++) { fprintf(tf, "%5.1f%%",
 238                                 PC(INTAVG(RET_NEW_hst[i],RET_NEW_ctr))); }
 239   fprintf(tf, "\n");
 240   fprintf(tf, "RET_OLD:         %7ld: ", RET_OLD_ctr);
 241   for (i = 0; i < 9; i++) { fprintf(tf, "%5.1f%%",
 242                                 PC(INTAVG(RET_OLD_hst[i],RET_OLD_ctr))); }
 243   fprintf(tf, "\n");
 244   fprintf(tf, "RET_UNBOXED_TUP: %7ld: ", RET_UNBOXED_TUP_ctr);
 245   for (i = 0; i < 9; i++) { fprintf(tf, "%5.1f%%",
 246                                     PC(INTAVG(RET_UNBOXED_TUP_hst[i],
 247                                               RET_UNBOXED_TUP_ctr))); }
 248   fprintf(tf, "\n");
 249   fprintf(tf, "\nRET_VEC_RETURN : %7ld: ", VEC_RETURN_ctr);
 250   for (i = 0; i < 9; i++) { fprintf(tf, "%5.1f%%",
 251                                 PC(INTAVG(RET_VEC_RETURN_hst[i],VEC_RETURN_ctr))); }
 252   fprintf(tf, "\n");
 253
 254   fprintf(tf,"\nUPDATE FRAMES: %ld (%ld omitted from thunks)",
 255         UPDF_PUSHED_ctr,
 256         UPDF_OMITTED_ctr);
 257
 258   fprintf(tf,"\nCATCH FRAMES:  %ld", CATCHF_PUSHED_ctr);
 259
 260   if (UPDF_RCC_PUSHED_ctr != 0)
 261      fprintf(tf,"%7ld restore cost centre frames (%ld omitted)\n",
 262         UPDF_RCC_PUSHED_ctr,
 263         UPDF_RCC_OMITTED_ctr);
 264
 265   fprintf(tf,"\nUPDATES: %ld\n", tot_updates);
 266   fprintf(tf,"%7ld (%5.1f%%) data values\n\t\t  [%ld in place, %ld allocated new space]\n",
 267         con_updates,
 268         PC(INTAVG(con_updates,tot_updates)),
 269         UPD_CON_IN_PLACE_ctr, UPD_CON_IN_NEW_ctr);
 270   fprintf(tf,"%7ld (%5.1f%%) partial applications\n\t\t  [%ld in place, %ld allocated new space]\n",
 271         pap_updates,
 272         PC(INTAVG(pap_updates,tot_updates)),
 273         UPD_PAP_IN_PLACE_ctr, UPD_PAP_IN_NEW_ctr);
 274   fprintf(tf,"%7ld (%5.1f%%) updates by squeezing\n",
 275         UPD_SQUEEZED_ctr,
 276         PC(INTAVG(UPD_SQUEEZED_ctr, tot_updates)));
 277
 278   fprintf(tf, "\nUPD_CON_IN_NEW:   %7ld: ", UPD_CON_IN_NEW_ctr);
 279   for (i = 0; i < 9; i++) { fprintf(tf, "%7ld", UPD_CON_IN_NEW_hst[i]); }
 280   fprintf(tf, "\n");
 281   fprintf(tf, "UPD_CON_IN_PLACE: %7ld: ", UPD_CON_IN_PLACE_ctr);
 282   for (i = 0; i < 9; i++) { fprintf(tf, "%7ld", UPD_CON_IN_PLACE_hst[i]); }
 283   fprintf(tf, "\n");
 284   fprintf(tf, "UPD_PAP_IN_NEW:   %7ld: ", UPD_PAP_IN_NEW_ctr);
 285   for (i = 0; i < 9; i++) { fprintf(tf, "%7ld", UPD_PAP_IN_NEW_hst[i]); }
 286   fprintf(tf, "\n");
 287
 288   if (tot_gengc_updates != 0) {
 289       fprintf(tf,"\nNEW GEN UPDATES: %9ld (%5.1f%%)\n",
 290               tot_new_updates,
 291               PC(INTAVG(tot_new_updates,tot_gengc_updates)));
 292       fprintf(tf,"OLD GEN UPDATES: %9ld (%5.1f%%)\n",
 293               tot_old_updates,
 294               PC(INTAVG(tot_old_updates,tot_gengc_updates)));
 295   }
 296
 297   fprintf(tf,"\nTotal bytes copied during GC: %ld\n",
 298           GC_WORDS_COPIED_ctr * sizeof(W_));
 299
 300   printRegisteredCounterInfo(tf);
 301
 302   fprintf(tf,"\n**************************************************\n");
 303
 304   /* here, we print out all the raw numbers; these are really
 305     more useful when we want to snag them for subsequent
 306     rdb-etc processing. WDP 95/11
 307   */
 308
 309 #define PR_CTR(ctr) \
 310   do { fprintf(tf,"%7ld " #ctr "\n", ctr); } while(0)
 311 /* COND_PR_CTR takes a boolean; if false then msg is the printname rather than ctr */
 312 #define COND_PR_CTR(ctr,b,msg) \
 313     if (b) { fprintf(tf,"%7ld " #ctr "\n", ctr); } else { fprintf(tf,"%7ld " msg "\n", ctr); }
 314 #define PR_HST(hst,i) \
 315   do { fprintf(tf,"%7ld " #hst "_" #i "\n", hst[i]); } while(0)
 316
 317   PR_CTR(ALLOC_HEAP_ctr);
 318   PR_CTR(ALLOC_HEAP_tot);
 319
 320   PR_CTR(ALLOC_FUN_ctr);
 321   PR_CTR(ALLOC_FUN_adm);
 322   PR_CTR(ALLOC_FUN_gds);
 323   PR_CTR(ALLOC_FUN_slp);
 324   PR_HST(ALLOC_FUN_hst,0);
 325   PR_HST(ALLOC_FUN_hst,1);
 326   PR_HST(ALLOC_FUN_hst,2);
 327   PR_HST(ALLOC_FUN_hst,3);
 328   PR_HST(ALLOC_FUN_hst,4);
 329   PR_CTR(ALLOC_UP_THK_ctr);
 330   PR_CTR(ALLOC_SE_THK_ctr);
 331   PR_CTR(ALLOC_THK_adm);
 332   PR_CTR(ALLOC_THK_gds);
 333   PR_CTR(ALLOC_THK_slp);
 334   PR_HST(ALLOC_THK_hst,0);
 335   PR_HST(ALLOC_THK_hst,1);
 336   PR_HST(ALLOC_THK_hst,2);
 337   PR_HST(ALLOC_THK_hst,3);
 338   PR_HST(ALLOC_THK_hst,4);
 339   PR_CTR(ALLOC_CON_ctr);
 340   PR_CTR(ALLOC_CON_adm);
 341   PR_CTR(ALLOC_CON_gds);
 342   PR_CTR(ALLOC_CON_slp);
 343   PR_HST(ALLOC_CON_hst,0);
 344   PR_HST(ALLOC_CON_hst,1);
 345   PR_HST(ALLOC_CON_hst,2);
 346   PR_HST(ALLOC_CON_hst,3);
 347   PR_HST(ALLOC_CON_hst,4);
 348   PR_CTR(ALLOC_TUP_ctr);
 349   PR_CTR(ALLOC_TUP_adm);
 350   PR_CTR(ALLOC_TUP_gds);
 351   PR_CTR(ALLOC_TUP_slp);
 352   PR_HST(ALLOC_TUP_hst,0);
 353   PR_HST(ALLOC_TUP_hst,1);
 354   PR_HST(ALLOC_TUP_hst,2);
 355   PR_HST(ALLOC_TUP_hst,3);
 356   PR_HST(ALLOC_TUP_hst,4);
 357   PR_CTR(ALLOC_BH_ctr);
 358   PR_CTR(ALLOC_BH_adm);
 359   PR_CTR(ALLOC_BH_gds);
 360   PR_CTR(ALLOC_BH_slp);
 361   PR_HST(ALLOC_BH_hst,0);
 362   PR_HST(ALLOC_BH_hst,1);
 363   PR_HST(ALLOC_BH_hst,2);
 364   PR_HST(ALLOC_BH_hst,3);
 365   PR_HST(ALLOC_BH_hst,4);
 366   PR_CTR(ALLOC_PRIM_ctr);
 367   PR_CTR(ALLOC_PRIM_adm);
 368   PR_CTR(ALLOC_PRIM_gds);
 369   PR_CTR(ALLOC_PRIM_slp);
 370   PR_HST(ALLOC_PRIM_hst,0);
 371   PR_HST(ALLOC_PRIM_hst,1);
 372   PR_HST(ALLOC_PRIM_hst,2);
 373   PR_HST(ALLOC_PRIM_hst,3);
 374   PR_HST(ALLOC_PRIM_hst,4);
 375   PR_CTR(ALLOC_PAP_ctr);
 376   PR_CTR(ALLOC_PAP_adm);
 377   PR_CTR(ALLOC_PAP_gds);
 378   PR_CTR(ALLOC_PAP_slp);
 379   PR_HST(ALLOC_PAP_hst,0);
 380   PR_HST(ALLOC_PAP_hst,1);
 381   PR_HST(ALLOC_PAP_hst,2);
 382   PR_HST(ALLOC_PAP_hst,3);
 383   PR_HST(ALLOC_PAP_hst,4);
 384
 385   PR_CTR(ALLOC_TSO_ctr);
 386   PR_CTR(ALLOC_TSO_adm);
 387   PR_CTR(ALLOC_TSO_gds);
 388   PR_CTR(ALLOC_TSO_slp);
 389   PR_HST(ALLOC_TSO_hst,0);
 390   PR_HST(ALLOC_TSO_hst,1);
 391   PR_HST(ALLOC_TSO_hst,2);
 392   PR_HST(ALLOC_TSO_hst,3);
 393   PR_HST(ALLOC_TSO_hst,4);
 394
 395 #ifdef PAR
 396   PR_CTR(ALLOC_FMBQ_ctr);
 397   PR_CTR(ALLOC_FMBQ_adm);
 398   PR_CTR(ALLOC_FMBQ_gds);
 399   PR_CTR(ALLOC_FMBQ_slp);
 400   PR_HST(ALLOC_FMBQ_hst,0);
 401   PR_HST(ALLOC_FMBQ_hst,1);
 402   PR_HST(ALLOC_FMBQ_hst,2);
 403   PR_HST(ALLOC_FMBQ_hst,3);
 404   PR_HST(ALLOC_FMBQ_hst,4);
 405   PR_CTR(ALLOC_FME_ctr);
 406   PR_CTR(ALLOC_FME_adm);
 407   PR_CTR(ALLOC_FME_gds);
 408   PR_CTR(ALLOC_FME_slp);
 409   PR_HST(ALLOC_FME_hst,0);
 410   PR_HST(ALLOC_FME_hst,1);
 411   PR_HST(ALLOC_FME_hst,2);
 412   PR_HST(ALLOC_FME_hst,3);
 413   PR_HST(ALLOC_FME_hst,4);
 414   PR_CTR(ALLOC_BF_ctr);
 415   PR_CTR(ALLOC_BF_adm);
 416   PR_CTR(ALLOC_BF_gds);
 417   PR_CTR(ALLOC_BF_slp);
 418   PR_HST(ALLOC_BF_hst,0);
 419   PR_HST(ALLOC_BF_hst,1);
 420   PR_HST(ALLOC_BF_hst,2);
 421   PR_HST(ALLOC_BF_hst,3);
 422   PR_HST(ALLOC_BF_hst,4);
 423 #endif
 424
 425   PR_CTR(ENT_VIA_NODE_ctr);
 426   PR_CTR(ENT_STATIC_CON_ctr);
 427   PR_CTR(ENT_DYN_CON_ctr);
 428   PR_CTR(ENT_STATIC_FUN_DIRECT_ctr);
 429   PR_CTR(ENT_DYN_FUN_DIRECT_ctr);
 430   PR_CTR(ENT_STATIC_IND_ctr);
 431   PR_CTR(ENT_DYN_IND_ctr);
 432
 433 /* The counters ENT_PERM_IND and UPD_{NEW,OLD}_PERM_IND are not dumped
 434  * at the end of execution unless update squeezing is turned off (+RTS
 435  * -Z =RtsFlags.GcFlags.squeezeUpdFrames), as they will be wrong
 436  * otherwise.  Why?  Because for each update frame squeezed out, we
 437  * count an UPD_NEW_PERM_IND *at GC time* (i.e., too early).  And
 438  * further, when we enter the closure that has been updated, we count
 439  * the ENT_PERM_IND, but we then enter the PERM_IND that was built for
 440  * the next update frame below, and so on down the chain until we
 441  * finally reach the value.  Thus we count many new ENT_PERM_INDs too
 442  * early.
 443  *
 444  * This of course refers to the -ticky version that uses PERM_INDs to
 445  * determine the number of closures entered 0/1/>1.  KSW 1999-04.  */
 446   COND_PR_CTR(ENT_PERM_IND_ctr,RtsFlags.GcFlags.squeezeUpdFrames == rtsFalse,"E!NT_PERM_IND_ctr requires +RTS -Z");
 447
 448   PR_CTR(ENT_AP_ctr);
 449   PR_CTR(ENT_PAP_ctr);
 450   PR_CTR(ENT_AP_STACK_ctr);
 451   PR_CTR(ENT_BH_ctr);
 452   PR_CTR(ENT_STATIC_THK_ctr);
 453   PR_CTR(ENT_DYN_THK_ctr);
 454
 455   PR_CTR(SLOW_CALL_v_ctr);
 456   PR_CTR(SLOW_CALL_f_ctr);
 457   PR_CTR(SLOW_CALL_d_ctr);
 458   PR_CTR(SLOW_CALL_l_ctr);
 459   PR_CTR(SLOW_CALL_n_ctr);
 460   PR_CTR(SLOW_CALL_p_ctr);
 461   PR_CTR(SLOW_CALL_pv_ctr);
 462   PR_CTR(SLOW_CALL_pp_ctr);
 463   PR_CTR(SLOW_CALL_ppv_ctr);
 464   PR_CTR(SLOW_CALL_ppp_ctr);
 465   PR_CTR(SLOW_CALL_pppv_ctr);
 466   PR_CTR(SLOW_CALL_pppp_ctr);
 467   PR_CTR(SLOW_CALL_ppppp_ctr);
 468   PR_CTR(SLOW_CALL_pppppp_ctr);
 469   PR_CTR(SLOW_CALL_OTHER_ctr);
 470
 471   PR_CTR(UNKNOWN_CALL_ctr);
 472   PR_CTR(KNOWN_CALL_ctr);
 473   PR_CTR(KNOWN_CALL_TOO_FEW_ARGS_ctr);
 474   PR_CTR(KNOWN_CALL_EXTRA_ARGS_ctr);
 475   PR_CTR(MULTI_CHUNK_SLOW_CALL_ctr);
 476   PR_CTR(MULTI_CHUNK_SLOW_CALL_CHUNKS_ctr);
 477   PR_CTR(SLOW_CALL_ctr);
 478   PR_CTR(SLOW_CALL_FUN_TOO_FEW_ctr);
 479   PR_CTR(SLOW_CALL_FUN_CORRECT_ctr);
 480   PR_CTR(SLOW_CALL_FUN_TOO_MANY_ctr);
 481   PR_CTR(SLOW_CALL_PAP_TOO_FEW_ctr);
 482   PR_CTR(SLOW_CALL_PAP_CORRECT_ctr);
 483   PR_CTR(SLOW_CALL_PAP_TOO_MANY_ctr);
 484   PR_CTR(SLOW_CALL_UNEVALD_ctr);
 485   PR_HST(SLOW_CALL_hst,0);
 486   PR_HST(SLOW_CALL_hst,1);
 487   PR_HST(SLOW_CALL_hst,2);
 488   PR_HST(SLOW_CALL_hst,3);
 489   PR_HST(SLOW_CALL_hst,4);
 490   PR_HST(SLOW_CALL_hst,5);
 491   PR_HST(SLOW_CALL_hst,6);
 492   PR_HST(SLOW_CALL_hst,7);
 493
 494   PR_CTR(RET_NEW_ctr);
 495   PR_CTR(RET_OLD_ctr);
 496   PR_CTR(RET_UNBOXED_TUP_ctr);
 497   PR_CTR(VEC_RETURN_ctr);
 498
 499   PR_HST(RET_NEW_hst,0);
 500   PR_HST(RET_NEW_hst,1);
 501   PR_HST(RET_NEW_hst,2);
 502   PR_HST(RET_NEW_hst,3);
 503   PR_HST(RET_NEW_hst,4);
 504   PR_HST(RET_NEW_hst,5);
 505   PR_HST(RET_NEW_hst,6);
 506   PR_HST(RET_NEW_hst,7);
 507   PR_HST(RET_NEW_hst,8);
 508   PR_HST(RET_OLD_hst,0);
 509   PR_HST(RET_OLD_hst,1);
 510   PR_HST(RET_OLD_hst,2);
 511   PR_HST(RET_OLD_hst,3);
 512   PR_HST(RET_OLD_hst,4);
 513   PR_HST(RET_OLD_hst,5);
 514   PR_HST(RET_OLD_hst,6);
 515   PR_HST(RET_OLD_hst,7);
 516   PR_HST(RET_OLD_hst,8);
 517   PR_HST(RET_UNBOXED_TUP_hst,0);
 518   PR_HST(RET_UNBOXED_TUP_hst,1);
 519   PR_HST(RET_UNBOXED_TUP_hst,2);
 520   PR_HST(RET_UNBOXED_TUP_hst,3);
 521   PR_HST(RET_UNBOXED_TUP_hst,4);
 522   PR_HST(RET_UNBOXED_TUP_hst,5);
 523   PR_HST(RET_UNBOXED_TUP_hst,6);
 524   PR_HST(RET_UNBOXED_TUP_hst,7);
 525   PR_HST(RET_UNBOXED_TUP_hst,8);
 526   PR_HST(RET_VEC_RETURN_hst,0);
 527   PR_HST(RET_VEC_RETURN_hst,1);
 528   PR_HST(RET_VEC_RETURN_hst,2);
 529   PR_HST(RET_VEC_RETURN_hst,3);
 530   PR_HST(RET_VEC_RETURN_hst,4);
 531   PR_HST(RET_VEC_RETURN_hst,5);
 532   PR_HST(RET_VEC_RETURN_hst,6);
 533   PR_HST(RET_VEC_RETURN_hst,7);
 534   PR_HST(RET_VEC_RETURN_hst,8);
 535
 536   PR_CTR(UPDF_OMITTED_ctr);
 537   PR_CTR(UPDF_PUSHED_ctr);
 538   PR_CTR(CATCHF_PUSHED_ctr);
 539
 540   PR_CTR(UPDF_RCC_PUSHED_ctr);
 541   PR_CTR(UPDF_RCC_OMITTED_ctr);
 542
 543   PR_CTR(UPD_SQUEEZED_ctr);
 544   PR_CTR(UPD_CON_IN_NEW_ctr);
 545   PR_CTR(UPD_CON_IN_PLACE_ctr);
 546   PR_CTR(UPD_PAP_IN_NEW_ctr);
 547   PR_CTR(UPD_PAP_IN_PLACE_ctr);
 548
 549   PR_CTR(UPD_BH_UPDATABLE_ctr);
 550   PR_CTR(UPD_BH_SINGLE_ENTRY_ctr);
 551   PR_CTR(UPD_CAF_BH_UPDATABLE_ctr);
 552   PR_CTR(UPD_CAF_BH_SINGLE_ENTRY_ctr);
 553
 554   PR_HST(UPD_CON_IN_NEW_hst,0);
 555   PR_HST(UPD_CON_IN_NEW_hst,1);
 556   PR_HST(UPD_CON_IN_NEW_hst,2);
 557   PR_HST(UPD_CON_IN_NEW_hst,3);
 558   PR_HST(UPD_CON_IN_NEW_hst,4);
 559   PR_HST(UPD_CON_IN_NEW_hst,5);
 560   PR_HST(UPD_CON_IN_NEW_hst,6);
 561   PR_HST(UPD_CON_IN_NEW_hst,7);
 562   PR_HST(UPD_CON_IN_NEW_hst,8);
 563   PR_HST(UPD_PAP_IN_NEW_hst,0);
 564   PR_HST(UPD_PAP_IN_NEW_hst,1);
 565   PR_HST(UPD_PAP_IN_NEW_hst,2);
 566   PR_HST(UPD_PAP_IN_NEW_hst,3);
 567   PR_HST(UPD_PAP_IN_NEW_hst,4);
 568   PR_HST(UPD_PAP_IN_NEW_hst,5);
 569   PR_HST(UPD_PAP_IN_NEW_hst,6);
 570   PR_HST(UPD_PAP_IN_NEW_hst,7);
 571   PR_HST(UPD_PAP_IN_NEW_hst,8);
 572
 573   PR_CTR(UPD_NEW_IND_ctr);
 574   /* see comment on ENT_PERM_IND_ctr */
 575   COND_PR_CTR(UPD_NEW_PERM_IND_ctr,RtsFlags.GcFlags.squeezeUpdFrames == rtsFalse,"U!PD_NEW_PERM_IND_ctr requires +RTS -Z");
 576   PR_CTR(UPD_OLD_IND_ctr);
 577   /* see comment on ENT_PERM_IND_ctr */
 578   COND_PR_CTR(UPD_OLD_PERM_IND_ctr,RtsFlags.GcFlags.squeezeUpdFrames == rtsFalse,"U!PD_OLD_PERM_IND_ctr requires +RTS -Z");
 579
 580   PR_CTR(GC_SEL_ABANDONED_ctr);
 581   PR_CTR(GC_SEL_MINOR_ctr);
 582   PR_CTR(GC_SEL_MAJOR_ctr);
 583   PR_CTR(GC_FAILED_PROMOTION_ctr);
 584   PR_CTR(GC_WORDS_COPIED_ctr);
 585 }
 586
 587 /* Data structure used in ``registering'' one of these counters. */
 588
 589 StgEntCounter *ticky_entry_ctrs = NULL; /* root of list of them */
 590
 591 /* To print out all the registered-counter info: */
 592
 593 static void
 594 printRegisteredCounterInfo (FILE *tf)
 595 {
 596     StgEntCounter *p;
 597
 598     if ( ticky_entry_ctrs != NULL ) {
 599       fprintf(tf,"\n**************************************************\n\n");
 600     }
 601     fprintf(tf, "%11s%11s %6s%6s    %-11s%-30s\n",
 602             "Entries", "Allocs", "Arity", "Stack", "Kinds", "Function");
 603     fprintf(tf, "--------------------------------------------------------------------------------\n");
 604     /* Function name at the end so it doesn't mess up the tabulation */
 605
 606     for (p = ticky_entry_ctrs; p != NULL; p = p->link) {
 607         fprintf(tf, "%11ld%11ld %6u%6u    %-11s%-30s",
 608                 p->entry_count,
 609                 p->allocs,
 610                 p->arity,
 611                 p->stk_args,
 612                 p->arg_kinds,
 613                 p->str);
 614
 615         fprintf(tf, "\n");
 616
 617     }
 618 }
 619
 620 /* Catch-all top-level counter struct.  Allocations from CAFs will go
 621  * here.
 622  */
 623 StgEntCounter top_ct
 624         = { 0, 0, 0,
 625             "TOP", "",
 626             0, 0, NULL };
 627
 628 #endif /* TICKY_TICKY */
 629