[Date Prev][Date Next] [Chronological] [Thread] [Top]
Re: Suggest slapd loglevel [Comparison Logs]

To: Matthew J Backes <lucca@csun.edu>
Subject: Re: Suggest slapd loglevel [Comparison Logs]
From: Caylan Van Larson <caylan@cs.und.edu>
Date: Fri, 13 Sep 2002 12:13:44 -0500 (CDT)
Cc: openldap-software@OpenLDAP.org, root <root@cs.und.edu>
In-reply-to: <Pine.LNX.4.44.0209131024560.32187-100000@betamax.aero.und.edu>
Here is one more similar log except this connection did not crash the 
server.  I am presenting this because this shows usefull imformation at 
the end (last 15 lines).  I have attached the last 15 lines from the last 
log I sent (the one that crashed).

Sample log that does not show crash:
--SNIP
Sep 13 11:31:32 slurpy slapd[2558]: do_search
Sep 13 11:31:32 slurpy slapd[2558]: conn=7021 op=29 SRCH base="ou=People,dc=aero,dc=und,dc=edu" scope=2 filter="(&(objectClass=posixAccount)(uid=mbux))"
Sep 13 11:31:32 slurpy slapd[2558]: => ldbm_back_search
Sep 13 11:31:32 slurpy slapd[2558]: dn2entry_r: dn: "OU=PEOPLE,DC=AERO,DC=UND,DC=EDU"
Sep 13 11:31:32 slurpy slapd[2558]: => dn2id( "OU=PEOPLE,DC=AERO,DC=UND,DC=EDU" )
Sep 13 11:31:32 slurpy slapd[2558]: ====> cache_find_entry_dn2id("OU=PEOPLE,DC=AERO,DC=UND,DC=EDU"): 4203 (1 tries)
Sep 13 11:31:32 slurpy slapd[2558]: <= dn2id 4203 (in cache)
Sep 13 11:31:32 slurpy slapd[2558]: => id2entry_r( 4203 )
Sep 13 11:31:32 slurpy slapd[2558]: ====> cache_find_entry_id( 4203 ) "ou=People, dc=aero,dc=und,dc=edu" (found) (1 tries)
Sep 13 11:31:32 slurpy slapd[2558]: <= id2entry_r( 4203 ) 0x46ead878 (cache)
Sep 13 11:31:32 slurpy slapd[2558]: search_candidates: base="OU=PEOPLE,DC=AERO,DC=UND,DC=EDU" s=2 d=0
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => list_candidates 0xa0
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => dn2idl( "@OU=PEOPLE,DC=AERO,DC=UND,DC=EDU" )
Sep 13 11:31:32 slurpy slapd[2558]: => ldbm_cache_open( "/var/lib/ldap/dn2id.dbb", 73, 600 )
Sep 13 11:31:32 slurpy slapd[2558]: <= ldbm_cache_open (cache 0)
Sep 13 11:31:32 slurpy slapd[2558]: <= idl_fetch 5849 ids (5849 max)
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 5849
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => list_candidates 0xa1
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => equality_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => ldbm_cache_open( "/var/lib/ldap/objectClass.dbb", 16, 600 )
Sep 13 11:31:32 slurpy slapd[2558]: <= ldbm_cache_open (cache 3)
Sep 13 11:31:32 slurpy slapd[2558]: => key_read
Sep 13 11:31:32 slurpy slapd[2558]: <= index_read 0 candidates
Sep 13 11:31:32 slurpy slapd[2558]: <= equality_candidates NULL
Sep 13 11:31:32 slurpy slapd[2558]: <= equality_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => list_candidates 0xa0
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => equality_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => ldbm_cache_open( "/var/lib/ldap/objectClass.dbb", 16, 600 )
Sep 13 11:31:32 slurpy slapd[2558]: <= ldbm_cache_open (cache 3)
Sep 13 11:31:32 slurpy slapd[2558]: => key_read
Sep 13 11:31:32 slurpy slapd[2558]: <= idl_fetch 5847 ids (5847 max)
Sep 13 11:31:32 slurpy slapd[2558]: <= index_read 5847 candidates
Sep 13 11:31:32 slurpy slapd[2558]: <= equality_candidates 5847
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 5847
Sep 13 11:31:32 slurpy slapd[2558]: => filter_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => equality_candidates
Sep 13 11:31:32 slurpy slapd[2558]: => ldbm_cache_open( "/var/lib/ldap/uid.dbb", 16, 600 )
Sep 13 11:31:32 slurpy slapd[2558]: <= ldbm_cache_open (cache 4)
Sep 13 11:31:32 slurpy slapd[2558]: => key_read
Sep 13 11:31:32 slurpy slapd[2558]: <= index_read 0 candidates
Sep 13 11:31:32 slurpy slapd[2558]: <= equality_candidates NULL
Sep 13 11:31:32 slurpy slapd[2558]: <= equality_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: <= list_candidates NULL
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: <= list_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: <= list_candidates NULL
Sep 13 11:31:32 slurpy slapd[2558]: <= filter_candidates 0
Sep 13 11:31:32 slurpy slapd[2558]: ====> cache_return_entry_r( 4203 ): returned (0)
Sep 13 11:31:32 slurpy slapd[2558]: ldbm_search: no candidates
Sep 13 11:31:32 slurpy slapd[2558]: send_ldap_search_result 0::
Sep 13 11:31:32 slurpy slapd[2558]: send_ldap_response: msgid=30 tag=101 err=0
Sep 13 11:31:32 slurpy slapd[2558]: conn=7021 op=29 SEARCH RESULT tag=101 err=0 text=
--SNAP

Now here is the last part of the log that I sent earlier.  Note the 1's 
compared to the 0's in the above log.  There was a crash at the end of 
this, "str2entry" returns a -1.

--SNIP
Sep 13 11:31:33 slurpy slapd[19001]: => key_read
Sep 13 11:31:33 slurpy slapd[19001]: <= index_read 1 candidates
Sep 13 11:31:33 slurpy slapd[19001]: <= equality_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= filter_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= list_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= filter_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= list_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= filter_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= list_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: <= filter_candidates 1
Sep 13 11:31:33 slurpy slapd[19001]: ====> cache_return_entry_r( 4203 ): returned (0)
Sep 13 11:31:33 slurpy slapd[19001]: => id2entry_r( 4798 )
Sep 13 11:31:33 slurpy slapd[19001]: => ldbm_cache_open( "/var/lib/ldap/id2entry.dbb", 73, 600 )
Sep 13 11:31:33 slurpy slapd[19001]: <= ldbm_cache_open (cache 1)
Sep 13 11:31:33 slurpy slapd[19001]: => str2entry
Sep 13 11:31:33 slurpy slapd[19001]: <= str2entry(cn=haibiao,ou=CompSci,ou=Students,ou=People,dc=aero,dc=und,dc=edu) -> -1 (0x85d8c70)

//* CRASH then autorestarted.

Sep 13 11:32:02 slurpy slapd[28790]: daemon: socket() failed errno=97 (Address family not supported by protocol)
Sep 13 11:32:02 slurpy slapd[28790]: daemon: socket() failed errno=97 (Address family not supported by protocol)
Sep 13 11:32:02 slurpy slapd[28793]: slapd startup: initiated.
Sep 13 11:32:02 slurpy slapd[28793]: slapd starting
--SNAP



Caylan Van Larson
Unix Administrator - Systems Team Member
University of North Dakota (Aerospace College)
caylan@cs.und.edu
701-777-6151 (work)
References:
- Re: Suggest slapd loglevel
  - From: Caylan Van Larson <caylan@cs.und.edu>
Prev by Date: Re: Suggest slapd loglevel [NEW LOGS AVAILABLE]
Next by Date: Re: sasl
Index(es):
- Chronological
- Thread