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

Debug from crashing openldap 2.0.7

To: openldap-software@OpenLDAP.org
Subject: Debug from crashing openldap 2.0.7
From: Mark Diggory <mdiggory@latte.harvard.edu>
Date: Thu, 22 Mar 2001 13:26:41 -0500
References: <20010322132431.A1250@cse.msu.edu>
User-agent: Mozilla/5.0 (X11; U; Linux 2.2.17-14 i686; en-US; 0.8) Gecko/20010218

Here is a copy of the debug output from an openldap server which is currently crashing when I try to LDAP bind (JNDI lookup) to a dn that crosses a referral using the latest JNDI and OpenLDAP 2.0.7.

ie vdcOp=xxx,vdcRole=yyy,ou=zzz,ou=local,o=vdc

where vdcRole is a referral to another entry.

Any advice available would be greatly appreciated.

-Mark Diggory

line 31 (working directory # service AND an understanding of referrals.) /usr/local/etc/openldap/slapd.conf: line 31: unknown directive "working" outside backend info and database definitions (ignored) line 34 (pidfile /usr/local/var/slapd.pid) line 35 (argsfile /usr/local/var/slapd.args) line 44 (schemacheck on) line 46 (allow tls_2_anon) line 48 (TLSCertificateFile /usr/local/etc/openldap/lorien-ldap.crt) line 49 (TLSCertificateKeyFile /usr/local/etc/openldap/lorien-ldap.key) line 55 (database ldbm) line 56 (suffix "o=vdc") line 57 (rootdn "cn=Manager, o=vdc") line 62 (rootpw **********) line 66 (directory /usr/local/var/openldap-ldbm) line 68 (index objectClass eq) index objectClass 0x0004 slapd startup: initiated. slapd starting daemon: added 6r daemon: select: listen=6 active_threads=0 tvp=NULL daemon: activity on 1 descriptors daemon: new connection on 9 ldap_pvt_gethostbyname_a: host=lorien, r=0 daemon: conn=0 fd=9 connection from IP=127.0.0.1:1079 (IP=0.0.0.0:389) accepted. daemon: added 9r daemon: activity on: daemon: select: listen=6 active_threads=0 tvp=NULL daemon: activity on 1 descriptors daemon: activity on: 9r daemon: read activity on 9 connection_get(9) connection_get(9): got connid=0 connection_read(9): checking for input on id=0 ber_get_next ldap_read: want=1, got=1 0000: 30 0 ldap_read: want=1, got=1 0000: 2a * ldap_read: want=42, got=42 0000: 02 01 01 60 25 02 01 03 04 18 6f 75 3d 6c 6f 72 ...`%.....ou=lor 0010: 69 65 6e 2c 6f 75 3d 6c 6f 63 61 6c 2c 6f 3d 76 ien,ou=local,o=v 0020: 64 63 80 06 73 65 63 72 65 74 dc..********** ber_get_next: tag 0x30 len 42 contents: ber_dump: buf=0x08176050 ptr=0x08176050 end=0x0817607a len=42 0000: 02 01 01 60 25 02 01 03 04 18 6f 75 3d 6c 6f 72 ...`%.....ou=lor 0010: 69 65 6e 2c 6f 75 3d 6c 6f 63 61 6c 2c 6f 3d 76 ien,ou=local,o=v 0020: 64 63 80 06 73 65 63 72 65 74 dc..********** ber_get_next ldap_read: want=1 error=Resource temporarily unavailable ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable) daemon: select: listen=6 active_threads=1 tvp=NULL do_bind ber_scanf fmt ({iat) ber: ber_dump: buf=0x08176050 ptr=0x08176053 end=0x0817607a len=39 0000: 60 25 02 01 03 04 18 6f 75 3d 6c 6f 72 69 65 6e `%.....ou=lorien 0010: 2c 6f 75 3d 6c 6f 63 61 6c 2c 6f 3d 76 64 63 80 ,ou=local,o=vdc. 0020: 06 73 65 63 72 65 74 .********** ber_scanf fmt (o}) ber: ber_dump: buf=0x08176050 ptr=0x08176072 end=0x0817607a len=8 0000: 80 06 73 65 63 72 65 74 ..********** do_bind: version=3 dn="ou=lorien,ou=local,o=vdc" method=128 conn=0 op=0 BIND dn="OU=LORIEN,OU=LOCAL,O=VDC" method=128 ==> ldbm_back_bind: dn: ou=lorien,ou=local,o=vdc dn2entry_r: dn: "OU=LORIEN,OU=LOCAL,O=VDC" => dn2id( "OU=LORIEN,OU=LOCAL,O=VDC" ) => ldbm_cache_open( "/usr/local/var/openldap-ldbm/dn2id.dbb", 7, 600 ) ldbm_cache_open (blksize 4096) (maxids 1022) (maxindirect 9) <= ldbm_cache_open (opened 0) <= dn2id 548 => id2entry_r( 548 ) => ldbm_cache_open( "/usr/local/var/openldap-ldbm/id2entry.dbb", 7, 600 ) ldbm_cache_open (blksize 4096) (maxids 1022) (maxindirect 9) <= ldbm_cache_open (opened 1) => str2entry <= str2entry(ou=lorien,ou=local,o=vdc) -> -1 (0x8176e88) entry_rdwr_rlock: ID: 548 <= id2entry_r( 548 ) 0x8176e88 (disk) => access_allowed: auth access to "ou=lorien,ou=local,o=vdc" "userPassword" requested => acl_get: [1] check attr userPassword <= acl_get: [1] acl ou=lorien,ou=local,o=vdc attr: userPassword => acl_mask: access to entry "ou=lorien,ou=local,o=vdc", attr "userPassword" requested => acl_mask: to all values by "", (=n) <= check a_dn_pat: * <= acl_mask: [1] applying write (=wrscx) (stop) <= acl_mask: [1] mask: write (=wrscx) => access_allowed: auth access granted by write (=wrscx) entry_rdwr_runlock: ID: 548 ====> cache_return_entry_r( 548 ): created (0) do_bind: v3 bind: "ou=lorien,ou=local,o=vdc" to "ou=lorien,ou=local,o=vdc" send_ldap_result: conn=0 op=0 p=3 send_ldap_result: 0:: send_ldap_response: msgid=1 tag=97 err=0 ber_flush: 14 bytes to sd 9 0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........ ldap_write: want=14, written=14 0000: 30 0c 02 01 01 61 07 0a 01 00 04 00 04 00 0....a........ conn=0 op=0 RESULT tag=97 err=0 text= daemon: activity on 1 descriptors daemon: activity on: 9r daemon: read activity on 9 connection_get(9) connection_get(9): got connid=0 connection_read(9): checking for input on id=0 ber_get_next ldap_read: want=1, got=1 0000: 30 0 ldap_read: want=1, got=1 0000: 66 f ldap_read: want=102, got=102 0000: 02 01 02 63 61 04 41 76 64 63 4f 70 65 72 61 74 ...ca.AvdcOperat 0010: 69 6f 6e 3d 52 41 50 5f 41 43 43 45 53 53 2c 76 ion=RAP_ACCESS,v 0020: 64 63 52 6f 6c 65 3d 72 65 73 65 61 72 63 68 65 dcRole=researche 0030: 72 2c 6f 75 3d 68 6d 64 63 2c 6f 75 3d 6c 6f 63 r,ou=hmdc,ou=loc 0040: 61 6c 2c 6f 3d 76 64 63 0a 01 00 0a 01 03 02 01 al,o=vdc........ 0050: 00 02 01 00 01 01 00 87 0b 6f 62 6a 65 63 74 43 .........objectC 0060: 6c 61 73 73 30 00 lass0. ber_get_next: tag 0x30 len 102 contents: ber_dump: buf=0x08176de0 ptr=0x08176de0 end=0x08176e46 len=102 0000: 02 01 02 63 61 04 41 76 64 63 4f 70 65 72 61 74 ...ca.AvdcOperat 0010: 69 6f 6e 3d 52 41 50 5f 41 43 43 45 53 53 2c 76 ion=RAP_ACCESS,v 0020: 64 63 52 6f 6c 65 3d 72 65 73 65 61 72 63 68 65 dcRole=researche 0030: 72 2c 6f 75 3d 68 6d 64 63 2c 6f 75 3d 6c 6f 63 r,ou=hmdc,ou=loc 0040: 61 6c 2c 6f 3d 76 64 63 0a 01 00 0a 01 03 02 01 al,o=vdc........ 0050: 00 02 01 00 01 01 00 87 0b 6f 62 6a 65 63 74 43 .........objectC 0060: 6c 61 73 73 30 00 lass0. ber_get_next ldap_read: want=1 error=Resource temporarily unavailable ber_get_next on fd 9 failed errno=11 (Resource temporarily unavailable) daemon: select: listen=6 active_threads=1 tvp=NULL do_search ber_scanf fmt ({aiiiib) ber: ber_dump: buf=0x08176de0 ptr=0x08176de3 end=0x08176e46 len=99 0000: 63 61 04 41 76 64 63 4f 70 65 72 61 74 69 6f 6e ca.AvdcOperation 0010: 3d 52 41 50 5f 41 43 43 45 53 53 2c 76 64 63 52 =RAP_ACCESS,vdcR 0020: 6f 6c 65 3d 72 65 73 65 61 72 63 68 65 72 2c 6f ole=researcher,o 0030: 75 3d 68 6d 64 63 2c 6f 75 3d 6c 6f 63 61 6c 2c u=hmdc,ou=local, 0040: 6f 3d 76 64 63 0a 01 00 0a 01 03 02 01 00 02 01 o=vdc........... 0050: 00 01 01 00 87 0b 6f 62 6a 65 63 74 43 6c 61 73 ......objectClas 0060: 73 30 00 s0. SRCH "vdcOperation=RAP_ACCESS,vdcRole=researcher,ou=hmdc,ou=local,o=vdc" 0 3 0 0 0 begin get_filter PRESENT ber_scanf fmt (o) ber: ber_dump: buf=0x08176de0 ptr=0x08176e37 end=0x08176e46 len=15 0000: 87 0b 6f 62 6a 65 63 74 43 6c 61 73 73 30 00 ..objectClass0. end get_filter 0 filter: (objectClass=*) ber_scanf fmt ({v}}) ber: ber_dump: buf=0x08176de0 ptr=0x08176e44 end=0x08176e46 len=2 0000: 30 00 0. attrs: conn=0 op=1 SRCH base="vdcOperation=RAP_ACCESS,vdcRole=researcher,ou=hmdc,ou=local,o=vdc" scope=0 filter="(objectClass=*)" => ldbm_back_search dn2entry_r: dn: "VDCOPERATION=RAP_ACCESS,VDCROLE=RESEARCHER,OU=HMDC,OU=LOCAL,O=VDC" => dn2id( "VDCOPERATION=RAP_ACCESS,VDCROLE=RESEARCHER,OU=HMDC,OU=LOCAL,O=VDC" ) => ldbm_cache_open( "/usr/local/var/openldap-ldbm/dn2id.dbb", 7, 600 ) <= ldbm_cache_open (cache 0) <= dn2id NOID dn2entry_r: dn: "VDCROLE=RESEARCHER,OU=HMDC,OU=LOCAL,O=VDC" => dn2id( "VDCROLE=RESEARCHER,OU=HMDC,OU=LOCAL,O=VDC" ) => ldbm_cache_open( "/usr/local/var/openldap-ldbm/dn2id.dbb", 7, 600 ) <= ldbm_cache_open (cache 0) <= dn2id 570 => id2entry_r( 570 ) => ldbm_cache_open( "/usr/local/var/openldap-ldbm/id2entry.dbb", 7, 600 ) <= ldbm_cache_open (cache 1) => str2entry <= str2entry(vdcRole=researcher,ou=hmdc,ou=local,o=vdc) -> -1 (0x8177578) entry_rdwr_rlock: ID: 570 <= id2entry_r( 570 ) 0x8177578 (disk) entry_rdwr_runlock: ID: 570 ====> cache_return_entry_r( 570 ): created (0) send_ldap_result: conn=0 op=1 p=3 send_ldap_result: 32:vdcRole=researcher,ou=hmdc,ou=local,o=vdc: send_ldap_result: referral: ldap://localhost/vdcRole=scientist,ou=lorien,ou=local,o=vdc send_ldap_response: msgid=2 tag=101 err=32 send_ldap_response: ref=ldap://localhost/vdcRole=scientist,ou=lorien,ou=local,o=vdc slapd: result.c:285: send_ldap_response: Assertion `err == 0x0a' failed. Abort [mdiggory@lorien libexec]$

References:
- OpenLDAP 2.0.7 and Kerberos 4?
  - From: Franciszka Bakowska <bakowska@cse.msu.edu>

Prev by Date: OpenLDAP 2.0.7 and Kerberos 4?
Next by Date: Re: OpenLDAP 2.0.7 and Kerberos 4?
Index(es):
- Chronological
- Thread