[GHC] #1933: Zero times in profiling with GHC-6.8.1

GHC trac at galois.com
Wed Nov 28 10:49:21 EST 2007


#1933: Zero times in profiling with GHC-6.8.1
-------------------------------+--------------------------------------------
 Reporter:  daniel.is.fischer  |          Owner:  simonmar
     Type:  bug                |         Status:  new     
 Priority:  normal             |      Milestone:  6.8.3   
Component:  Profiling          |        Version:  6.8.1   
 Severity:  normal             |     Resolution:          
 Keywords:                     |     Difficulty:  Unknown 
 Testcase:                     |   Architecture:  x86     
       Os:  Linux              |  
-------------------------------+--------------------------------------------
Comment (by daniel.is.fischer):

 Indeed, that did work. Would the config.log help in figuring out what went
 wrong?
 {{{
 dafis at linux:~/EulerProblems/SubteamA/MasterMind>
 ~/Haskell/RecentHZips/ghc-6.8.1/compiler/stage2/ghc-inplace -O2 -prof
 -auto-all -osuf pr_o -hisuf pr_hi -o rMaster --make master
 [1 of 1] Compiling Main             ( master.hs, master.pr_o )
 Linking rMaster ...
 dafis at linux:~/EulerProblems/SubteamA/MasterMind> ./rMaster +RTS -P
 -sstderr -RTS MMDataA
 ./rMaster MMDataA +RTS -P -sstderr
 4816567204758932
 2,018,820,728 bytes allocated in the heap
  69,460,240 bytes copied during GC (scavenged)
       7,168 bytes copied during GC (not scavenged)
     139,264 bytes maximum residency (14 sample(s))

        3851 collections in generation 0 (  1.45s)
          14 collections in generation 1 (  0.02s)

           2 Mb total memory in use

   INIT  time    0.00s  (  0.00s elapsed)
   MUT   time   16.73s  ( 23.02s elapsed)
   GC    time    1.47s  (  2.26s elapsed)
   RP    time    0.00s  (  0.00s elapsed)
   PROF  time    0.00s  (  0.00s elapsed)
   EXIT  time    0.00s  (  0.00s elapsed)
   Total time   18.20s  ( 25.28s elapsed)

   %GC time       8.1%  (8.9% elapsed)

   Alloc rate    120,670,695 bytes per MUT second

   Productivity  91.9% of total user, 66.2% of total elapsed
 }}}
 And rMaster.prof:
 {{{
         Wed Nov 28 16:46 2007 Time and Allocation Profiling Report
 (Final)

            rMaster +RTS -P -sstderr -RTS MMDataA

         total time  =       16.96 secs   (848 ticks @ 20 ms)
         total alloc = 1,313,421,616 bytes  (excludes profiling overheads)

 COST CENTRE                    MODULE               %time %alloc  ticks
 bytes

 appCon                         Main                  50.4   70.3    427
 230850097
 steps                          Main                  23.2    9.8    197
 32234523
 unchosen                       Main                  14.0    9.2    119
 30046098
 full                           Main                   7.1   10.0     60
 32779939
 forbid                         Main                   2.0    0.0     17
 0
 choose                         Main                   1.5    0.6     13
 2132353


 individual    inherited
 COST CENTRE              MODULE
 no.    entries  %time %alloc   %time %alloc  ticks     bytes

 MAIN                     MAIN
 1           0   0.0    0.0   100.0  100.0      0       152
  main                    Main
 188           1   0.0    0.0   100.0  100.0      0       244
   fromFile               Main
 189           2   0.0    0.0   100.0  100.0      0     52652
    out                   Main
 212           1   0.0    0.0     0.0    0.0      0      9704
    noInfo                Main
 207           1   0.0    0.0     0.0    0.0      0       972
    untie                 Main
 206           1   0.0    0.0     0.0    0.0      0      2084
    full                  Main
 194       83340   7.1   10.0   100.0  100.0     60 131119756
     con2tag_Isit#        Main
 203       28824   0.0    0.0     0.0    0.0      0         0
     steps                Main
 195      295637  23.2    9.8    92.9   90.0    197 128937404
      selected            Main
 210          16   0.0    0.0     0.0    0.0      0       192
      unselected          Main
 209         256   0.0    0.0     0.0    0.0      0      9216
      appPoss             Main
 208          17   0.0    0.0     0.0    0.0      0     45424
      chosen              Main
 204      138902   0.6    0.0     1.5    0.1      5         0
       mustBe             Main
 205     1156314   0.9    0.1     0.9    0.1      8    971272
      unchosen            Main
 200    10015366  14.0    9.2    16.0    9.2    119 120184392
       forbid             Main
 201     1815159   2.0    0.0     2.0    0.0     17         0
      appCon              Main
 197      698230  50.4   70.3    50.6   70.3    427 923399700
       binom              Main
 202      644946   0.2    0.0     0.2    0.0      2         0
      choose              Main
 196      464897   1.5    0.6     1.5    0.6     13   8529412
    con2tag_Isit#         Main
 193          48   0.0    0.0     0.0    0.0      0         0
    line2Con              Main
 190          17   0.0    0.0     0.0    0.0      0    107616
     binom                Main
 191          17   0.0    0.0     0.0    0.0      0         0
  CAF                     Main
 182          12   0.0    0.0     0.0    0.0      0        96
   noInfo                 Main
 211           0   0.0    0.0     0.0    0.0      0       408
   appCon                 Main
 199           0   0.0    0.0     0.0    0.0      0       688
   steps                  Main
 198           0   0.0    0.0     0.0    0.0      0       688
   binom                  Main
 192           0   0.0    0.0     0.0    0.0      0     37812
  CAF                     Text.Read.Lex
 159           8   0.0    0.0     0.0    0.0      0      1772
  CAF                     GHC.Read
 154           1   0.0    0.0     0.0    0.0      0       976
  CAF                     GHC.Int
 148           1   0.0    0.0     0.0    0.0      0       108
  CAF                     Foreign.Storable
 145           1   0.0    0.0     0.0    0.0      0        44
  CAF                     GHC.Handle
 134           7   0.0    0.0     0.0    0.0      0      8720
  CAF                     System.Posix.Internals
 118           7   0.0    0.0     0.0    0.0      0       112
 }}}

-- 
Ticket URL: <http://hackage.haskell.org/trac/ghc/ticket/1933#comment:9>
GHC <http://www.haskell.org/ghc/>
The Glasgow Haskell Compiler


More information about the Glasgow-haskell-bugs mailing list