Sep  4 12:52:02 lenny32-tpl slapd[4079]: connection_closing: readying conn=9 sd=14 for close
Sep  4 12:52:02 lenny32-tpl slapd[4079]: connection_resched: attempting closing conn=9 sd=14
Sep  4 12:52:02 lenny32-tpl slapd[4079]: connection_close: conn=9 sd=14
Sep  4 12:52:02 lenny32-tpl slapd[4079]: daemon: removing 14
Sep  4 12:52:02 lenny32-tpl slapd[4079]: conn=9 fd=14 closed
Sep  4 12:52:02 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:52:02 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:52:02 lenny32-tpl slapd[4079]: 
Sep  4 12:52:02 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:52:02 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:53:11 lenny32-tpl slapd[4079]: 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: slap_listener_activate(9): 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 busy
Sep  4 12:53:11 lenny32-tpl slapd[4079]: >>> slap_listener(ldap:///)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: listen=9, new connection on 14
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: added 14r (active) listener=(nil)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: conn=10 fd=14 ACCEPT from IP=127.0.0.1:51486 (IP=0.0.0.0:389)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:53:11 lenny32-tpl slapd[4079]: 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:53:11 lenny32-tpl slapd[4079]:  14r
Sep  4 12:53:11 lenny32-tpl slapd[4079]: 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: read active on 14
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_get(14)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_get(14): got connid=10
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_read(14): checking for input on id=10
Sep  4 12:53:11 lenny32-tpl slapd[4079]: conn=10 op=0 do_bind
Sep  4 12:53:11 lenny32-tpl slapd[4079]: >>> dnPrettyNormal: <uid=chantra,ou=users,dc=example,dc=com>
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <<< dnPrettyNormal: <uid=chantra,ou=users,dc=example,dc=com>, <uid=chantra,ou=users,dc=example,dc=com>
Sep  4 12:53:11 lenny32-tpl slapd[4079]: conn=10 op=0 BIND dn="uid=chantra,ou=users,dc=example,dc=com" method=128
Sep  4 12:53:11 lenny32-tpl slapd[4079]: do_bind: version=3 dn="uid=chantra,ou=users,dc=example,dc=com" method=128
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: ndn: "uid=chantra,ou=users,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: oc: "(null)", at: "(null)"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("uid=chantra,ou=users,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: found entry: "uid=chantra,ou=users,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_entry_get: rc=0
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: ndn: "cn=default,ou=policies,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: oc: "(null)", at: "(null)"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("cn=default,ou=policies,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: found entry: "cn=default,ou=policies,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_entry_get: rc=0
Sep  4 12:53:11 lenny32-tpl slapd[4079]: ==> hdb_bind: dn: uid=chantra,ou=users,dc=example,dc=com
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("uid=chantra,ou=users,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => access_allowed: auth access to "uid=chantra,ou=users,dc=example,dc=com" "userPassword" requested
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => acl_get: [1] attr userPassword
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => slap_access_allowed: result not in cache (userPassword)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => acl_mask: access to entry "uid=chantra,ou=users,dc=example,dc=com", attr "userPassword" requested
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => acl_mask: to value by "", (=0) 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= check a_dn_pat: cn=admin,dc=example,dc=com
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= check a_dn_pat: anonymous
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= acl_mask: [2] applying auth(=xd) (stop)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= acl_mask: [2] mask: auth(=xd)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => slap_access_allowed: auth access granted by auth(=xd)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => access_allowed: auth access granted by auth(=xd)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: send_ldap_result: conn=10 op=0 p=3
Sep  4 12:53:11 lenny32-tpl slapd[4079]: send_ldap_result: err=49 matched="" text=""
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: ndn: "uid=chantra,ou=users,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: oc: "(null)", at: "(null)"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("uid=chantra,ou=users,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: found entry: "uid=chantra,ou=users,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_entry_get: rc=0
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: ndn: "uid=chantra,ou=users,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: oc: "(null)", at: "(null)"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("uid=chantra,ou=users,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: found entry: "uid=chantra,ou=users,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_entry_get: rc=0
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: ndn: "cn=default,ou=policies,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: oc: "(null)", at: "(null)"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("cn=default,ou=policies,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => bdb_entry_get: found entry: "cn=default,ou=policies,dc=example,dc=com"
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_entry_get: rc=0
Sep  4 12:53:11 lenny32-tpl slapd[4079]: hdb_modify: uid=chantra,ou=users,dc=example,dc=com
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_dn2entry("uid=chantra,ou=users,dc=example,dc=com")
Sep  4 12:53:11 lenny32-tpl slapd[4079]: bdb_modify_internal: 0x00000004: uid=chantra,ou=users,dc=example,dc=com
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => access_allowed: delete access to "uid=chantra,ou=users,dc=example,dc=com" "pwdAccountLockedTime" requested
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => dn: [2] 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => acl_get: [3] attr pwdAccountLockedTime
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => slap_access_allowed: result not in cache (pwdAccountLockedTime)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => acl_mask: access to entry "uid=chantra,ou=users,dc=example,dc=com", attr "pwdAccountLockedTime" requested
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => acl_mask: to all values by "", (=0) 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= check a_dn_pat: cn=admin,dc=example,dc=com
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= check a_dn_pat: *
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= acl_mask: [2] applying read(=rscxd) (stop)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: <= acl_mask: [2] mask: read(=rscxd)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => slap_access_allowed: delete access denied by read(=rscxd)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: => access_allowed: no more rules
Sep  4 12:53:11 lenny32-tpl slapd[4079]: hdb_modify: modify failed (50)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: send_ldap_result: conn=10 op=0 p=3
Sep  4 12:53:11 lenny32-tpl slapd[4079]: send_ldap_result: err=50 matched="" text=""
Sep  4 12:53:11 lenny32-tpl slapd[4079]: send_ldap_response: msgid=1 tag=97 err=49
Sep  4 12:53:11 lenny32-tpl slapd[4079]: conn=10 op=0 RESULT tag=97 err=49 text=
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:53:11 lenny32-tpl slapd[4079]: 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:53:11 lenny32-tpl slapd[4079]:  14r
Sep  4 12:53:11 lenny32-tpl slapd[4079]: 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: read active on 14
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_get(14)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_get(14): got connid=10
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_read(14): checking for input on id=10
Sep  4 12:53:11 lenny32-tpl slapd[4079]: ber_get_next on fd 14 failed errno=0 (Success)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_read(14): input error=-2 id=10, closing.
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_closing: readying conn=10 sd=14 for close
Sep  4 12:53:11 lenny32-tpl slapd[4079]: connection_close: conn=10 sd=14
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: removing 14
Sep  4 12:53:11 lenny32-tpl slapd[4079]: conn=10 fd=14 closed (connection lost)
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on 1 descriptor
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: activity on:
Sep  4 12:53:11 lenny32-tpl slapd[4079]: 
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=8 active_threads=0 tvp=zero
Sep  4 12:53:11 lenny32-tpl slapd[4079]: daemon: epoll: listen=9 active_threads=0 tvp=zero