[racket] DrRacket 5.3 [3m] hangs for a while when closing tab with open file

From: Erik Dominikus (erik.dominikus71 at gmail.com)
Date: Thu Aug 16 04:02:19 EDT 2012

I'm attaching two profiling results here. The first one is for
DrRacket's start up. The second one is for opening and closing two to
three files in different tabs.

I'm not sure about their validity; the window became totally
unresponsive for a few seconds.

I guess there must be something in my ~/.racket directory slowing it
down. I did download some PLaneT packages using (require (planet ...)).
Maybe they are incompatible?


On Wed, 2012-08-15 at 23:39 -0500, Robby Findler wrote:
> I'm not seeing this behavior.
> 
> It may shed some light if you run the profiler. You can do that by
> setting the PLTDRPROFILE environment variable (to anything), starting
> up DrRacket, opening a file in a tab, clicking "start" in the
> profiling window, and then closing the tab. The profiling window
> should fill up with some helpful information.
> 
> One way to run with the environment variable set is via the command-line:
> 
>  env PLTDRPROFILE=1 drracket
> 
> Thanks,
> Robby
> 
> On Wed, Aug 15, 2012 at 10:51 PM, Erik Dominikus
> <erik.dominikus71 at gmail.com> wrote:
> > On my machine, DrRacket 5.3 [3m] (built from source package) looks like
> > waiting for something for about 5-10 seconds when closing a tab that has
> > a file open in it. It did not happen on Racket 5.2 [3m].
> >
> >
> > My machine's uname -a:
> >
> > Linux kire 2.6.32-41-generic #91-Ubuntu SMP Wed Jun 13 11:43:55 UTC 2012
> > x86_64 GNU/Linux
> >
> >
> > (FYI, if a reply is needed, expect it to be late as I'll be on holiday
> > until Aug 27.)
> >
> >
> > ____________________
> >   Racket Users list:
> >   http://lists.racket-lang.org/users

-------------- next part --------------
Profiling results
-----------------
  Total cpu time observed: 5170ms (out of 9010ms)
  Number of samples taken: 1189 (once every 4ms)
  Threads observed:        20
  (Hiding functions with self<1.0% and local<2.0%: 5 of 66 hidden)

=======================================================================================================
                              Caller
 Idx   Total      Self      Name+src                                                             Local%
       ms(pct)    ms(pct)     Callee
=======================================================================================================
 [1] 1670(32.3%) 415(8.0%)  ??? ...ects/mred/private/wx/common/queue.rkt:633:3
                              ??? [5]                                                             75.1%
-------------------------------------------------------------------------------------------------------
                              loop [55]                                                          100.0%
 [2]  300(5.8%)    0(0.0%)  ??? ...ollects/racket/private/serialize.rkt:479:30
                              loop [45]                                                          100.0%
-------------------------------------------------------------------------------------------------------
 [3]  220(4.3%)  220(4.3%)  loop .../racket/collects/racket/promise.rkt:174:10
-------------------------------------------------------------------------------------------------------
 [4]   30(0.6%)    0(0.0%)  loop ...ts/mred/private/wx/common/queue.rkt:397:18
                              call-with-break-parameterization [12]                              100.0%
-------------------------------------------------------------------------------------------------------
                              call-with-break-parameterization [12]                                2.3%
                              ??? [1]                                                             97.7%
 [5] 1285(24.9%)   0(0.0%)  ??? ...cts/mred/private/wx/common/queue.rkt:485:32
                              ??? [6]                                                            100.0%
-------------------------------------------------------------------------------------------------------
                              ??? [5]                                                            100.0%
 [6] 1285(24.9%)   0(0.0%)  ??? ...ects/mred/private/wx/common/queue.rkt:445:6
                              ??? [8]                                                             82.1%
                              call-with-exception-handler [7]                                     15.6%
                              ??? [9]                                                              1.6%
-------------------------------------------------------------------------------------------------------
                              ??? [6]                                                              8.4%
 [7] 2395(46.3%)   0(0.0%)  call-with-exception-handler ...re-scheme.rkt:268:2
                              ??? [10]                                                            91.6%
                              call-with-break-parameterization [12]                                8.4%
-------------------------------------------------------------------------------------------------------
                              ??? [6]                                                            100.0%
 [8] 1055(20.4%)   0(0.0%)  ??? ...et/collects/drracket/private/main.rkt:745:1
                              loop [11]                                                          100.0%
-------------------------------------------------------------------------------------------------------
                              ??? [6]                                                            100.0%
 [9]   20(0.4%)    0(0.0%)  ??? ...t/collects/drracket/private/rep.rkt:1379:13
                              ??? [13]                                                           100.0%
-------------------------------------------------------------------------------------------------------
                              call-with-exception-handler [7]                                    100.0%
[10] 2195(42.5%)   0(0.0%)  ??? ...ib/racket/collects/racket/promise.rkt:154:7
                              load-collections-xref [14]                                         100.0%
-------------------------------------------------------------------------------------------------------
                              ??? [8]                                                            100.0%
[11] 1055(20.4%)   0(0.0%)  loop ...private/../../racket/private/map.rkt:26:19
                              create-new-drscheme-frame [15]                                     100.0%
-------------------------------------------------------------------------------------------------------
                              loop [4]                                                            13.0%
                              call-with-exception-handler [7]                                     87.0%
[12]  230(4.4%)    0(0.0%)  call-with-break-parameterization ...heme.rkt:151:2
                              on-size method in ...rk/private/frame.rkt:255:2 [16]                87.0%
                              ??? [5]                                                             13.0%
-------------------------------------------------------------------------------------------------------
                              ??? [9]                                                            100.0%
[13]   20(0.4%)    0(0.0%)  ??? ...ollects/drracket/private/language.rkt:532:5
                              make-setup-printing-parameters [17]                                100.0%
-------------------------------------------------------------------------------------------------------
                              ??? [10]                                                           100.0%
[14] 2195(42.5%)   0(0.0%)  load-collections-xref ...lects/setup/xref.rkt:52:0
                              load-xref6 [18]                                                     96.6%
                              get-reader-thunks [21]                                               3.4%
-------------------------------------------------------------------------------------------------------
                              loop [11]                                                          100.0%
[15] 1055(20.4%)   0(0.0%)  create-new-drscheme-frame ...ivate/unit.rkt:5014:2
                              initialize-console method in ...cket/private/rep.rkt:389:4 [19]    100.0%
-------------------------------------------------------------------------------------------------------
                              call-with-break-parameterization [12]                              100.0%
[16]  200(3.9%)    0(0.0%)  on-size method in ...rk/private/frame.rkt:255:2 ...
                              ??? [20]                                                           100.0%
-------------------------------------------------------------------------------------------------------
                              ??? [13]                                                           100.0%
[17]   20(0.4%)    0(0.0%)  make-setup-printing-parameters ...nguage.rkt:369:2
                              [traversing imports] [22]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              load-collections-xref [14]                                         100.0%
[18] 2120(41.0%)   0(0.0%)  load-xref6 ...cket/collects/scribble/xref.rkt:36:0
                              for-loop [23]                                                      100.0%
-------------------------------------------------------------------------------------------------------
                              create-new-drscheme-frame [15]                                     100.0%
[19] 1055(20.4%)   0(0.0%)  initialize-console method in ...cket/private/rep.rkt:389:4 ...
                              reset-console method in ...ts/rackunit/tool.rkt:63:6 [24]           60.7%
                              end-edit-sequence method in text% [25]                              39.3%
-------------------------------------------------------------------------------------------------------
                              on-size method in ...rk/private/frame.rkt:255:2 [16]               100.0%
[20]  200(3.9%)  200(3.9%)  ??? ...et/collects/framework/preferences.rkt:136:3
-------------------------------------------------------------------------------------------------------
                              load-collections-xref [14]                                         100.0%
[21]   75(1.5%)    0(0.0%)  get-reader-thunks .../collects/setup/xref.rkt:40:0
                              get-dests [26]                                                     100.0%
-------------------------------------------------------------------------------------------------------
                              make-setup-printing-parameters [17]                                100.0%
[22]   20(0.4%)    0(0.0%)  [traversing imports] ...eshow/pict-convert.rkt:##f
                              [running body] [27]                                                100.0%
-------------------------------------------------------------------------------------------------------
                              load-xref6 [18]                                                    100.0%
[23] 2120(41.0%) 540(10.4%) for-loop ...racket/collects/scribble/xref.rkt:42:4
                              fasl->s-exp [28]                                                    33.7%
                              unpack370 [29]                                                      30.9%
                              for-loop [32]                                                        9.9%
-------------------------------------------------------------------------------------------------------
                              initialize-console method in ...cket/private/rep.rkt:389:4 [19]    100.0%
[24]  640(12.4%)   0(0.0%)  reset-console method in ...ts/rackunit/tool.rkt:63:6 ...
                              reset-console method in ...ugger/debug-tool.rkt:588:6 [30]         100.0%
-------------------------------------------------------------------------------------------------------
                              initialize-console method in ...cket/private/rep.rkt:389:4 [19]    100.0%
[25]  415(8.0%)    0(0.0%)  end-edit-sequence method in text% ...ext.rkt:758:2
                              after-edit-sequence method in ...k/private/editor.rkt:46:4 [31]    100.0%
-------------------------------------------------------------------------------------------------------
                              get-reader-thunks [21]                                             100.0%
[26]   75(1.5%)    0(0.0%)  get-dests ...b/racket/collects/setup/xref.rkt:14:0
                              for-loop [33]                                                      100.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [22]                                          100.0%
[27]   20(0.4%)    0(0.0%)  [running body] .../collects/slideshow/pict.rkt:##f
                              apply-contract [34]                                                100.0%
-------------------------------------------------------------------------------------------------------
                              for-loop [23]                                                      100.0%
[28]  715(13.8%) 525(10.2%) fasl->s-exp ...acket/collects/racket/fasl.rkt:18:0
                              make-base-namespace [36]                                            26.6%
-------------------------------------------------------------------------------------------------------
                              for-loop [23]                                                      100.0%
[29]  655(12.7%)   0(0.0%)  unpack370 ...llects/scribble/base-render.rkt:234:4
                              loop [45]                                                           81.7%
                              deserialize [37]                                                    18.3%
-------------------------------------------------------------------------------------------------------
                              reset-console method in ...ts/rackunit/tool.rkt:63:6 [24]          100.0%
[30]  640(12.4%)   0(0.0%)  reset-console method in ...ugger/debug-tool.rkt:588:6 ...
                              reset-console method in ...cket/private/rep.rkt:389:4 [35]         100.0%
-------------------------------------------------------------------------------------------------------
                              end-edit-sequence method in text% [25]                             100.0%
[31]  415(8.0%)  415(8.0%)  after-edit-sequence method in ...k/private/editor.rkt:46:4 ...
-------------------------------------------------------------------------------------------------------
                              for-loop [23]                                                      100.0%
[32]  210(4.1%)  210(4.1%)  for-loop ...ollects/scribble/base-render.rkt:239:8
-------------------------------------------------------------------------------------------------------
                              get-dests [26]                                                     100.0%
[33]   75(1.5%)    0(0.0%)  for-loop ...ib/racket/collects/setup/xref.rkt:21:2
                              get-info/full [38]                                                 100.0%
-------------------------------------------------------------------------------------------------------
                              [running body] [27]                                                100.0%
[34]   20(0.4%)    0(0.0%)  apply-contract ...t/contract/private/base.rkt:47:0
                              ??? [39]                                                           100.0%
-------------------------------------------------------------------------------------------------------
                              reset-console method in ...ugger/debug-tool.rkt:588:6 [30]         100.0%
[35]  640(12.4%)   0(0.0%)  reset-console method in ...cket/private/rep.rkt:389:4 ...
                              init-evaluation-thread method in ...cket/private/rep.rkt:389:4 [40]100.0%
-------------------------------------------------------------------------------------------------------
                              fasl->s-exp [28]                                                   100.0%
[36]  190(3.7%)  125(2.4%)  make-base-namespace .../private/namespace.rkt:26:2
                              [traversing imports] [43]                                           34.2%
-------------------------------------------------------------------------------------------------------
                              unpack370 [29]                                                     100.0%
[37]  120(2.3%)    0(0.0%)  deserialize .../racket/private/serialize.rkt:600:2
                              loop [41]                                                          100.0%
-------------------------------------------------------------------------------------------------------
                              for-loop [33]                                                      100.0%
[38]   75(1.5%)    0(0.0%)  get-info/full ...t/collects/setup/getinfo.rkt:32:0
                              get-info/full/ext [42]                                             100.0%
-------------------------------------------------------------------------------------------------------
                              apply-contract [34]                                                100.0%
[39]   20(0.4%)    0(0.0%)  ??? ...ts/racket/private/class-internal.rkt:2801:6
                              for-loop [44]                                                      100.0%
-------------------------------------------------------------------------------------------------------
                              reset-console method in ...cket/private/rep.rkt:389:4 [35]         100.0%
[40]  640(12.4%)   0(0.0%)  init-evaluation-thread method in ...cket/private/rep.rkt:389:4 ...
                              on-execute method in .../module-language.rkt:88:4 [46]             100.0%
-------------------------------------------------------------------------------------------------------
                              deserialize [37]                                                   100.0%
[41]  120(2.3%)    0(0.0%)  loop ...ollects/racket/private/serialize.rkt:582:8
                              loop [45]                                                          100.0%
-------------------------------------------------------------------------------------------------------
                              get-info/full [38]                                                 100.0%
[42]   75(1.5%)    0(0.0%)  get-info/full/ext ...llects/setup/getinfo.rkt:36:0
                              temp32 [47]                                                        100.0%
-------------------------------------------------------------------------------------------------------
                              make-base-namespace [36]                                           100.0%
[43]   65(1.3%)    0(0.0%)  [traversing imports] ...llects/racket/base.rkt:##f
                              [traversing imports] [48]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              ??? [39]                                                           100.0%
[44]   20(0.4%)    0(0.0%)  for-loop ...ket/private/class-internal.rkt:2943:12
                              ??? [49]                                                           100.0%
-------------------------------------------------------------------------------------------------------
                              lookup-shared! [54]                                                  2.8%
                              loop [41]                                                            7.8%
                              ??? [2]                                                             11.5%
                              unpack370 [29]                                                      29.5%
                              loop [45]                                                           48.5%
[45]  655(12.7%) 420(8.1%)  loop ...ollects/racket/private/serialize.rkt:444:4
                              loop [45]                                                           48.5%
                              loop [52]                                                           17.9%
                              map [51]                                                            11.5%
                              lookup-shared! [54]                                                  2.8%
-------------------------------------------------------------------------------------------------------
                              init-evaluation-thread method in ...cket/private/rep.rkt:389:4 [40]100.0%
[46]  640(12.4%)   0(0.0%)  on-execute method in .../module-language.rkt:88:4 ...
                              on-execute method in ...private/language.rkt:619:4 [50]            100.0%
-------------------------------------------------------------------------------------------------------
                              get-info/full/ext [42]                                             100.0%
[47]   75(1.5%)   75(1.5%)  temp32 ...b/racket/collects/setup/getinfo.rkt:49:8
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [43]                                          100.0%
[48]   65(1.3%)    0(0.0%)  [traversing imports] ...t/private/pre-base.rkt:##f
                              [traversing imports] [53]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              for-loop [44]                                                      100.0%
[49]   20(0.4%)   20(0.4%)  ??? ...cts/racket/contract/private/arrow.rkt:508:6
-------------------------------------------------------------------------------------------------------
                              on-execute method in .../module-language.rkt:88:4 [46]             100.0%
[50]  640(12.4%) 640(12.4%) on-execute method in ...private/language.rkt:619:4 ...
-------------------------------------------------------------------------------------------------------
                              loop [45]                                                          100.0%
[51]  300(5.8%)    0(0.0%)  map ...acket/collects/racket/private/map.rkt:21:13
                              loop [55]                                                          100.0%
-------------------------------------------------------------------------------------------------------
                              loop [45]                                                          100.0%
[52]  235(4.5%)  235(4.5%)  loop ...cket/collects/racket/private/map.rkt:53:19
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [48]                                          100.0%
[53]   65(1.3%)    0(0.0%)  [traversing imports] ...rivate/more-scheme.rkt:##f
                              [traversing imports] [56]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              loop [45]                                                          100.0%
[54]   55(1.1%)    0(0.0%)  lookup-shared! ...cket/private/serialize.rkt:426:2
                              loop [45]                                                          100.0%
-------------------------------------------------------------------------------------------------------
                              map [51]                                                             1.0%
                              loop [55]                                                           99.0%
[55]  300(5.8%)    0(0.0%)  loop ...cket/collects/racket/private/map.rkt:26:19
                              loop [55]                                                           99.0%
                              ??? [2]                                                              1.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [53]                                          100.0%
[56]   65(1.3%)    0(0.0%)  [traversing imports] ...ket/private/define.rkt:##f
                              [traversing imports] [57]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [56]                                          100.0%
[57]   65(1.3%)    0(0.0%)  [traversing imports] ...vate/letstx-scheme.rkt:##f
                              [traversing imports] [58]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [57]                                          100.0%
[58]   65(1.3%)    0(0.0%)  [traversing imports] ...et/private/stxcase.rkt:##f
                              [traversing imports] [59]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [58]                                          100.0%
[59]   65(1.3%)    0(0.0%)  [traversing imports] ...ivate/small-scheme.rkt:##f
                              [traversing imports] [60]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [59]                                          100.0%
[60]   65(1.3%)    0(0.0%)  [traversing imports] ...ivate/define-et-al.rkt:##f
                              [traversing imports] [61]                                          100.0%
-------------------------------------------------------------------------------------------------------
                              [traversing imports] [60]                                          100.0%
[61]   65(1.3%)   65(1.3%)  [traversing imports] ...acket/private/cond.rkt:##f
-------------------------------------------------------------------------------------------------------

-------------- next part --------------
Profiling results
-----------------
  Total cpu time observed: 3850ms (out of 8940ms)
  Number of samples taken: 6625 (once every 1ms)
  Threads observed:        54
  (Hiding functions with self<1.0% and local<2.0%: 38 of 99 hidden)

==============================================================================================================
                               Caller
 Idx   Total       Self      Name+src                                                                   Local%
       ms(pct)     ms(pct)     Callee
==============================================================================================================
 [1] 3325(86.4%) 1080(28.1%) ??? ...ects/mred/private/wx/common/queue.rkt:633:3
                               ??? [4]                                                                   67.5%
--------------------------------------------------------------------------------------------------------------
                               call-with-exception-handler [30]                                         100.0%
 [2] 1090(28.3%)    0(0.0%)  call-with-break-parameterization ...heme.rkt:151:2
                               on-subwindow-char method in frame% [5]                                    49.4%
                               ??? [3]                                                                   49.4%
                               on-focus method in editor-canvas% [7]                                      1.2%
                               ??? [4]                                                                    0.0%
--------------------------------------------------------------------------------------------------------------
                               call-with-break-parameterization [2]                                     100.0%
 [3] 1090(28.3%)    0(0.0%)  ??? ...cket/collects/mred/private/wxmenu.rkt:54:22
                               handle-key-event method in keymap% [6]                                   100.0%
--------------------------------------------------------------------------------------------------------------
                               call-with-break-parameterization [2]                                       0.0%
                               show-dialog [35]                                                           0.9%
                               ??? [1]                                                                   99.1%
 [4] 2245(58.3%)    0(0.0%)  ??? ...cts/mred/private/wx/common/queue.rkt:485:32
                               ??? [8]                                                                  100.0%
--------------------------------------------------------------------------------------------------------------
                               call-with-break-parameterization [2]                                     100.0%
 [5] 1090(28.3%)    0(0.0%)  on-subwindow-char method in frame% ...p.rkt:177:24
                               call-with-exception-handler [30]                                         100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [3]                                                                  100.0%
 [6] 1090(28.3%)    0(0.0%)  handle-key-event method in keymap% ...ap.rkt:445:2
                               chain-handle-key-event method in keymap% [9]                             100.0%
--------------------------------------------------------------------------------------------------------------
                               call-with-break-parameterization [2]                                     100.0%
 [7]   40(1.0%)     0(0.0%)  on-focus method in editor-canvas% ...vas.rkt:340:2
                               own-caret method in text% [10]                                           100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [4]                                                                  100.0%
 [8] 2245(58.3%)    0(0.0%)  ??? ...ects/mred/private/wx/common/queue.rkt:445:6
                               dispatch-on-char method in window% [12]                                   47.7%
                               colorer-callback method in ...rk/private/color.rkt:66:2 [13]              46.5%
                               do-on-paint method in ...mon/canvas-mixin.rkt:115:2 [18]                   4.9%
--------------------------------------------------------------------------------------------------------------
                               handle-key-event method in keymap% [6]                                    33.3%
                               for-loop [11]                                                             66.7%
 [9] 1090(28.3%)    0(0.0%)  chain-handle-key-event method in keymap% ...:491:2
                               for-loop [11]                                                             66.7%
                               core3741 [14]                                                              9.6%
                               ??? [15]                                                                   9.2%
                               core332 [16]                                                               6.6%
                               change-to-tab method in ...ket/private/unit.rkt:1355:4 [23]                3.7%
                               ??? [17]                                                                   3.5%
                               loop [19]                                                                  0.8%
--------------------------------------------------------------------------------------------------------------
                               on-focus method in editor-canvas% [7]                                    100.0%
[10]   40(1.0%)     0(0.0%)  own-caret method in text% ...e/wxme/text.rkt:653:2
                               redraw method in text% [32]                                              100.0%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                             100.0%
[11] 1090(28.3%)    0(0.0%)  for-loop ...cts/mred/private/wxme/keymap.rkt:479:4
                               chain-handle-key-event method in keymap% [9]                             100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [8]                                                                  100.0%
[12] 1090(28.3%)    0(0.0%)  dispatch-on-char method in window% ...ow.rkt:631:4
                               call-pre-on-char method in window% [20]                                  100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [8]                                                                  100.0%
[13] 1045(27.1%)    0(0.0%)  colorer-callback method in ...rk/private/color.rkt:66:2 ...
                               colorer-driver method in ...rk/private/color.rkt:66:2 [21]                52.2%
                               end-edit-sequence method in text% [22]                                    47.8%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                             100.0%
[14]  315(8.2%)     0(0.0%)  core3741 ...lects/drracket/private/unit.rkt:2886:8
                               change-to-tab method in ...ket/private/unit.rkt:1355:4 [23]               57.1%
                               initialize-console method in ...cket/private/rep.rkt:389:4 [26]           42.9%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                             100.0%
[15]  300(7.8%)     0(0.0%)  ??? ...t/collects/drracket/private/unit.rkt:4986:6
                               change-to-file method in ...ket/private/unit.rkt:1355:4 [24]             100.0%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                             100.0%
[16]  215(5.6%)     0(0.0%)  core332 ...lects/framework/private/finder.rkt:66:4
                               file-selector [25]                                                       100.0%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                             100.0%
[17]  115(3.0%)     0(0.0%)  ??? ...collects/framework/private/handler.rkt:93:5
                               ??? [51]                                                                 100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [8]                                                                  100.0%
[18]  110(2.9%)     0(0.0%)  do-on-paint method in ...mon/canvas-mixin.rkt:115:2 ...
                               get-cr method in backing-dc% [27]                                         77.3%
                               clean-slate method in backing-dc% [28]                                    22.7%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                             100.0%
[19]   25(0.6%)     0(0.0%)  loop ...collects/drracket/private/unit.rkt:2977:11
                               on-close method in tab% [29]                                             100.0%
--------------------------------------------------------------------------------------------------------------
                               dispatch-on-char method in window% [12]                                    3.7%
                               call-pre-on-char method in window% [20]                                   96.3%
[20] 1090(28.3%)    0(0.0%)  call-pre-on-char method in window% ...ow.rkt:651:4
                               call-pre-on-char method in window% [20]                                   96.3%
                               call-with-exception-handler [30]                                           3.7%
--------------------------------------------------------------------------------------------------------------
                               colorer-callback method in ...rk/private/color.rkt:66:2 [13]             100.0%
[21]  545(14.2%)    0(0.0%)  colorer-driver method in ...rk/private/color.rkt:66:2 ...
                               color method in ...rk/private/color.rkt:66:2 [31]                        100.0%
--------------------------------------------------------------------------------------------------------------
                               colorer-callback method in ...rk/private/color.rkt:66:2 [13]             100.0%
[22]  500(13.0%)    0(0.0%)  end-edit-sequence method in text% ...ext.rkt:758:2
                               redraw method in text% [32]                                              100.0%
--------------------------------------------------------------------------------------------------------------
                               chain-handle-key-event method in keymap% [9]                              40.0%
                               core3741 [14]                                                             60.0%
[23]  300(7.8%)     0(0.0%)  change-to-tab method in ...ket/private/unit.rkt:1355:4 ...
                               restore-visible-tab-regions method in ...ket/private/unit.rkt:1355:4 [33]100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [15]                                                                 100.0%
[24]  300(7.8%)     0(0.0%)  change-to-file method in ...ket/private/unit.rkt:1355:4 ...
                               after-load-file method in ...ork/private/text.rkt:74:2 [34]               71.7%
                               update-shown method in ...et/private/debug.rkt:1580:4 [41]                28.3%
--------------------------------------------------------------------------------------------------------------
                               core332 [16]                                                             100.0%
[25]  215(5.6%)     0(0.0%)  file-selector ...rivate/wx/gtk/filedialog.rkt:58:0
                               show-dialog [35]                                                         100.0%
--------------------------------------------------------------------------------------------------------------
                               core3741 [14]                                                            100.0%
[26]  135(3.5%)   135(3.5%)  initialize-console method in ...cket/private/rep.rkt:389:4 ...
--------------------------------------------------------------------------------------------------------------
                               do-on-paint method in ...mon/canvas-mixin.rkt:115:2 [18]                 100.0%
[27]   85(2.2%)     0(0.0%)  get-cr method in backing-dc% ...cking-dc.rkt:119:4
                               do-make-object [36]                                                      100.0%
--------------------------------------------------------------------------------------------------------------
                               do-on-paint method in ...mon/canvas-mixin.rkt:115:2 [18]                 100.0%
[28]   25(0.6%)     0(0.0%)  clean-slate method in backing-dc% ...-dc.rkt:142:4
                               reset-recording method in ...rivate/record-dc.rkt:344:2 [37]             100.0%
--------------------------------------------------------------------------------------------------------------
                               loop [19]                                                                100.0%
[29]   25(0.6%)     0(0.0%)  on-close method in tab% ...private/unit.rkt:1246:6
                               on-close method in ...cket/private/rep.rkt:389:4 [38]                    100.0%
--------------------------------------------------------------------------------------------------------------
                               on-subwindow-char method in frame% [5]                                    49.4%
                               call-pre-on-char method in window% [20]                                   49.4%
[30] 1090(28.3%)    0(0.0%)  call-with-exception-handler ...re-scheme.rkt:268:2
                               call-with-break-parameterization [2]                                     100.0%
--------------------------------------------------------------------------------------------------------------
                               colorer-driver method in ...rk/private/color.rkt:66:2 [21]               100.0%
[31]  545(14.2%)    0(0.0%)  color method in ...rk/private/color.rkt:66:2 ...:4
                               do-change-style method in text% [39]                                     100.0%
--------------------------------------------------------------------------------------------------------------
                               own-caret method in text% [10]                                             7.4%
                               end-edit-sequence method in text% [22]                                    92.6%
[32]  540(14.0%)   40(1.0%)  redraw method in text% ...ate/wxme/text.rkt:4895:2
                               core1440 [40]                                                             92.6%
--------------------------------------------------------------------------------------------------------------
                               change-to-tab method in ...ket/private/unit.rkt:1355:4 [23]              100.0%
[33]  300(7.8%)     0(0.0%)  restore-visible-tab-regions method in ...ket/private/unit.rkt:1355:4 ...
                               update-shown method in ...et/private/debug.rkt:1580:4 [41]               100.0%
--------------------------------------------------------------------------------------------------------------
                               change-to-file method in ...ket/private/unit.rkt:1355:4 [24]             100.0%
[34]  215(5.6%)     0(0.0%)  after-load-file method in ...ork/private/text.rkt:74:2 ...
                               after-load-file method in ...ket/private/unit.rkt:531:8 [42]             100.0%
--------------------------------------------------------------------------------------------------------------
                               file-selector [25]                                                       100.0%
[35]  215(5.6%)   175(4.5%)  show-dialog ...d/private/wx/gtk/stddialog.rkt:38:0
                               ??? [4]                                                                   18.6%
--------------------------------------------------------------------------------------------------------------
                               get-cr method in backing-dc% [27]                                        100.0%
[36]   85(2.2%)     0(0.0%)  do-make-object ...rivate/class-internal.rkt:3941:0
                               continue-make-object [43]                                                100.0%
--------------------------------------------------------------------------------------------------------------
                               clean-slate method in backing-dc% [28]                                   100.0%
[37]   25(0.6%)     0(0.0%)  reset-recording method in ...rivate/record-dc.rkt:344:2 ...
                               get-initial-matrix method in dc% [44]                                    100.0%
--------------------------------------------------------------------------------------------------------------
                               on-close method in tab% [29]                                             100.0%
[38]   25(0.6%)     0(0.0%)  on-close method in ...cket/private/rep.rkt:389:4 ...
                               ??? [51]                                                                 100.0%
--------------------------------------------------------------------------------------------------------------
                               color method in ...rk/private/color.rkt:66:2 [31]                        100.0%
[39]  545(14.2%)    0(0.0%)  do-change-style method in text% ...text.rkt:3694:2
                               do-find-snip method in text% [47]                                         64.2%
                               loop [49]                                                                 35.8%
--------------------------------------------------------------------------------------------------------------
                               redraw method in text% [32]                                              100.0%
[40]  500(13.0%)    0(0.0%)  core1440 ...ects/mred/private/wxme/text.rkt:4741:2
                               update-graphics [45]                                                     100.0%
--------------------------------------------------------------------------------------------------------------
                               change-to-file method in ...ket/private/unit.rkt:1355:4 [24]              22.1%
                               restore-visible-tab-regions method in ...ket/private/unit.rkt:1355:4 [33] 77.9%
[41]  385(10.0%)    0(0.0%)  update-shown method in ...et/private/debug.rkt:1580:4 ...
                               update-shown method in ...ket/private/unit.rkt:1355:4 [46]               100.0%
--------------------------------------------------------------------------------------------------------------
                               after-load-file method in ...ork/private/text.rkt:74:2 [34]              100.0%
[42]  215(5.6%)     0(0.0%)  after-load-file method in ...ket/private/unit.rkt:531:8 ...
                               core741 [48]                                                             100.0%
--------------------------------------------------------------------------------------------------------------
                               do-make-object [36]                                                      100.0%
[43]   85(2.2%)     0(0.0%)  continue-make-object .../class-internal.rkt:3960:0
                               ??? [50]                                                                 100.0%
--------------------------------------------------------------------------------------------------------------
                               reset-recording method in ...rivate/record-dc.rkt:344:2 [37]             100.0%
[44]   25(0.6%)    25(0.6%)  get-initial-matrix method in dc% ...e/dc.rkt:478:4
--------------------------------------------------------------------------------------------------------------
                               core1440 [40]                                                             12.0%
                               update-graphics [45]                                                      88.0%
[45]  500(13.0%)    0(0.0%)  update-graphics ...d/private/wxme/mline.rkt:1072:0
                               update-graphics [45]                                                      88.0%
                               update-here [52]                                                          12.0%
--------------------------------------------------------------------------------------------------------------
                               update-shown method in ...et/private/debug.rkt:1580:4 [41]               100.0%
[46]  385(10.0%)    0(0.0%)  update-shown method in ...ket/private/unit.rkt:1355:4 ...
                               ??? [51]                                                                 100.0%
--------------------------------------------------------------------------------------------------------------
                               do-change-style method in text% [39]                                     100.0%
[47]  350(9.1%)     0(0.0%)  do-find-snip method in text% ...me/text.rkt:4467:2
                               find-snip/pos method in text% [53]                                       100.0%
--------------------------------------------------------------------------------------------------------------
                               after-load-file method in ...ket/private/unit.rkt:531:8 [42]             100.0%
[48]  215(5.6%)     0(0.0%)  core741 ...llects/drracket/private/unit.rkt:710:10
                               ??? [51]                                                                 100.0%
--------------------------------------------------------------------------------------------------------------
                               do-change-style method in text% [39]                                     100.0%
[49]  195(5.1%)     0(0.0%)  loop ...ollects/mred/private/wxme/text.rkt:3740:22
                               continue-refresh method in text% [54]                                    100.0%
--------------------------------------------------------------------------------------------------------------
                               continue-make-object [43]                                                100.0%
[50]   85(2.2%)    85(2.2%)  ??? ...et/collects/mred/private/wx/gtk/dc.rkt:46:2
--------------------------------------------------------------------------------------------------------------
                               on-close method in ...cket/private/rep.rkt:389:4 [38]                      3.4%
                               ??? [17]                                                                  15.5%
                               core741 [48]                                                              29.1%
                               update-shown method in ...ket/private/unit.rkt:1355:4 [46]                52.0%
[51]  740(19.2%)  320(8.3%)  ??? ...et/collects/framework/preferences.rkt:136:3
                               ??? [56]                                                                  56.8%
--------------------------------------------------------------------------------------------------------------
                               update-graphics [45]                                                     100.0%
[52]  500(13.0%)    0(0.0%)  update-here .../mred/private/wxme/mline.rkt:1077:2
                               loop [55]                                                                100.0%
--------------------------------------------------------------------------------------------------------------
                               do-find-snip method in text% [47]                                        100.0%
[53]  350(9.1%)   350(9.1%)  find-snip/pos method in text% ...e/text.rkt:4480:2
--------------------------------------------------------------------------------------------------------------
                               loop [49]                                                                100.0%
[54]  195(5.1%)   195(5.1%)  continue-refresh method in text% ...ext.rkt:5516:2
--------------------------------------------------------------------------------------------------------------
                               update-here [52]                                                         100.0%
[55]  500(13.0%)  315(8.2%)  loop ...ollects/mred/private/wxme/mline.rkt:1082:7
                               core726 [58]                                                              37.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [51]                                                                 100.0%
[56]  420(10.9%)    0(0.0%)  ??? ...3/lib/racket/collects/racket/file.rkt:475:5
                               get-prefs [57]                                                           100.0%
--------------------------------------------------------------------------------------------------------------
                               ??? [56]                                                                 100.0%
[57]  420(10.9%)  420(10.9%) get-prefs ...racket/collects/racket/file.rkt:323:0
--------------------------------------------------------------------------------------------------------------
                               loop [55]                                                                100.0%
[58]  185(4.8%)     0(0.0%)  core726 ...ects/racket/snip/private/snip.rkt:431:2
                               get-text-height method in style% [59]                                    100.0%
--------------------------------------------------------------------------------------------------------------
                               core726 [58]                                                             100.0%
[59]  185(4.8%)     0(0.0%)  get-text-height method in style% ...tyle.rkt:900:2
                               reset-text-metrics method in style% [60]                                 100.0%
--------------------------------------------------------------------------------------------------------------
                               get-text-height method in style% [59]                                    100.0%
[60]  185(4.8%)     0(0.0%)  reset-text-metrics method in style% ...e.rkt:885:2
                               cache-font-metrics-key method in dc% [61]                                100.0%
--------------------------------------------------------------------------------------------------------------
                               reset-text-metrics method in style% [60]                                 100.0%
[61]  185(4.8%)   185(4.8%)  cache-font-metrics-key method in dc% ....rkt:515:4
--------------------------------------------------------------------------------------------------------------


Posted on the users mailing list.