RE: Repost: "Deferring operation" messages, slow operation?

[Darren Gamble <darren.gamble@sjrb.ca>]

> > Good day,
> >
> > I posted this message a week ago- unfortunately, I didn't 
> receive a reply.
> >
> > Is there anyone who has any idea what could be causing this?
> >
> > Thanks,
> 
> Hi Darren,
> 
> Unfortunately, I have no idea what is causing that for you.  
> One thing you 
> might do, is set up a test server, and turn up debugging on 
> slapd, and see 
> if you can create the problem there.  It might give you some 
> idea of what 
> is happening.  "truss" might give you some hints as well.
> 
> --Quanah
 
Thanks for your reply!

This is actually a test machine- we wanted to make 100% sure everything was
working properly before we modified our prod machines.

I have tried to increase the logging level as you have suggested, although
without the slightest clue about what might be causing this, I am not sure
what log levels to select.  Some of the log levels cause our testing queries
to time out, just due to the logging.

I've included a section of the log at loglevel "1".  While I can easily
follow the "normal" log for connections and queries, this is more or less
Greek to me.  The original "deferring operation" messages don't appear at
this level, just these "deferring conn" messages- I presume because they're
not included at that level.

"truss" is Solaris specific, I believe.  There's probably something similar
on Linux, although I am not sure if the information that I would get would
mean anything to me.

Also, do these "failed" messages mean anything to anyone else, or are they
not related?

Thanks again,



Nov 12 10:19:09 sr3ef slapd[21089]: ber_get_next on fd 23 failed errno=0
(Success)
Nov 12 10:19:09 sr3ef slapd[21089]: connection_read(23): input error=-2
id=13, closing.
Nov 12 10:19:09 sr3ef slapd[21089]: connection_closing: readying conn=13
sd=23 for close
Nov 12 10:19:09 sr3ef slapd[21089]: connection_close: deferring conn=13
sd=23
Nov 12 10:19:09 sr3ef slapd[21089]: connection_resched: attempting closing
conn=13 sd=23
Nov 12 10:19:09 sr3ef slapd[21089]: connection_close: conn=13 sd=23
Nov 12 10:19:09 sr3ef slapd[21089]: connection_get(20): got connid=11
Nov 12 10:19:09 sr3ef slapd[21089]: connection_read(20): checking for input
on id=11
Nov 12 10:19:09 sr3ef slapd[21089]: ber_get_next on fd 20 failed errno=0
(Success)
Nov 12 10:19:09 sr3ef slapd[21089]: connection_read(20): input error=-2
id=11, closing.
Nov 12 10:19:09 sr3ef slapd[21089]: connection_closing: readying conn=11
sd=20 for close
Nov 12 10:19:09 sr3ef slapd[21089]: connection_close: deferring conn=11
sd=20
Nov 12 10:19:09 sr3ef slapd[21089]: connection_get(21): got connid=12
Nov 12 10:19:09 sr3ef slapd[21089]: connection_read(21): checking for input
on id=12
Nov 12 10:19:09 sr3ef slapd[21089]: ber_get_next on fd 21 failed errno=0
(Success)
Nov 12 10:19:09 sr3ef slapd[21089]: connection_read(21): input error=-2
id=12, closing.


============================
Darren Gamble
Planner, Regional Services
Shaw Cablesystems GP
630 - 3rd Avenue SW
Calgary, Alberta, Canada
T2P 4L4
(403) 781-4948