[Date Prev][Date Next] [Chronological] [Thread] [Top]

Re: Slapd sometimes crashes after starting the same search action three times





--On Tuesday, April 05, 2005 4:23 PM +0000 default default <gerritvandenhul@lycos.nl> wrote:


Hi all!

Since you can reproduce this with OpenLDAP 2.2.24, it likely should be filed as an ITS at:


<http://www.openldap.org/its/>

--Quanah


I have a problem with RedHat 4.0 and openldap 2.2.13.

Slapd sometimes crashes after starting the same search action three
times, at the same time.
Slapd is started just before these search actions.

When I remove the regex line from the access control list, I can't
reproduce the error.
I can't reproduce the error on a single processor system either (one XEON
processor; hypertreading disabled).

I have reproduced the error also with RedHat 3.0 and with openldap 2.2.24.

Perhaps this is a problem with acl parsing on a multi-processor system?

Greetings,

Gerrit van den Hul


System: HP Proliant with two XEON processsors (four processors for O.S.) O.S.: RedHat 4.0 ES

(Stripped) openldap configuration:

loglevel           5
sizelimit           50000
timelimit           3600
sockbuf_max_incoming_auth      8000000
idletimeout         0
schemacheck         on
defaultsearchbase   "o=org,c=nl"
allow               bind_v2

database      bdb
checkpoint    250 15
suffix        "o=org,c=NL"
directory     /usr/local/openldap/openldapDB
index          objectClass      eq
index          cn               eq,sub

access to dn.regex="orgcode=(.*),ou=lb,o=org,c=nl" attr="data"
        by dn.regex="cn=updater,orgcode=$1,ou=lb,o=org,c=nl" write

access to *
        by self write
        by * read


DB configuration:

set_cachesize   0       500000000       0


Logfile:

Apr  5 13:27:18 localhost slapd[3038]: bdb_db_open:
dbenv_open(/usr/local/openldap/openldapDB)
Apr  5 13:27:18 localhost slapd[3038]: slapd starting
Apr  5 13:27:18 localhost slapd[3038]: connection_get(10)
Apr  5 13:27:18 localhost slapd[3038]: connection_get(10): got connid=0
Apr  5 13:27:18 localhost slapd[3038]: connection_read(10): checking for
input on id=0
Apr  5 13:27:18 localhost slapd[3038]: ber_get_next on fd 10 failed
errno=11 (Resource temporarily unavailable)
Apr  5 13:27:18 localhost slapd[3038]: do_bind
Apr  5 13:27:18 localhost slapd[3038]: >>> dnPrettyNormal: <>
Apr  5 13:27:18 localhost slapd[3038]: <<< dnPrettyNormal: <>, <>
Apr  5 13:27:18 localhost slapd[3038]: do_bind: version=2 dn="" method=128
Apr  5 13:27:18 localhost slapd[3038]: send_ldap_result: conn=0 op=0 p=2
Apr  5 13:27:18 localhost slapd[3038]: send_ldap_result: err=0 matched=""
text=""
Apr  5 13:27:18 localhost slapd[3038]: send_ldap_response: msgid=1 tag=97
err=0
Apr  5 13:27:18 localhost slapd[3038]: do_bind: v2 anonymous bind
Apr  5 13:27:18 localhost slapd[3038]: connection_get(11)
Apr  5 13:27:18 localhost slapd[3038]: connection_get(11): got connid=1
Apr  5 13:27:18 localhost slapd[3038]: connection_read(11): checking for
input on id=1
Apr  5 13:27:18 localhost slapd[3038]: ber_get_next on fd 11 failed
errno=11 (Resource temporarily unavailable)
Apr  5 13:27:18 localhost slapd[3038]: do_bind
Apr  5 13:27:18 localhost slapd[3038]: >>> dnPrettyNormal: <>
Apr  5 13:27:18 localhost slapd[3038]: <<< dnPrettyNormal: <>, <>
Apr  5 13:27:18 localhost slapd[3038]: do_bind: version=2 dn="" method=128
Apr  5 13:27:18 localhost slapd[3038]: send_ldap_result: conn=1 op=0 p=2
Apr  5 13:27:18 localhost slapd[3038]: send_ldap_result: err=0 matched=""
text=""
Apr  5 13:27:18 localhost slapd[3038]: send_ldap_response: msgid=1 tag=97
err=0
Apr  5 13:27:18 localhost slapd[3038]: connection_get(10)
Apr  5 13:27:18 localhost slapd[3038]: connection_get(10): got connid=0
Apr  5 13:27:18 localhost slapd[3038]: connection_read(10): checking for
input on id=0
Apr  5 13:27:18 localhost slapd[3038]: do_bind: v2 anonymous bind
Apr  5 13:27:18 localhost slapd[3038]: ber_get_next on fd 10 failed
errno=11 (Resource temporarily unavailable)
Apr  5 13:27:18 localhost slapd[3038]: do_search
Apr  5 13:27:18 localhost slapd[3038]: >>> dnPrettyNormal:
<ou=LB,o=org,c=NL>
Apr  5 13:27:18 localhost slapd[3038]: <<< dnPrettyNormal:
<ou=LB,o=org,c=NL>, <ou=lb,o=org,c=nl>
Apr  5 13:27:18 localhost slapd[3038]: SRCH "ou=LB,o=org,c=NL" 1 0
Apr  5 13:27:18 localhost slapd[3038]:     0 1000 0
Apr  5 13:27:18 localhost slapd[3038]:     filter:
(&(&(activeEntry=0)(objectClass=organizationalUnit)(orgCode=1488))(!(modi
fyTimestamp<=20050225064438Z)))
Apr  5 13:27:18 localhost slapd[3038]:     attrs:
Apr  5 13:27:18 localhost slapd[3038]:  orgID
Apr  5 13:27:18 localhost slapd[3038]:
Apr  5 13:27:18 localhost slapd[3038]: ==> limits_get: conn=0 op=1
dn="[anonymous]"
Apr  5 13:27:18 localhost slapd[3038]: => bdb_search
Apr  5 13:27:18 localhost slapd[3038]: bdb_dn2entry("ou=lb,o=org,c=nl")
Apr  5 13:27:18 localhost slapd[3038]: => bdb_dn2id( "o=org,c=nl" )
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_dn2id: got id=0x00000001
Apr  5 13:27:18 localhost slapd[3038]: => bdb_dn2id( "ou=lb,o=org,c=nl" )
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_dn2id: got id=0x00000002
Apr  5 13:27:18 localhost slapd[3038]: entry_decode: "ou=LB,o=org,c=NL"
Apr  5 13:27:18 localhost slapd[3038]: <= entry_decode(ou=LB,o=org,c=NL)
Apr  5 13:27:18 localhost slapd[3038]: search_candidates:
base="ou=lb,o=org,c=nl" (0x00000002) scope=1
Apr  5 13:27:18 localhost slapd[3038]: => bdb_dn2idl( "ou=lb,o=org,c=nl" )
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key:
%ou=lb,o=org,c=nl
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_dn2idl: id=369 first=3
last=245732
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(objectClass)
Apr  5 13:27:18 localhost slapd[3038]: => key_read
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key: [b49d1940]
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_index_read: failed (-30990)
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates: id=0,
first=0, last=0
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(activeEntry)
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates:
(activeEntry) index_param failed (18)
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(objectClass)
Apr  5 13:27:18 localhost slapd[3038]: => key_read
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key: [9bee355f]
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_index_read 1278 candidates
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates:
id=1278, first=2, last=286888
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(orgCode)
Apr  5 13:27:18 localhost slapd[3038]: => key_read
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key: [e9046241]
Apr  5 13:27:18 localhost slapd[3038]: connection_get(11)
Apr  5 13:27:18 localhost slapd[3038]: connection_get(11): got connid=1
Apr  5 13:27:18 localhost slapd[3038]: connection_read(11): checking for
input on id=1
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_index_read 1 candidates
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates: id=1,
first=635, last=635
Apr  5 13:27:18 localhost slapd[3038]: do_search
Apr  5 13:27:18 localhost slapd[3038]: >>> dnPrettyNormal:
<ou=LB,o=org,c=NL>
Apr  5 13:27:18 localhost slapd[3038]: <<< dnPrettyNormal:
<ou=LB,o=org,c=NL>, <ou=lb,o=org,c=nl>
Apr  5 13:27:18 localhost slapd[3038]: SRCH "ou=LB,o=org,c=NL" 1 0
Apr  5 13:27:18 localhost slapd[3038]:     0 1000 0
Apr  5 13:27:18 localhost slapd[3038]: ber_get_next on fd 11 failed
errno=11 (Resource temporarily unavailable)
Apr  5 13:27:18 localhost slapd[3038]: bdb_search_candidates: id=1
first=635 last=635
Apr  5 13:27:18 localhost slapd[3038]:     filter:
(&(&(activeEntry=0)(objectClass=organizationalUnit)(orgCode=1488))(!(modi
fyTimestamp<=20050225064438Z)))
Apr  5 13:27:18 localhost slapd[3038]:     attrs:
Apr  5 13:27:18 localhost slapd[3038]:  orgID
Apr  5 13:27:18 localhost slapd[3038]:
Apr  5 13:27:18 localhost slapd[3038]: ==> limits_get: conn=1 op=1
dn="[anonymous]"
Apr  5 13:27:18 localhost slapd[3038]: => bdb_search
Apr  5 13:27:18 localhost slapd[3038]: bdb_dn2entry("ou=lb,o=org,c=nl")
Apr  5 13:27:18 localhost slapd[3038]: search_candidates:
base="ou=lb,o=org,c=nl" (0x00000002) scope=1
Apr  5 13:27:18 localhost slapd[3038]: => bdb_dn2idl( "ou=lb,o=org,c=nl" )
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key:
%ou=lb,o=org,c=nl
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_dn2idl: id=369 first=3
last=245732
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(objectClass)
Apr  5 13:27:18 localhost slapd[3038]: => key_read
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key: [b49d1940]
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_index_read: failed (-30990)
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates: id=0,
first=0, last=0
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(activeEntry)
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates:
(activeEntry) index_param failed (18)
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(objectClass)
Apr  5 13:27:18 localhost slapd[3038]: => key_read
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key: [9bee355f]
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_index_read 1278 candidates
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates:
id=1278, first=2, last=286888
Apr  5 13:27:18 localhost slapd[3038]: => bdb_equality_candidates
(orgCode)
Apr  5 13:27:18 localhost slapd[3038]: => key_read
Apr  5 13:27:18 localhost slapd[3038]: bdb_idl_fetch_key: [e9046241]
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_index_read 1 candidates
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_equality_candidates: id=1,
first=635, last=635
Apr  5 13:27:18 localhost slapd[3038]: bdb_search_candidates: id=1
first=635 last=635
Apr  5 13:27:18 localhost slapd[3038]: entry_decode:
"orgCode=1488,ou=LB,o=org,c=NL"
Apr  5 13:27:18 localhost slapd[3038]: entry_decode:
"orgCode=1488,ou=LB,o=org,c=NL"
Apr  5 13:27:18 localhost slapd[3038]: <=
entry_decode(orgCode=1488,ou=LB,o=org,c=NL)
Apr  5 13:27:18 localhost slapd[3038]: <=
entry_decode(orgCode=1488,ou=LB,o=org,c=NL)
Apr  5 13:27:18 localhost slapd[3038]: => bdb_dn2id(
"orgCode=1488,ou=lb,o=org,c=nl" )
Apr  5 13:27:18 localhost slapd[3038]: => bdb_dn2id(
"orgCode=1488,ou=lb,o=org,c=nl" )
Apr  5 13:27:18 localhost slapd[3038]: <= bdb_dn2id: got id=0x0000027b


Stack trace:

# 0  0x007a719e in build_trtable () from /lib/tls/libc.so.6
# 1  0x007ae234 in re_search_internal () from /lib/tls/libc.so.6
# 2  0x007af912 in regexec@@GLIBC_2.3.4 () from /lib/tls/libc.so.6
# 3  0x00aa3d19 in access_allowed () from /usr/sbin/slapd
# 4  0x00aa16f2 in do_abandon () from /usr/sbin/slapd
# 5  0x00aa1f67 in test_filter () from /usr/sbin/slapd
# 6  0x00aa1c78 in test_filter () from /usr/sbin/slapd
# 7  0x00aa1c78 in test_filter () from /usr/sbin/slapd
# 8  0x00addc0f in bdb_do_search () from /usr/sbin/slapd
# 9  0x097d9b28 in ?? ()
# 10 0x921fe2b4 in ?? ()
# 11 0x9263de50 in ?? ()
# 12 0x00000001 in ?? ()
# 13 0x00000024 in ?? ()
# 14 0x9263df10 in ?? ()
# 15 0x00000000 in ?? ()


Gratis je email via Outlook lezen? Download nu Lycos Inside van Lycos Mail!



-- Quanah Gibson-Mount Principal Software Developer ITSS/Shared Services Stanford University GnuPG Public Key: http://www.stanford.edu/~quanah/pgp.html

"These censorship operations against schools and libraries are stronger
than ever in the present religio-political climate. They often focus on
fantasy and sf books, which foster that deadly enemy to bigotry and blind
faith, the imagination." -- Ursula K. Le Guin