Stop profiling output from running together (#8811)
authorDave Laing <dave.laing.80@gmail.com>
Mon, 6 Apr 2015 10:10:29 +0000 (12:10 +0200)
committerThomas Miedema <thomasmiedema@gmail.com>
Mon, 6 Apr 2015 10:10:38 +0000 (12:10 +0200)
Reviewed By: thomie

Differential Revision: https://phabricator.haskell.org/D779

12 files changed:
rts/Profiling.c
testsuite/tests/profiling/should_run/T2552.prof.sample
testsuite/tests/profiling/should_run/T5559.prof.sample
testsuite/tests/profiling/should_run/T680.prof.sample
testsuite/tests/profiling/should_run/ioprof.prof.sample
testsuite/tests/profiling/should_run/prof-doc-fib.prof.sample
testsuite/tests/profiling/should_run/prof-doc-last.prof.sample
testsuite/tests/profiling/should_run/profinline001.prof.sample
testsuite/tests/profiling/should_run/scc001.prof.sample
testsuite/tests/profiling/should_run/scc002.prof.sample
testsuite/tests/profiling/should_run/scc003.prof.sample
testsuite/tests/profiling/should_run/scc005.prof.sample

index a299189..23a4899 100644 (file)
@@ -114,14 +114,17 @@ static  CostCentreStack * actualPush_     ( CostCentreStack *ccs, CostCentre *cc
 static  rtsBool           ignoreCCS       ( CostCentreStack *ccs );
 static  void              countTickss     ( CostCentreStack *ccs );
 static  void              inheritCosts    ( CostCentreStack *ccs );
+static  nat               numDigits       ( StgInt i );
 static  void              findCCSMaxLens  ( CostCentreStack *ccs,
                                             nat indent,
                                             nat *max_label_len,
-                                            nat *max_module_len );
+                                            nat *max_module_len,
+                                            nat *max_id_len );
 static  void              logCCS          ( CostCentreStack *ccs,
                                             nat indent,
                                             nat max_label_len,
-                                            nat max_module_len );
+                                            nat max_module_len,
+                                            nat max_id_len );
 static  void              reportCCS       ( CostCentreStack *ccs );
 static  CostCentreStack * checkLoop       ( CostCentreStack *ccs,
                                             CostCentre *cc );
@@ -758,7 +761,7 @@ reportPerCCCosts( void )
     sorted_cc_list = NULL;
 
     max_label_len  = 11; // no shorter than the "COST CENTRE" header
-    max_module_len = 7;  // no shorter than the "MODULE" header
+    max_module_len = 6;  // no shorter than the "MODULE" header
 
     for (cc = CC_LIST; cc != NULL; cc = next) {
         next = cc->link;
@@ -773,7 +776,7 @@ reportPerCCCosts( void )
     }
 
     fprintf(prof_file, "%-*s %-*s", max_label_len, "COST CENTRE", max_module_len, "MODULE");
-    fprintf(prof_file, "%6s %6s", "%time", "%alloc");
+    fprintf(prof_file, " %6s %6s", "%time", "%alloc");
     if (RtsFlags.CcFlags.doCostCentres >= COST_CENTRES_VERBOSE) {
         fprintf(prof_file, "  %5s %9s", "ticks", "bytes");
     }
@@ -789,7 +792,7 @@ reportPerCCCosts( void )
                 cc->module,
                 max_module_len - strlen_utf8(cc->module), "");
 
-        fprintf(prof_file, "%6.1f %6.1f",
+        fprintf(prof_file, " %6.1f %6.1f",
                 total_prof_ticks == 0 ? 0.0 : (cc->time_ticks / (StgFloat) total_prof_ticks * 100),
                 total_alloc == 0 ? 0.0 : (cc->mem_alloc / (StgFloat)
                                           total_alloc * 100)
@@ -810,12 +813,21 @@ reportPerCCCosts( void )
    -------------------------------------------------------------------------- */
 
 static void
-fprintHeader( nat max_label_len, nat max_module_len )
+fprintHeader( nat max_label_len, nat max_module_len, nat max_id_len )
 {
-    fprintf(prof_file, "%-*s %-*s%6s %11s  %11s   %11s\n", max_label_len, "", max_module_len, "", "", "", "individual", "inherited");
+    fprintf(prof_file, "%-*s %-*s %-*s %11s  %12s   %12s\n",
+            max_label_len, "",
+            max_module_len, "",
+            max_id_len, "",
+            "", "individual", "inherited");
 
-    fprintf(prof_file, "%-*s %-*s", max_label_len, "COST CENTRE", max_module_len, "MODULE");
-    fprintf(prof_file, "%6s %11s  %5s %5s   %5s %5s", "no.", "entries", "%time", "%alloc", "%time", "%alloc");
+    fprintf(prof_file, "%-*s %-*s %-*s",
+            max_label_len, "COST CENTRE",
+            max_module_len, "MODULE",
+            max_id_len, "no.");
+
+    fprintf(prof_file, " %11s  %5s %6s   %5s %6s",
+            "entries", "%time", "%alloc", "%time", "%alloc");
 
     if (RtsFlags.CcFlags.doCostCentres >= COST_CENTRES_VERBOSE) {
         fprintf(prof_file, "  %5s %9s", "ticks", "bytes");
@@ -871,8 +883,25 @@ reportCCSProfiling( void )
     reportCCS(pruneCCSTree(CCS_MAIN));
 }
 
+static nat
+numDigits(StgInt i) {
+    nat result;
+
+    result = 1;
+
+    if (i < 0) i = 0;
+
+    while (i > 9) {
+        i /= 10;
+        result++;
+    }
+
+    return result;
+}
+
 static void
-findCCSMaxLens(CostCentreStack *ccs, nat indent, nat *max_label_len, nat *max_module_len) {
+findCCSMaxLens(CostCentreStack *ccs, nat indent,
+        nat *max_label_len, nat *max_module_len, nat *max_id_len) {
     CostCentre *cc;
     IndexTable *i;
 
@@ -880,16 +909,19 @@ findCCSMaxLens(CostCentreStack *ccs, nat indent, nat *max_label_len, nat *max_mo
 
     *max_label_len = stg_max(*max_label_len, indent + strlen_utf8(cc->label));
     *max_module_len = stg_max(*max_module_len, strlen_utf8(cc->module));
+    *max_id_len = stg_max(*max_id_len, numDigits(ccs->ccsID));
 
     for (i = ccs->indexTable; i != 0; i = i->next) {
         if (!i->back_edge) {
-            findCCSMaxLens(i->ccs, indent+1, max_label_len, max_module_len);
+            findCCSMaxLens(i->ccs, indent+1,
+                    max_label_len, max_module_len, max_id_len);
         }
     }
 }
 
 static void
-logCCS(CostCentreStack *ccs, nat indent, nat max_label_len, nat max_module_len)
+logCCS(CostCentreStack *ccs, nat indent,
+        nat max_label_len, nat max_module_len, nat max_id_len)
 {
     CostCentre *cc;
     IndexTable *i;
@@ -909,8 +941,9 @@ logCCS(CostCentreStack *ccs, nat indent, nat max_label_len, nat max_module_len)
                 cc->module,
                 max_module_len - strlen_utf8(cc->module), "");
 
-        fprintf(prof_file, "%6ld %11" FMT_Word64 "  %5.1f  %5.1f   %5.1f  %5.1f",
-            ccs->ccsID, ccs->scc_count,
+        fprintf(prof_file,
+                " %*ld %11" FMT_Word64 "  %5.1f  %5.1f   %5.1f  %5.1f",
+                max_id_len, ccs->ccsID, ccs->scc_count,
                 total_prof_ticks == 0 ? 0.0 : ((double)ccs->time_ticks / (double)total_prof_ticks * 100.0),
                 total_alloc == 0 ? 0.0 : ((double)ccs->mem_alloc / (double)total_alloc * 100.0),
                 total_prof_ticks == 0 ? 0.0 : ((double)ccs->inherited_ticks / (double)total_prof_ticks * 100.0),
@@ -926,7 +959,7 @@ logCCS(CostCentreStack *ccs, nat indent, nat max_label_len, nat max_module_len)
 
     for (i = ccs->indexTable; i != 0; i = i->next) {
         if (!i->back_edge) {
-            logCCS(i->ccs, indent+1, max_label_len, max_module_len);
+            logCCS(i->ccs, indent+1, max_label_len, max_module_len, max_id_len);
         }
     }
 }
@@ -934,15 +967,16 @@ logCCS(CostCentreStack *ccs, nat indent, nat max_label_len, nat max_module_len)
 static void
 reportCCS(CostCentreStack *ccs)
 {
-    nat max_label_len, max_module_len;
+    nat max_label_len, max_module_len, max_id_len;
 
     max_label_len = 11; // no shorter than "COST CENTRE" header
-    max_module_len = 7; // no shorter than "MODULE" header
+    max_module_len = 6; // no shorter than "MODULE" header
+    max_id_len = 3; // no shorter than "no." header
 
-    findCCSMaxLens(ccs, 0, &max_label_len, &max_module_len);
+    findCCSMaxLens(ccs, 0, &max_label_len, &max_module_len, &max_id_len);
 
-    fprintHeader(max_label_len, max_module_len);
-    logCCS(ccs, 0, max_label_len, max_module_len);
+    fprintHeader(max_label_len, max_module_len, max_id_len);
+    logCCS(ccs, 0, max_label_len, max_module_len, max_id_len);
 }
 
 
index 2ec702f..ea6b5ec 100644 (file)
@@ -1,36 +1,36 @@
-       Wed Jan 28 11:18 2015 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 20:30 2015 Time and Allocation Profiling Report  (Final)
 
           T2552 +RTS -hc -p -RTS
 
-       total time  =        0.09 secs   (93 ticks @ 1000 us, 1 processor)
-       total alloc = 123,466,232 bytes  (excludes profiling overheads)
+       total time  =        0.09 secs   (92 ticks @ 1000 us, 1 processor)
+       total alloc = 123,466,304 bytes  (excludes profiling overheads)
 
 COST CENTRE     MODULE  %time %alloc
 
-fib2'.nfib      Main     34.4   33.3
-fib3'.nfib      Main     33.3   33.3
-fib1.fib1'.nfib Main     32.3   33.3
+fib1.fib1'.nfib Main     34.8   33.3
+fib2'.nfib      Main     34.8   33.3
+fib3'.nfib      Main     30.4   33.3
 
 
-                                                                individual     inherited
-COST CENTRE           MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                               individual      inherited
+COST CENTRE           MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN                  MAIN                     98           0    0.0    0.0   100.0  100.0
- main                 Main                    197           0    0.0    0.0     0.0    0.0
- CAF                  Main                    195           0    0.0    0.0   100.0  100.0
-  main                Main                    196           1    0.0    0.0   100.0  100.0
-   fib3               Main                    205           1    0.0    0.0    33.3   33.3
-    fib3'             Main                    206           1    0.0    0.0    33.3   33.3
-     fib3'.nfib       Main                    207     1028457   33.3   33.3    33.3   33.3
-   fib2               Main                    202           1    0.0    0.0    34.4   33.3
-    fib2'             Main                    203           1    0.0    0.0    34.4   33.3
-     fib2'.nfib       Main                    204     1028457   34.4   33.3    34.4   33.3
-   fib1               Main                    198           1    0.0    0.0    32.3   33.3
-    fib1.fib1'        Main                    199           1    0.0    0.0    32.3   33.3
-     nfib'            Main                    200           1    0.0    0.0    32.3   33.3
-      fib1.fib1'.nfib Main                    201     1028457   32.3   33.3    32.3   33.3
- CAF                  GHC.Conc.Signal         189           0    0.0    0.0     0.0    0.0
- CAF                  GHC.IO.Encoding         179           0    0.0    0.0     0.0    0.0
- CAF                  GHC.IO.Encoding.Iconv   177           0    0.0    0.0     0.0    0.0
- CAF                  GHC.IO.Handle.FD        169           0    0.0    0.0     0.0    0.0
- CAF                  GHC.IO.Handle.Text      167           0    0.0    0.0     0.0    0.0
+MAIN                  MAIN                   98           0    0.0    0.0   100.0  100.0
+ main                 Main                  197           0    0.0    0.0     0.0    0.0
+ CAF                  Main                  195           0    0.0    0.0   100.0  100.0
+  main                Main                  196           1    0.0    0.0   100.0  100.0
+   fib3               Main                  205           1    0.0    0.0    30.4   33.3
+    fib3'             Main                  206           1    0.0    0.0    30.4   33.3
+     fib3'.nfib       Main                  207     1028457   30.4   33.3    30.4   33.3
+   fib2               Main                  202           1    0.0    0.0    34.8   33.3
+    fib2'             Main                  203           1    0.0    0.0    34.8   33.3
+     fib2'.nfib       Main                  204     1028457   34.8   33.3    34.8   33.3
+   fib1               Main                  198           1    0.0    0.0    34.8   33.3
+    fib1.fib1'        Main                  199           1    0.0    0.0    34.8   33.3
+     nfib'            Main                  200           1    0.0    0.0    34.8   33.3
+      fib1.fib1'.nfib Main                  201     1028457   34.8   33.3    34.8   33.3
+ CAF                  GHC.Conc.Signal       177           0    0.0    0.0     0.0    0.0
+ CAF                  GHC.IO.Encoding       161           0    0.0    0.0     0.0    0.0
+ CAF                  GHC.IO.Encoding.Iconv 159           0    0.0    0.0     0.0    0.0
+ CAF                  GHC.IO.Handle.FD      150           0    0.0    0.0     0.0    0.0
+ CAF                  GHC.IO.Handle.Text    148           0    0.0    0.0     0.0    0.0
index 6bc1740..5c4b74a 100644 (file)
@@ -1,23 +1,25 @@
-       Wed Nov  9 09:45 2011 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
           T5559 +RTS -p -RTS
 
-       total time  =        0.08 secs   (4 ticks @ 20 ms)
-       total alloc =  52,274,888 bytes  (excludes profiling overheads)
+       total time  =        0.04 secs   (36 ticks @ 1000 us, 1 processor)
+       total alloc =  57,359,016 bytes  (excludes profiling overheads)
 
 COST CENTRE MODULE  %time %alloc
 
-føb         Main    100.0   99.9
+føb         Main     97.2   99.9
+MAIN        MAIN      2.8    0.0
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                     individual      inherited
+COST CENTRE MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                    100           0    0.0    0.0   100.0  100.0
- CAF        GHC.IO.Handle.FD        126           0    0.0    0.1     0.0    0.1
- CAF        GHC.IO.Encoding.Iconv   118           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Encoding         116           0    0.0    0.0     0.0    0.0
- CAF        GHC.Conc.Signal         108           0    0.0    0.0     0.0    0.0
- CAF        Main                    106           0    0.0    0.0   100.0   99.9
-  main      Main                    200           1    0.0    0.0   100.0   99.9
-   føb      Main                    201      392835  100.0   99.9   100.0   99.9
+MAIN        MAIN                   99           0    2.8    0.0   100.0  100.0
+ CAF        Main                  197           0    0.0    0.0    97.2   99.9
+  main      Main                  198           1    0.0    0.0    97.2   99.9
+   føb      Main                  199      392835   97.2   99.9    97.2   99.9
+ CAF        GHC.Conc.Signal       179           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Encoding       163           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Encoding.Iconv 161           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Handle.FD      152           0    0.0    0.1     0.0    0.1
+ CAF        GHC.IO.Handle.Text    150           0    0.0    0.0     0.0    0.0
index bc65fdd..6b2d037 100644 (file)
@@ -1,35 +1,37 @@
-       Wed Oct 26 14:10 2011 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
           T680 +RTS -p -RTS
 
-       total time  =        0.38 secs   (19 ticks @ 20 ms)
-       total alloc = 224,788,720 bytes  (excludes profiling overheads)
+       total time  =        0.20 secs   (203 ticks @ 1000 us, 1 processor)
+       total alloc = 449,665,336 bytes  (excludes profiling overheads)
 
 COST CENTRE MODULE  %time %alloc
 
 foo.\       Main    100.0   99.8
 
 
-                                                               individual     inherited
-COST CENTRE          MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                              individual      inherited
+COST CENTRE          MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN                 MAIN                    102           0    0.0    0.0   100.0  100.0
- CAF                 Main                    203           0    0.0    0.0   100.0  100.0
-  k                  Main                    206           1    0.0    0.0     0.0    0.0
-   foo               Main                    207           1    0.0    0.0     0.0    0.0
-    foo.bar          Main                    211           1    0.0    0.0     0.0    0.0
-     foo.bar.k'      Main                    215           1    0.0    0.0     0.0    0.0
-      k.\            Main                    216           1    0.0    0.0     0.0    0.0
-  r                  Main                    205           1    0.0    0.0   100.0  100.0
-   k                 Main                    208           0    0.0    0.0   100.0  100.0
-    foo              Main                    209           0    0.0    0.0   100.0  100.0
-     foo.bar         Main                    212           0    0.0    0.0     0.0    0.1
-      foo.bar.k'     Main                    217           0    0.0    0.0     0.0    0.0
-      foo.bar.\      Main                    213        4001    0.0    0.1     0.0    0.1
-       foo.bar.\.k'' Main                    214        4000    0.0    0.0     0.0    0.0
-     foo.\           Main                    210        4001  100.0   99.8   100.0   99.8
-  main               Main                    204           1    0.0    0.0     0.0    0.0
- CAF                 GHC.Conc.Signal         200           0    0.0    0.0     0.0    0.0
- CAF                 GHC.IO.Encoding.Iconv   190           0    0.0    0.0     0.0    0.0
- CAF                 GHC.IO.Handle.FD        182           0    0.0    0.0     0.0    0.0
- CAF                 GHC.Show                170           0    0.0    0.0     0.0    0.0
+MAIN                 MAIN                  103           0    0.0    0.0   100.0  100.0
+ CAF                 Main                  205           0    0.0    0.0   100.0  100.0
+  k                  Main                  208           1    0.0    0.0     0.0    0.0
+   foo               Main                  209           1    0.0    0.0     0.0    0.0
+    foo.bar          Main                  213           1    0.0    0.0     0.0    0.0
+     foo.bar.k'      Main                  217           1    0.0    0.0     0.0    0.0
+      k.\            Main                  218           1    0.0    0.0     0.0    0.0
+  r                  Main                  207           1    0.0    0.0   100.0  100.0
+   k                 Main                  210           0    0.0    0.0   100.0  100.0
+    foo              Main                  211           0    0.0    0.0   100.0  100.0
+     foo.bar         Main                  214           0    0.0    0.0     0.0    0.1
+      foo.bar.k'     Main                  219           0    0.0    0.0     0.0    0.0
+      foo.bar.\      Main                  215        4001    0.0    0.1     0.0    0.1
+       foo.bar.\.k'' Main                  216        4000    0.0    0.0     0.0    0.0
+     foo.\           Main                  212        4001  100.0   99.8   100.0   99.8
+  main               Main                  206           1    0.0    0.0     0.0    0.0
+ CAF                 GHC.Conc.Signal       184           0    0.0    0.0     0.0    0.0
+ CAF                 GHC.IO.Encoding       167           0    0.0    0.0     0.0    0.0
+ CAF                 GHC.IO.Encoding.Iconv 165           0    0.0    0.0     0.0    0.0
+ CAF                 GHC.IO.Handle.FD      156           0    0.0    0.0     0.0    0.0
+ CAF                 GHC.IO.Handle.Text    154           0    0.0    0.0     0.0    0.0
+ CAF                 GHC.Show              141           0    0.0    0.0     0.0    0.0
index 35f3ea4..5fd43b9 100644 (file)
@@ -1,36 +1,36 @@
-       Wed Jan 28 11:06 2015 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 20:30 2015 Time and Allocation Profiling Report  (Final)
 
           ioprof +RTS -hc -p -RTS
 
        total time  =        0.00 secs   (0 ticks @ 1000 us, 1 processor)
-       total alloc =      52,192 bytes  (excludes profiling overheads)
+       total alloc =      52,224 bytes  (excludes profiling overheads)
 
-COST CENTRE MODULE           %time %alloc
+COST CENTRE MODULE            %time %alloc
 
-MAIN        MAIN               0.0    1.4
-CAF         GHC.IO.Handle.FD   0.0   66.1
-CAF         GHC.IO.Encoding    0.0    6.3
-CAF         GHC.Conc.Signal    0.0    1.3
-main        Main               0.0   16.7
-errorM.\    Main               0.0    6.9
+MAIN        MAIN                0.0    1.4
+CAF         GHC.IO.Handle.FD    0.0   66.1
+CAF         GHC.IO.Encoding     0.0    6.3
+CAF         GHC.Conc.Signal     0.0    1.3
+main        Main                0.0   16.7
+errorM.\    Main                0.0    6.9
 
 
-                                                            individual     inherited
-COST CENTRE       MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                           individual      inherited
+COST CENTRE       MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN              MAIN                     98           0    0.0    1.4     0.0  100.0
- main             Main                    197           0    0.0   16.6     0.0   16.6
- CAF              Main                    195           0    0.0    0.0     0.0    7.5
-  main            Main                    196           1    0.0    0.2     0.0    7.5
-   runM           Main                    198           1    0.0    0.3     0.0    7.3
-    bar           Main                    199           1    0.0    0.0     0.0    7.0
-     >>=          Main                    200           1    0.0    0.0     0.0    6.9
-      >>=.\       Main                    201           1    0.0    0.0     0.0    6.9
-       foo        Main                    202           1    0.0    0.0     0.0    6.9
-        errorM    Main                    203           1    0.0    0.0     0.0    6.9
-         errorM.\ Main                    204           1    0.0    6.9     0.0    6.9
- CAF              GHC.Conc.Signal         189           0    0.0    1.3     0.0    1.3
- CAF              GHC.Conc.Sync           188           0    0.0    0.4     0.0    0.4
- CAF              GHC.IO.Encoding         179           0    0.0    6.3     0.0    6.3
- CAF              GHC.IO.Encoding.Iconv   177           0    0.0    0.4     0.0    0.4
- CAF              GHC.IO.Handle.FD        169           0    0.0   66.1     0.0   66.1
+MAIN              MAIN                   98           0    0.0    1.4     0.0  100.0
+ main             Main                  197           0    0.0   16.5     0.0   16.5
+ CAF              Main                  195           0    0.0    0.0     0.0    7.5
+  main            Main                  196           1    0.0    0.2     0.0    7.5
+   runM           Main                  198           1    0.0    0.3     0.0    7.3
+    bar           Main                  199           1    0.0    0.0     0.0    7.0
+     >>=          Main                  200           1    0.0    0.0     0.0    6.9
+      >>=.\       Main                  201           1    0.0    0.0     0.0    6.9
+       foo        Main                  202           1    0.0    0.0     0.0    6.9
+        errorM    Main                  203           1    0.0    0.0     0.0    6.9
+         errorM.\ Main                  204           1    0.0    6.9     0.0    6.9
+ CAF              GHC.Conc.Signal       177           0    0.0    1.3     0.0    1.3
+ CAF              GHC.Conc.Sync         176           0    0.0    0.4     0.0    0.4
+ CAF              GHC.IO.Encoding       161           0    0.0    6.3     0.0    6.3
+ CAF              GHC.IO.Encoding.Iconv 159           0    0.0    0.4     0.0    0.4
+ CAF              GHC.IO.Handle.FD      150           0    0.0   66.1     0.0   66.1
index 919d7b2..e35a6c7 100644 (file)
@@ -1,28 +1,27 @@
-       Wed Jan 28 11:24 2015 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
-          prof-doc-fib +RTS -hc -p -RTS
+          prof-doc-fib +RTS -p -RTS
 
-       total time  =        0.08 secs   (83 ticks @ 1000 us, 1 processor)
-       total alloc = 107,829,264 bytes  (excludes profiling overheads)
+       total time  =        0.31 secs   (310 ticks @ 1000 us, 1 processor)
+       total alloc = 538,951,032 bytes  (excludes profiling overheads)
 
-COST CENTRE MODULE  %time %alloc
+COST CENTRE MODULE     %time %alloc
 
-fib         Main    100.0  100.0
+fib         Main       100.0  100.0
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                      individual      inherited
+COST CENTRE  MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                     98           0    0.0    0.0   100.0  100.0
- main       Main                    197           0    0.0    0.0     0.0    0.0
- CAF        Main                    195           0    0.0    0.0   100.0  100.0
-  main      Main                    196           1    0.0    0.0   100.0  100.0
-   main.f   Main                    200           1    0.0    0.0   100.0   99.9
-    fib     Main                    201     2692537  100.0   99.9   100.0   99.9
-   main.g   Main                    198           1    0.0    0.0     0.0    0.1
-    fib     Main                    199        1973    0.0    0.1     0.0    0.1
- CAF        GHC.Conc.Signal         189           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Encoding         179           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Encoding.Iconv   177           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Handle.FD        169           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Handle.Text      167           0    0.0    0.0     0.0    0.0
+MAIN         MAIN                   99           0    0.0    0.0   100.0  100.0
+ CAF         Main                  197           0    0.0    0.0   100.0  100.0
+  main       Main                  198           1    0.0    0.0   100.0  100.0
+   main.f    Main                  201           1    0.0    0.0   100.0   99.9
+    fib      Main                  202     2692537  100.0   99.9   100.0   99.9
+   main.g    Main                  199           1    0.0    0.0     0.0    0.1
+    fib      Main                  200        1973    0.0    0.1     0.0    0.1
+ CAF         GHC.Conc.Signal       179           0    0.0    0.0     0.0    0.0
+ CAF         GHC.IO.Encoding       163           0    0.0    0.0     0.0    0.0
+ CAF         GHC.IO.Encoding.Iconv 161           0    0.0    0.0     0.0    0.0
+ CAF         GHC.IO.Handle.FD      152           0    0.0    0.0     0.0    0.0
+ CAF         GHC.IO.Handle.Text    150           0    0.0    0.0     0.0    0.0
index 258e9e1..dd03649 100644 (file)
@@ -1,31 +1,34 @@
-       Thu Oct 27 09:37 2011 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
-          prof-doc-last +RTS -hc -p -RTS
+          prof-doc-last +RTS -p -RTS
 
-       total time  =        0.34 secs   (17 ticks @ 20 ms)
-       total alloc = 204,047,104 bytes  (excludes profiling overheads)
+       total time  =        0.16 secs   (160 ticks @ 1000 us, 1 processor)
+       total alloc = 648,053,608 bytes  (excludes profiling overheads)
 
 COST CENTRE  MODULE  %time %alloc
 
-main.ys      Main     35.3   39.2
-main.xs      Main     29.4   19.6
-last_init_ys Main     23.5   27.4
-last_init_xs Main     11.8   13.7
+last_init_ys Main     28.1   29.6
+main.ys      Main     26.2   24.7
+main.xs      Main     16.9   12.3
+last_init_xs Main     14.4   14.8
+last_ys      Main      7.5   12.3
+last_xs      Main      6.9    6.2
 
 
-                                                          individual     inherited
-COST CENTRE     MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                         individual      inherited
+COST CENTRE     MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN            MAIN                    101           0    0.0    0.0   100.0  100.0
- main           Main                    203           0    0.0    0.0     0.0    0.0
- CAF            Main                    201           0    0.0    0.0   100.0  100.0
-  main          Main                    202           1    0.0    0.0   100.0  100.0
-   last_init_ys Main                    209           1   23.5   27.4    23.5   27.4
-   main.ys      Main                    208           1   35.3   39.2    35.3   39.2
-   last_ys      Main                    207           1    0.0    0.0     0.0    0.0
-   last_init_xs Main                    206           1   11.8   13.7    11.8   13.7
-   main.xs      Main                    205           1   29.4   19.6    29.4   19.6
-   last_xs      Main                    204           1    0.0    0.0     0.0    0.0
- CAF            GHC.Conc.Signal         199           0    0.0    0.0     0.0    0.0
- CAF            GHC.IO.Encoding.Iconv   188           0    0.0    0.0     0.0    0.0
- CAF            GHC.IO.Handle.FD        180           0    0.0    0.0     0.0    0.0
+MAIN            MAIN                   99           0    0.0    0.0   100.0  100.0
+ CAF            Main                  197           0    0.0    0.0   100.0  100.0
+  main          Main                  198           1    0.0    0.0   100.0  100.0
+   last_init_ys Main                  204           1   28.1   29.6    28.1   29.6
+   main.ys      Main                  203           1   26.2   24.7    26.2   24.7
+   last_ys      Main                  202           1    7.5   12.3     7.5   12.3
+   last_init_xs Main                  201           1   14.4   14.8    14.4   14.8
+   main.xs      Main                  200           1   16.9   12.3    16.9   12.3
+   last_xs      Main                  199           1    6.9    6.2     6.9    6.2
+ CAF            GHC.Conc.Signal       179           0    0.0    0.0     0.0    0.0
+ CAF            GHC.IO.Encoding       163           0    0.0    0.0     0.0    0.0
+ CAF            GHC.IO.Encoding.Iconv 161           0    0.0    0.0     0.0    0.0
+ CAF            GHC.IO.Handle.FD      152           0    0.0    0.0     0.0    0.0
+ CAF            GHC.IO.Handle.Text    150           0    0.0    0.0     0.0    0.0
index ac7bb77..6eacf18 100644 (file)
@@ -1,28 +1,29 @@
-       Fri Jun 15 11:01 2012 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
           profinline001 +RTS -p -RTS
 
        total time  =        0.00 secs   (0 ticks @ 1000 us, 1 processor)
-       total alloc =      49,336 bytes  (excludes profiling overheads)
+       total alloc =      49,056 bytes  (excludes profiling overheads)
 
-COST CENTRE MODULE           %time %alloc
+COST CENTRE MODULE            %time %alloc
 
-MAIN        MAIN               0.0    1.2
-CAF         GHC.IO.Handle.FD   0.0   71.0
-CAF         GHC.IO.Encoding    0.0    5.7
-CAF         GHC.Conc.Signal    0.0    1.4
-main        Main               0.0   19.2
+MAIN        MAIN                0.0    1.4
+CAF         GHC.IO.Handle.FD    0.0   70.4
+CAF         GHC.IO.Encoding     0.0    5.6
+CAF         GHC.Conc.Signal     0.0    1.3
+main        Main                0.0   19.5
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                     individual      inherited
+COST CENTRE MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                    103           0    0.0    1.2     0.0  100.0
- CAF        Main                    205           0    0.0    0.8     0.0   20.3
-  main      Main                    206           1    0.0   19.2     0.0   19.5
-   f        Main                    207           1    0.0    0.3     0.0    0.4
-    g       Main                    208           1    0.0    0.0     0.0    0.0
- CAF        GHC.Conc.Signal         203           0    0.0    1.4     0.0    1.4
- CAF        GHC.IO.Encoding         195           0    0.0    5.7     0.0    5.7
- CAF        GHC.IO.Encoding.Iconv   193           0    0.0    0.5     0.0    0.5
- CAF        GHC.IO.Handle.FD        185           0    0.0   71.0     0.0   71.0
+MAIN        MAIN                   99           0    0.0    1.4     0.0  100.0
+ CAF        Main                  197           0    0.0    0.7     0.0   20.5
+  main      Main                  198           1    0.0   19.5     0.0   19.8
+   f        Main                  199           1    0.0    0.3     0.0    0.4
+    g       Main                  200           1    0.0    0.0     0.0    0.0
+ CAF        GHC.Conc.Signal       179           0    0.0    1.3     0.0    1.3
+ CAF        GHC.IO.Encoding       163           0    0.0    5.6     0.0    5.6
+ CAF        GHC.IO.Encoding.Iconv 161           0    0.0    0.5     0.0    0.5
+ CAF        GHC.IO.Handle.FD      152           0    0.0   70.4     0.0   70.4
+ CAF        GHC.IO.Handle.Text    150           0    0.0    0.2     0.0    0.2
index 1a4bec7..6df5768 100644 (file)
@@ -1,33 +1,33 @@
-       Sun Nov  2 20:50 2014 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 20:30 2015 Time and Allocation Profiling Report  (Final)
 
-          scc001 +RTS -hc -p -RTS
+          scc001 +RTS -hc -p -RTS
 
-       total time  =        0.00 secs   (0 ticks @ 1000 us, 1 processor)
-       total alloc =      51,344 bytes  (excludes profiling overheads)
+       total time  =        0.00 secs   (0 ticks @ 1000 us, 1 processor)
+       total alloc =      51,344 bytes  (excludes profiling overheads)
 
-COST CENTRE MODULE                %time %alloc
+COST CENTRE MODULE            %time %alloc
 
-MAIN        MAIN               0.0    1.9
-CAF         GHC.IO.Encoding    0.0    5.4
-CAF         GHC.IO.Handle.FD   0.0   67.3
-CAF         GHC.Conc.Signal    0.0    1.3
-main        Main               0.0   22.8
+MAIN        MAIN                0.0    1.9
+CAF         GHC.IO.Handle.FD    0.0   67.3
+CAF         GHC.IO.Encoding     0.0    5.4
+CAF         GHC.Conc.Signal     0.0    1.3
+main        Main                0.0   22.8
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                     individual      inherited
+COST CENTRE MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                     44           0    0.0    1.9     0.0  100.0
- main       Main                     89           0    0.0   22.8     0.0   22.8
-  g         Main                     91           1    0.0    0.0     0.0    0.0
-  f         Main                     90           1    0.0    0.0     0.0    0.0
- CAF        Main                     87           0    0.0    0.1     0.0    0.1
-  (...)     Main                     93           1    0.0    0.0     0.0    0.0
-  h         Main                     92           1    0.0    0.0     0.0    0.0
-  main      Main                     88           1    0.0    0.0     0.0    0.0
- CAF        GHC.Show                 84           0    0.0    0.6     0.0    0.6
- CAF        GHC.Conc.Signal          82           0    0.0    1.3     0.0    1.3
- CAF        GHC.IO.Handle.FD         80           0    0.0   67.3     0.0   67.3
- CAF        GHC.IO.Handle.Text       79           0    0.0    0.2     0.0    0.2
- CAF        GHC.IO.Encoding          75           0    0.0    5.4     0.0    5.4
- CAF        GHC.IO.Encoding.Iconv    69           0    0.0    0.5     0.0    0.5
+MAIN        MAIN                   98           0    0.0    1.9     0.0  100.0
+ main       Main                  197           0    0.0   22.8     0.0   22.8
+  g         Main                  199           1    0.0    0.0     0.0    0.0
+  f         Main                  198           1    0.0    0.0     0.0    0.0
+ CAF        Main                  195           0    0.0    0.1     0.0    0.1
+  (...)     Main                  201           1    0.0    0.0     0.0    0.0
+  h         Main                  200           1    0.0    0.0     0.0    0.0
+  main      Main                  196           1    0.0    0.0     0.0    0.0
+ CAF        GHC.Conc.Signal       177           0    0.0    1.3     0.0    1.3
+ CAF        GHC.IO.Encoding       161           0    0.0    5.4     0.0    5.4
+ CAF        GHC.IO.Encoding.Iconv 159           0    0.0    0.5     0.0    0.5
+ CAF        GHC.IO.Handle.FD      150           0    0.0   67.3     0.0   67.3
+ CAF        GHC.IO.Handle.Text    148           0    0.0    0.2     0.0    0.2
+ CAF        GHC.Show              135           0    0.0    0.6     0.0    0.6
index 1e2e0ca..0c27b96 100644 (file)
@@ -1,29 +1,29 @@
-       Fri Dec  2 12:54 2011 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
-          scc002 +RTS -hc -p -RTS
+          scc002 +RTS -p -RTS
 
-       total time  =        0.01 secs   (15 ticks @ 1000 us, 1 processor)
-       total alloc =   8,049,336 bytes  (excludes profiling overheads)
+       total time  =        0.01 secs   (7 ticks @ 1000 us, 1 processor)
+       total alloc =   8,049,712 bytes  (excludes profiling overheads)
 
 COST CENTRE MODULE  %time %alloc
 
 big         Main    100.0   99.4
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                     individual      inherited
+COST CENTRE MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                    105           0    0.0    0.0   100.0  100.0
- main       Main                    211           0    0.0    0.1     0.0    0.1
CAF        GHC.IO.Handle.FD        136           0    0.0    0.4     0.0    0.4
- CAF        GHC.IO.Encoding.Iconv   128           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Encoding         126           0    0.0    0.0     0.0    0.0
- CAF        GHC.Conc.Signal         117           0    0.0    0.0     0.0    0.0
- CAF        Main                    112           0    0.0    0.0   100.0   99.4
-  yan       Main                    212           1    0.0    0.0   100.0   99.4
-   yan.e    Main                    216           1    0.0    0.0   100.0   99.4
-    big     Main                    217           1  100.0   99.4   100.0   99.4
 main      Main                    210           1    0.0    0.0     0.0    0.0
  yan      Main                    213           0    0.0    0.0     0.0    0.0
-    yan.\   Main                    214           2    0.0    0.0     0.0    0.0
    yan1   Main                    215           2    0.0    0.0     0.0    0.0
+MAIN        MAIN                  113           0    0.0    0.0   100.0  100.0
+ CAF        Main                  225           0    0.0    0.0   100.0   99.5
 yan       Main                  227           1    0.0    0.0   100.0   99.4
+   yan.e    Main                  231           1    0.0    0.0   100.0   99.4
+    big     Main                  232           1  100.0   99.4   100.0   99.4
+  main      Main                  226           1    0.0    0.1     0.0    0.1
+   yan      Main                  228           0    0.0    0.0     0.0    0.0
+    yan.\   Main                  229           2    0.0    0.0     0.0    0.0
+     yan1   Main                  230           2    0.0    0.0     0.0    0.0
+ CAF        GHC.Conc.Signal       196           0    0.0    0.0     0.0    0.0
CAF        GHC.IO.Encoding       179           0    0.0    0.0     0.0    0.0
CAF        GHC.IO.Encoding.Iconv 177           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Handle.FD      168           0    0.0    0.4     0.0    0.4
CAF        GHC.IO.Handle.Text    166           0    0.0    0.0     0.0    0.0
index 1b2f145..e1d0b64 100644 (file)
@@ -1,25 +1,27 @@
-       Tue Oct 25 15:36 2011 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
           scc003 +RTS -p -RTS
 
-       total time  =        0.38 secs   (19 ticks @ 20 ms)
-       total alloc =  97,764,432 bytes  (excludes profiling overheads)
+       total time  =        0.13 secs   (132 ticks @ 1000 us, 1 processor)
+       total alloc = 195,487,784 bytes  (excludes profiling overheads)
 
 COST CENTRE MODULE  %time %alloc
 
 fib         Main    100.0  100.0
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                     individual      inherited
+COST CENTRE MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                    102           0    0.0    0.0   100.0  100.0
- CAF        Main                    203           0    0.0    0.0   100.0  100.0
-  main      Main                    204           1    0.0    0.0   100.0  100.0
-   f        Main                    205           1    0.0    0.0   100.0  100.0
-    f.\     Main                    207           1    0.0    0.0   100.0   99.1
-     fib    Main                    208     2692537  100.0   99.1   100.0   99.1
-    fib     Main                    206       21891    0.0    0.8     0.0    0.8
- CAF        GHC.Conc.Signal         201           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Encoding.Iconv   191           0    0.0    0.0     0.0    0.0
- CAF        GHC.IO.Handle.FD        183           0    0.0    0.0     0.0    0.0
+MAIN        MAIN                   99           0    0.0    0.0   100.0  100.0
+ CAF        Main                  197           0    0.0    0.0   100.0  100.0
+  main      Main                  198           1    0.0    0.0   100.0  100.0
+   f        Main                  199           1    0.0    0.0   100.0  100.0
+    f.\     Main                  201           1    0.0    0.0    99.2   99.2
+     fib    Main                  202     2692537   99.2   99.2    99.2   99.2
+    fib     Main                  200       21891    0.8    0.8     0.8    0.8
+ CAF        GHC.Conc.Signal       179           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Encoding       163           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Encoding.Iconv 161           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Handle.FD      152           0    0.0    0.0     0.0    0.0
+ CAF        GHC.IO.Handle.Text    150           0    0.0    0.0     0.0    0.0
index 5d720f3..2db2dc3 100644 (file)
@@ -1,27 +1,27 @@
-       Wed Jan 28 10:59 2015 Time and Allocation Profiling Report  (Final)
+       Thu Apr  2 19:44 2015 Time and Allocation Profiling Report  (Final)
 
           scc005 +RTS -p -RTS
 
        total time  =        0.00 secs   (0 ticks @ 1000 us, 1 processor)
-       total alloc =      39,104 bytes  (excludes profiling overheads)
+       total alloc =      39,136 bytes  (excludes profiling overheads)
 
-COST CENTRE MODULE           %time %alloc
+COST CENTRE MODULE            %time %alloc
 
-MAIN        MAIN               0.0    1.2
-CAF         GHC.IO.Handle.FD   0.0   88.3
-CAF         GHC.IO.Encoding    0.0    7.1
-CAF         GHC.Conc.Signal    0.0    1.7
+MAIN        MAIN                0.0    1.2
+CAF         GHC.IO.Handle.FD    0.0   88.3
+CAF         GHC.IO.Encoding     0.0    7.1
+CAF         GHC.Conc.Signal     0.0    1.7
 
 
-                                                      individual     inherited
-COST CENTRE MODULE                  no.     entries  %time %alloc   %time %alloc
+                                                     individual      inherited
+COST CENTRE MODULE                no.     entries  %time %alloc   %time %alloc
 
-MAIN        MAIN                     98           0    0.0    1.2     0.0  100.0
- CAF        Main                    195           0    0.0    0.9     0.0    1.2
-  main      Main                    196           1    0.0    0.2     0.0    0.3
-   g        Main                    197           1    0.0    0.2     0.0    0.2
-    f       Main                    198           1    0.0    0.0     0.0    0.0
- CAF        GHC.Conc.Signal         189           0    0.0    1.7     0.0    1.7
- CAF        GHC.IO.Encoding         179           0    0.0    7.1     0.0    7.1
- CAF        GHC.IO.Encoding.Iconv   177           0    0.0    0.6     0.0    0.6
- CAF        GHC.IO.Handle.FD        169           0    0.0   88.3     0.0   88.3
+MAIN        MAIN                   98           0    0.0    1.2     0.0  100.0
+ CAF        Main                  195           0    0.0    0.9     0.0    1.2
+  main      Main                  196           1    0.0    0.2     0.0    0.3
+   g        Main                  197           1    0.0    0.2     0.0    0.2
+    f       Main                  198           1    0.0    0.0     0.0    0.0
+ CAF        GHC.Conc.Signal       177           0    0.0    1.7     0.0    1.7
+ CAF        GHC.IO.Encoding       161           0    0.0    7.1     0.0    7.1
+ CAF        GHC.IO.Encoding.Iconv 159           0    0.0    0.6     0.0    0.6
+ CAF        GHC.IO.Handle.FD      150           0    0.0   88.3     0.0   88.3