Re: Long compilation times

[Cameron Horsburgh]

On Mon, Jan 12, 2009 at 06:58:07AM +1100, Cameron Horsburgh wrote:
> On Sun, Jan 11, 2009 at 05:29:14PM +0100, John Mandereau wrote:
> > Cameron Horsburgh a écrit :
> >> I'm running a 2.4 GHz P4 with 1.25 Gig of Ram. The machine is aging,
> >> but it should be up to this challenge. I've noticed scores compile
> >> much quicker on much lower specced machines, so I'm suspecting
> >> something peculiar to my installation, but I don't know where to look.
> >
> ,----
> | 
> |       address@hidden:~/temp$ time lilypond score.ly
> |       GNU LilyPond 2.12.2
> |       Processing `score.ly'
> |       Parsing...
> |       Interpreting music... [8][16][24][32][40][48][56][64][72][80][88]
> |       Preprocessing graphical objects...
> |       Finding the ideal number of pages...
> |       Fitting music on 1 or 2 pages...
> |       Drawing systems...
> |       Layout output to `score.ps'...
> |       Converting to `./score.pdf'...
> | 
> |       real    12m13.780s
> |       user    10m22.640s
> |       sys     0m2.062s
> | 
> `----
> 
> 

For no reason other than I could, I tried running strace on the
lilypond run. I have little idea what I'm doing or what I'm looking
at, but I have been able to find clues to problem programs in the past
this way.

For the record, the exact command I ran was:

     strace -e trace=file -o straceLilypond -t lilypond score.ly

I won't put the whole output here (unless someone really wants it),
but I noticed a couple of things that may or may not be useful. First,
there were many calls to files that don't exist on my system:

,----
| 
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/fonts/svg/score.ly.ly", 
0xbfd8a054) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/fonts/type1/score.ly.ly", 
0xbfd8a054) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/fonts/otf/score.ly.ly", 
0xbfd8a054) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/scm/score.ly.ly", 
0xbfd8a054) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/ps/score.ly.ly", 
0xbfd8a054) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/ly/score.ly.ly", 
0xbfd8a054) = -1 ENOENT (No such file or directory)
| 
`----

and

,----
| 
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/fonts/svg/markup-init.ly", 
0xbfd885c4) = -1 ENOENT (No such file or directory)
| 20:21:40 
stat64("/usr/local/share/lilypond/2.12.2/fonts/type1/markup-init.ly", 
0xbfd885c4) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/fonts/otf/markup-init.ly", 
0xbfd885c4) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/scm/markup-init.ly", 
0xbfd885c4) = -1 ENOENT (No such file or directory)
| 20:21:40 stat64("/usr/local/share/lilypond/2.12.2/ps/markup-init.ly", 
0xbfd885c4) = -1 ENOENT (No such file or directory)
| 
`----
are typical examples.

Second, here is the context of what I'm presuming is the
'Preprocessing graphical objects...' slowdown:

,----
| 
| 
| 20:21:45 open("/etc/pango/pango.modules", O_RDONLY) = -1 ENOENT (No such file 
or directory)
| 20:21:45 open("/proc/meminfo", O_RDONLY) = 6
| 20:21:45 stat64("/usr/lib/pango/1.6.0/modules/pango-basic-fc.so", 
{st_mode=S_IFREG|0644, st_size=6656, ...}) = 0
| 20:21:45 open("/usr/lib/pango/1.6.0/modules/pango-basic-fc.so", O_RDONLY) = 6
| 20:21:45 
open("/usr/local/share/lilypond/2.12.2/fonts/otf/CenturySchL-Roma.otf", 
O_RDONLY) = 6
| 20:21:46 
open("/usr/local/share/lilypond/2.12.2/fonts/otf/CenturySchL-Roma.otf", 
O_RDONLY) = 6
| 20:22:32 
open("/usr/local/share/lilypond/2.12.2/fonts/otf/CenturySchL-Bold.otf", 
O_RDONLY) = 6
| 20:28:24 --- SIGWINCH (Window changed) @ 0 (0) ---
| 20:35:05 
open("/usr/local/share/lilypond/2.12.2/fonts/otf/CenturySchL-Roma.otf", 
O_RDONLY) = 6
| 20:35:08 open("score.ps", O_WRONLY|O_CREAT|O_TRUNC|O_LARGEFILE, 0666) = 6
| 20:35:08 
stat64("/usr/local/share/lilypond/2.12.2/fonts/otf/CenturySchL-Bold.otf", 
{st_mode=S_IFREG|0644, st_size=68736, ...}) = 0
| 
`----

In my untrained opinion I have absolutely no idea if there's anything
wrong here. If somebody else has a clue, I'd love to hear your wisdom!

-- 

Cameron Horsburgh

Blog: http://spiritcry.wordpress.com/