[Date Prev][Date Next] [Chronological] [Thread] [Top]
Bizzar Behaviour from openldap-2.4.16 on Centos

To: openldap-technical@openldap.org
Subject: Bizzar Behaviour from openldap-2.4.16 on Centos
From: sysadmin@shakenbake.net
Date: Fri, 4 Sep 2009 23:57:30 +1000
Content-disposition: inline
User-agent: Mutt/1.5.19 (2009-01-05)
Hi,

Forgive me if this is an obvious one, as i'm new to openldap.
I have setup Openldap 2.4.16 from source with db-4.7.25 on Centos 5.3 x64, running on a VMWARE-ESXI Server.

#Installed with following options
export CPPFLAGS="-I/usr/local/db-4.7.25/include"
export LDFLAGS="-L/usr/local/db-4.7.25/lib -R/usr/local/db-4.7.25/lib"
export LD_LIBRARY_PATH="/data/extracted/db-4.7.25/build_unix/.libs/"
./configure --prefix=/usr/local/openldap-2.4.16
make depend
make
make install
###
cd db-4.7.25/build_unix
../dist/configure --prefix=/usr/local/db-4.7.25
make
make install

###
#
The behaviour is very odd, the process appears to start up but doesn't show up in netstat properly and basic ldapadd commands stall after the first entry.Firewall & selinux is turned off. I can't seem to see too many errors?

ldapadd -v -W -x -D "cn=admin,dc=labs,dc=example,dc=com,dc=au" -W -f <base ldiff file here>
ldap_initialize( <DEFAULT> )
Enter LDAP Password: 
add dc:
    labs
add o:
    example
add objectClass:
    top
    domain
adding new entry "dc=labs,dc=example,dc=com,dc=au"

########## QUERY PAUSES HERE ######

####
#tail from syslog with loglevel -1
###
Sep  4 23:50:25 safevm-ldap slapd[2684]: slap_listener_activate(8):  
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=8 busy 
Sep  4 23:50:25 safevm-ldap slapd[2684]: >>> slap_listener(ldap:///) 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: listen=8, new connection on 14 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: added 14r (active) listener=(nil) 
Sep  4 23:50:25 safevm-ldap slapd[2684]: conn=3 fd=14 ACCEPT from IP=127.0.0.1:48114 (IP=0.0.0.0:389) 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:50:25 safevm-ldap slapd[2684]:  
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:50:25 safevm-ldap slapd[2684]:  14r
Sep  4 23:50:25 safevm-ldap slapd[2684]:  
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: read active on 14 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: connection_get(14) 
Sep  4 23:50:25 safevm-ldap slapd[2684]: connection_get(14): got connid=3 
Sep  4 23:50:25 safevm-ldap slapd[2684]: connection_read(14): checking for input on id=3 
Sep  4 23:50:25 safevm-ldap slapd[2684]: conn=3 op=0 do_bind 
Sep  4 23:50:25 safevm-ldap slapd[2684]: >>> dnPrettyNormal: <cn=admin,dc=labs,dc=example,dc=com,dc=au> 
Sep  4 23:50:25 safevm-ldap slapd[2684]: <<< dnPrettyNormal: <cn=admin,dc=labs,dc=example,dc=com,dc=au>, <cn=admin,dc=labs,dc=example,dc=com,dc=au> 
Sep  4 23:50:25 safevm-ldap slapd[2684]: conn=3 op=0 BIND dn="cn=admin,dc=labs,dc=example,dc=com,dc=au" method=128 
Sep  4 23:50:25 safevm-ldap slapd[2684]: do_bind: version=3 dn="cn=admin,dc=labs,dc=example,dc=com,dc=au" method=128 
Sep  4 23:50:25 safevm-ldap slapd[2684]: ==> bdb_bind: dn: cn=admin,dc=labs,dc=example,dc=com,dc=au 
Sep  4 23:50:25 safevm-ldap slapd[2684]: conn=3 op=0 BIND dn="cn=admin,dc=labs,dc=example,dc=com,dc=au" mech=SIMPLE ssf=0 
Sep  4 23:50:25 safevm-ldap slapd[2684]: do_bind: v3 bind: "cn=admin,dc=labs,dc=example,dc=com,dc=au" to "cn=admin,dc=labs,dc=example,dc=com,dc=au" 
Sep  4 23:50:25 safevm-ldap slapd[2684]: send_ldap_result: conn=3 op=0 p=3 
Sep  4 23:50:25 safevm-ldap slapd[2684]: send_ldap_result: err=0 matched="" text="" 
Sep  4 23:50:25 safevm-ldap slapd[2684]: send_ldap_response: msgid=1 tag=97 err=0 
Sep  4 23:50:25 safevm-ldap slapd[2684]: conn=3 op=0 RESULT tag=97 err=0 text= 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:50:25 safevm-ldap slapd[2684]:  
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:50:25 safevm-ldap slapd[2684]:  14r
Sep  4 23:50:25 safevm-ldap slapd[2684]:  
Sep  4 23:50:25 safevm-ldap slapd[2684]: daemon: read active on 14 
Sep  4 23:50:26 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:50:26 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:50:26 safevm-ldap slapd[2684]: connection_get(14) 
Sep  4 23:50:26 safevm-ldap slapd[2684]: connection_get(14): got connid=3 
Sep  4 23:50:26 safevm-ldap slapd[2684]: connection_read(14): checking for input on id=3 
Sep  4 23:50:26 safevm-ldap slapd[2684]: conn=3 op=1 do_add 
Sep  4 23:50:26 safevm-ldap slapd[2684]: conn=3 op=1 do_add: dn (dc=labs,dc=example,dc=com,dc=au) 
Sep  4 23:50:26 safevm-ldap slapd[2684]: >>> dnPrettyNormal: <dc=labs,dc=example,dc=com,dc=au> 
Sep  4 23:50:26 safevm-ldap slapd[2684]: <<< dnPrettyNormal: <dc=labs,dc=example,dc=com,dc=au>, <dc=labs,dc=example,dc=com,dc=au> 
Sep  4 23:50:26 safevm-ldap slapd[2684]: conn=3 op=1 ADD dn="dc=labs,dc=example,dc=com,dc=au" 
Sep  4 23:50:26 safevm-ldap slapd[2684]: bdb_dn2entry("dc=labs,dc=example,dc=com,dc=au") 
Sep  4 23:50:26 safevm-ldap slapd[2684]: => bdb_dn2id("dc=labs,dc=example,dc=com,dc=au") 
Sep  4 23:50:26 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:50:26 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:50:26 safevm-ldap slapd[2684]:  
Sep  4 23:50:26 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:50:26 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:51:21 safevm-ldap slapd[2684]:  14r
Sep  4 23:51:21 safevm-ldap slapd[2684]:  
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: read active on 14 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:51:21 safevm-ldap slapd[2684]: connection_get(14) 
Sep  4 23:51:21 safevm-ldap slapd[2684]: connection_get(14): got connid=3 
Sep  4 23:51:21 safevm-ldap slapd[2684]: connection_read(14): checking for input on id=3 
Sep  4 23:51:21 safevm-ldap slapd[2684]: ber_get_next on fd 14 failed errno=0 (Success) 
Sep  4 23:51:21 safevm-ldap slapd[2684]: connection_read(14): input error=-2 id=3, closing. 
Sep  4 23:51:21 safevm-ldap slapd[2684]: connection_closing: readying conn=3 sd=14 for close 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: activity on 1 descriptor 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: activity on:
Sep  4 23:51:21 safevm-ldap slapd[2684]:  
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: epoll: listen=7 active_threads=0 tvp=NULL 
Sep  4 23:51:21 safevm-ldap slapd[2684]: daemon: epoll: listen=8 active_threads=0 tvp=NULL 
Sep  4 23:51:21 safevm-ldap slapd[2684]: connection_close: deferring conn=3 sd=14 


cya

Craig
Follow-Ups:
- Re: Bizzar Behaviour from openldap-2.4.16 on Centos
  - From: Quanah Gibson-Mount <quanah@zimbra.com>
Prev by Date: ppolicy and ACLs of pwdAccountLockedTime?
Next by Date: perl module to read ldif
Index(es):
- Chronological
- Thread