Scm_sloppy_assq mostly fixed, still real slow

[Jan Nieuwenhuizen <janneke at gnu dot org>]

Hi,

Ok, with scm_unprotect_object fixed, 1.3.4 and 1.3.5 are comparable
wrt execution times (1.3.5 is a lot better on memory usage).  However,
with my fixed 1.3.5, I still see quite some time in scm_sloppy_assq,
(half a minute), where I hardly see any time for 1.3.4 (half a second).
Other entries look similar.

Any additional ideas?

Greetings,
Jan.

ps: Still haven't tuned memory env parameters, or fixed LilyPond,
    just reverted to 59.uu1.

1.3.5 + scm_unpotect_object fix, lily 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    
  7.95     36.06    36.06 16034962     0.00     0.00  scm_intern_obarray_soft
  6.09     63.71    27.65 18853132     0.00     0.00  scm_sloppy_assq
  5.45     88.45    24.74 10482826     0.00     0.00  scm_gc_mark
  4.14    107.25    18.80 16039851     0.00     0.00  scm_strhash
  3.95    125.15    17.90 38304481     0.00     0.00  Item::line_l(void) const
  2.97    138.62    13.47 98865534     0.00     0.00  Score_element::parent_l(Ax
is) const
  2.76    151.13    12.51   145315     0.09     0.59  Score_element::handle_brok
en_smobs(long, long)
  2.66    163.20    12.07       69   174.93   175.80  scm_gc_sweep
  1.96    172.08     8.88 53439168     0.00     0.00  unsmob_element(long)

((gc-time-taken . 3824) (cells-allocated . 4597483) (cell-heap-size . 4948904) (bytes-malloced . 30078548) (gc-malloc-threshold . 32928543) (cell-heap-segments (270334096 . 270286048) (805834304 . 805466120) (806026856 . 805834760) (806379448 . 806027272) (807019848 . 806379528) (808079664 . 807022608) (809860352 . 808083464) (811958176 . 809861128) (814055328 . 811958280) (816152480 . 814055432) (818249632 . 816152584) (820346784 . 818249736) (822443936 . 820346888) (824541088 . 822444040) (826638240 . 824541192) (828735392 . 826638344) (830832544 . 828735496) (832930752 . 830832656) (835030944 . 832933896) (837128096 . 835031048) (838729808 . 837128208) (840830880 . 838733832) (842929088 . 840830992) (845029280 . 842932232)))
user: 546.52(54652) system:   2.45(245)
elapsed: 577.09
MAXSIZE: 88.094M(22552), MAXRSS: 88.094M(22552)
AVGSIZE: 60.766M(15556), AVGRSS: 60.766M(15556)


coriolan, 1.3.4, lily 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


((gc-time-taken . 3139) (cells-allocated . 3790361) (cell-heap-size . 7962624) (bytes-malloced . 28815081) (gc-malloc-threshold . 29449882) (cell-heap-segments (805728264 . 805466120) (806256648 . 805732360) (807833608 . 806260744) (812556296 . 807837704) (826716168 . 812560392) (869187592 . 826720264)))
user: 523.85(52385) system:   3.45(345)
elapsed: 529.28
MAXSIZE: 113.535M(29065), MAXRSS: 113.535M(29065)
AVGSIZE: 81.516M(20868), AVGRSS: 81.516M(20868)


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