Re: slow prelude-lml startup

On Tue, 2010-06-22 at 12:33 +0200, Yoann Vandoorselaere wrote:
> Le mardi 25 mai 2010 à 06:02 -0500, LC Bruzenak a écrit :
> > On Tue, 2010-05-04 at 16:49 +0200, Yoann Vandoorselaere wrote:

> ...
> Could you run Prelude-LML using strace (with the -T option), and send us
> the resulting log. This might help understand the problem.
> 
> strace -T prelude-lml
> 
> Regards,
> 

I think the mail list may throw away my replies with attachments.

Here is the excerpt from the trace where the time is spent. Hopefully
there is enough context. See the long time on the 1st recvfrom:

open("/var/spool/prelude/prelude-lml/127.0.0.1:4690/data0.journal", O_RDWR|O_CREAT, 0660) = 12 <0.000028>
fcntl(12, F_GETFD)                      = 0 <0.000019>
fcntl(12, F_SETFD, FD_CLOEXEC)          = 0 <0.000018>
fstat(12, {st_mode=S_IFREG|0660, st_size=0, ...}) = 0 <0.000019>
fstat(11, {st_mode=S_IFREG|0660, st_size=0, ...}) = 0 <0.000019>
umask(022)                              = 07 <0.000018>
socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 13 <0.000029>
fcntl(13, F_GETFD)                      = 0 <0.000019>
fcntl(13, F_SETFD, FD_CLOEXEC)          = 0 <0.000019>
fcntl(13, F_SETOWN, 3694)               = 0 <0.000019>
setsockopt(13, SOL_SOCKET, SO_KEEPALIVE, [1], 4) = 0 <0.000020>
connect(13, {sa_family=AF_INET, sin_port=htons(4690), sin_addr=inet_addr("127.0.0.1")}, 16) = 0 <0.000220>
getrusage(RUSAGE_SELF, {ru_utime={0, 5999}, ru_stime={0, 8998}, ...}) = 0 <0.000019>
times({tms_utime=0, tms_stime=0, tms_cutime=0, tms_cstime=0}) = 429470576 <0.000019>
getrusage(RUSAGE_SELF, {ru_utime={0, 5999}, ru_stime={0, 8998}, ...}) = 0 <0.000018>
times({tms_utime=0, tms_stime=0, tms_cutime=0, tms_cstime=0}) = 429470576 <0.000019>
getrusage(RUSAGE_SELF, {ru_utime={0, 5999}, ru_stime={0, 8998}, ...}) = 0 <0.000019>
times({tms_utime=0, tms_stime=0, tms_cutime=0, tms_cstime=0}) = 429470576 <0.000019>
sendto(13, "\26\3\2\0X\1\0\0T\3\2Lr\307\362t\35s\331\344\10u\335\276\363\34{;c\361}\366"..., 93, 0, NULL, 0) = 93 <0.000050>
recvfrom(13, "\26\3\2\0J", 5, 0, NULL, NULL) = 5 <166.573512>
recvfrom(13, "\2\0\0F\3\2Lr\310\230\244\234\324\346\350\343\16\370QP\211<5\235G\f\356\345w3Y\302"..., 74, 0, NULL, NULL) = 74 <0.000095>
recvfrom(13, "\26\3\2\1\353", 5, 0, NULL, NULL) = 5 <0.000021>
recvfrom(13, "\v\0\1\347\0\1\344\0\1\3410\202\1\3350\202\1H\240\3\2\1\2\2\10\365\364jLk\215\7"..., 491, 0, NULL, NULL) = 491 <0.000021>
recvfrom(13, "\26\3\2\1\215", 5, 0, NULL, NULL) = 5 <0.004350>
recvfrom(13, "\f\0\1\211\0\200\202\267\n\0046[/~wqH<ibka\261\33?\33\325\37\261\17\217\2"..., 397, 0, NULL, NULL) = 397 <0.000021>

I believe this is all part of the new connection thread in the
libprelude code. I can send more/all of this trace if needed. 

Thanks,
LCB.

-- 
LC (Lenny) Bruzenak
[email protected]

_______________________________________________
Prelude-user site list
[email protected]
http://lists.prelude-technologies.com/mailman/listinfo/prelude-user