This is the mail archive of the guile@sourceware.cygnus.com mailing list for the Guile project.


Index Nav: [Date Index] [Subject Index] [Author Index] [Thread Index]
Message Nav: [Date Prev] [Date Next] [Thread Prev] [Thread Next]

Some profile results: scm_sloppy_assq?


Hi,

Did anything weird happen to scm_sloppy_asc?  Of course, it could be
that 1.3.4's version for some reason worked remarkably well with
LilyPond's (in that case probably ill-using-guile) internals.

For 1.3.5, the number of calls increased by 1.5 but uses rediculously
more cycles, 626 seconds iso 0.38 seconds?

Greetings,

1.3.4, lilypond-1.3.59-uu1, coriolan:

Each sample counts as 0.01 seconds.
  %   cumulative   self              self     total           
 time   seconds   seconds    calls  ms/call  ms/call  name    
  8.47     37.05    37.05 16034857     0.00     0.00  scm_intern_obarray_soft
  4.43     56.43    19.38 16039735     0.00     0.00  scm_strhash
  4.37     75.56    19.13 38304481     0.00     0.00  Item::line_l(void) const
  3.80     92.16    16.60  7653200     0.00     0.00  scm_gc_mark
  3.40    107.02    14.86       68   218.53   219.71  scm_gc_sweep
  2.94    119.86    12.84 95018954     0.00     0.00  Score_element::parent_l(Ax
is) const
  2.76    131.92    12.06   145315     0.08     0.59  Score_element::handle_brok
en_smobs(long, long)
  2.42    142.50    10.58  1074898     0.01     0.03  scm_deval
  2.10    151.70     9.20 53439168     0.00     0.00  unsmob_element(long)
  ...
  0.09    365.77     0.38  1803382     0.00     0.00  scm_sloppy_assq


index % time    self  children    called     name
                0.00    0.00     553/1803382     scm_procedure_property [2590]
                0.00    0.00     606/1803382     scm_set_procedure_property_x [2
341]
                0.00    0.00    2731/1803382     scm_hash_fn_create_handle_x [21
24]
                0.16    0.00  781163/1803382     scm_hash_fn_get_handle [552]
                0.21    0.00 1018329/1803382     scm_assq_set_x [538]
[626]    0.1    0.38    0.00 1803382         scm_sloppy_assq [626]
-----------------------------------------------

((gc-time-taken . 4854) (cells-allocated . 3790365) (cell-heap-size . 7962624) (bytes-malloced . 28815081) (gc-malloc-threshold . 29449882) (cell-heap-segments (808087560 . 807825416) (808615944 . 808091656) (810192904 . 808620040) (814915592 . 810197000) (829075464 . 814919688) (871546888 . 829079560)))
user: 1397.94(139794) system:   3.24(324)
elapsed: 1406.98
MAXSIZE: 115.762M(29635), MAXRSS: 115.762M(29635)
AVGSIZE: 83.555M(21390), AVGRSS: 83.555M(21390)




1.3.5, lilypond-1.3.59-uu2, coriolan:

Each sample counts as 0.01 seconds.
  %   cumulative   self              self     total           
 time   seconds   seconds    calls   s/call   s/call  name    
 56.97    625.65   625.65  2798733     0.00     0.00  scm_sloppy_assq
  4.05    670.16    44.51 16034889     0.00     0.00  scm_intern_obarray_soft
  3.23    705.60    35.44 13682521     0.00     0.00  scm_gc_mark
  1.77    725.04    19.44 16039778     0.00     0.00  scm_strhash
  1.63    742.96    17.92 38304481     0.00     0.00  Item::line_l(void) const
  1.49    759.32    16.36       78     0.21     0.21  scm_gc_sweep
  1.30    773.60    14.28 102928932     0.00     0.00  Score_element::parent_l(A
xis) const
  1.03    784.94    11.34   145315     0.00     0.00  Score_element::handle_brok
en_smobs(long, long)
  0.86    794.43     9.49 53439168     0.00     0.00  unsmob_element(long)


index % time    self  children    called     name
                0.12    0.00     554/2798733     scm_procedure_property [995]
                0.14    0.00     611/2798733     scm_set_procedure_property_x [9
69]
               58.16    0.00  260162/2798733     scm_hash_fn_create_handle_x [56
]
              227.64    0.00 1018329/2798733     scm_assq_set_x [25]
              339.59    0.00 1519077/2798733     scm_hash_fn_get_handle [17]
[13]    57.0  625.65    0.00 2798733         scm_sloppy_assq [13]
-----------------------------------------------

((gc-time-taken . 9077) (cells-allocated . 4262286) (cell-heap-size . 5056864) (bytes-malloced . 34083349) (gc-malloc-threshold . 35329605) (cell-heap-segments (270957392 . 270909344) (808214080 . 807845896) (808406632 . 808214536) (808823256 . 808407048) (809513184 . 808824840) (810670224 . 809517072) (812609104 . 810672136) (814706592 . 812609544) (816803744 . 814706696) (818900896 . 816803848) (820998048 . 818901000) (823095200 . 820998152) (825192352 . 823095304) (827289504 . 825192456) (829386656 . 827289608) (831483808 . 829386760) (833580960 . 831483912) (835678112 . 833581064) (837776320 . 835678224) (839876512 . 837779464) (841973664 . 839876616) (844071872 . 841973776) (846172064 . 844075016) (848269216 . 846172168)))
user: 2756.92(275692) system:   5.20(520)
elapsed: 2768.39
MAXSIZE: 96.066M(24593), MAXRSS: 96.066M(24593)
AVGSIZE: 72.281M(18504), AVGRSS: 72.281M(18504)


-- 
Jan Nieuwenhuizen <janneke@gnu.org> | GNU LilyPond - The music typesetter
http://www.xs4all.nl/~jantien       | http://www.lilypond.org

Index Nav: [Date Index] [Subject Index] [Author Index] [Thread Index]
Message Nav: [Date Prev] [Date Next] [Thread Prev] [Thread Next]