**************************************************************************************************************** a "normal" log of a LDAP Auth: [Jun 14 10:25:05.755882 2014] Debug: Received: :971AAAAAO PRIVMSG 00AAAAAAG :id testpass [Jun 14 10:25:05.757158 2014] Debug: *** ldap_sasl_bind(con, , LDAP_SASL_SIMPLE, , NULL, NULL, 32767); [Jun 14 10:25:05.757352 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:25:05.758080 2014] Debug: m_ldap_authentication: searching for (&(uid=DukePyrolator)(objectClass=anopeUser)) [Jun 14 10:25:05.758122 2014] Debug: *** ldap_search_ext(this->con, ou=users,dc=anope,dc=org, LDAP_SCPE_SUBTREE, (&(uid=DukePyrolator)(objectClass=anopeUser)), NULL, 0, NULL, NULL, NULL, 0, 32767); [Jun 14 10:25:05.758218 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:25:05.777012 2014] Debug: m_ldap_authenticationn: binding as uid=DukePyrolator,ou=users,dc=anope,dc=org [Jun 14 10:25:05.777094 2014] Debug: *** ldap_sasl_bind(con, uid=DukePyrolator,ou=users,dc=anope,dc=org, LDAP_SASL_SIMPLE, testpass, NULL, NULL, 32767); [Jun 14 10:25:05.777205 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); syslog messages from the slapd: Jun 14 10:25:06 anope slapd[22502]: conn=7949 fd=13 ACCEPT from :59557 (IP=0.0.0.0:389) Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=0 BIND dn="" method=128 Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=0 RESULT tag=97 err=0 text= Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=1 SRCH base="ou=users,dc=anope,dc=org" scope=2 deref=0 filter="(&(uid=dukepyrolator)(objectClass=anope Jun 14 10:25:06 anope slapd[22502]: <= bdb_equality_candidates: (uid) not indexed Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=1 SEARCH RESULT tag=101 err=0 nentries=1 text= Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=2 BIND dn="uid=DukePyrolator,ou=users,dc=anope,dc=org" method=128 Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=2 BIND dn="uid=DukePyrolator,ou=users,dc=anope,dc=org" mech=SIMPLE ssf=0 Jun 14 10:25:06 anope slapd[22502]: conn=7949 op=2 RESULT tag=97 err=0 text= **************************************************************************************************************** this happens after a few logins/logouts: [Jun 14 10:22:58.791725 2014] Debug: Received: :971AAAAAN PRIVMSG 00AAAAAAG :id testpass [Jun 14 10:22:58.792133 2014] Debug: *** ldap_search_ext(this->con, uid=DukePyrolator,ou=users,dc=anope,dc=org, LDAP_SCPE_SUBTREE, (email=*), NULL, 0, NULL, NULL, NULL, 0, 0); [Jun 14 10:22:58.792200 2014] Debug: *** ldap_sasl_bind(con, , LDAP_SASL_SIMPLE, , NULL, NULL, 32767); [Jun 14 10:22:58.792339 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:22:58.792792 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:22:58.792842 2014] Debug: m_ldap_authentication: searching for (&(uid=DukePyrolator)(objectClass=anopeUser)) [Jun 14 10:22:58.792856 2014] Debug: *** ldap_search_ext(this->con, ou=users,dc=anope,dc=org, LDAP_SCOPE_SUBTREE, (&(uid=DukePyrolator)(objectClass=anopeUser)), NULL, 0, NULL, NULL, NULL, 0, 0); [Jun 14 10:22:59.793953 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); < removed like ~60 ldap_result() messages from the log, one message per second > [Jun 14 10:24:04.865872 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:24:05.867222 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:24:05.867243 2014] M_LDAP: Error running LDAP query: Query timed out [Jun 14 10:24:05.867261 2014] M_LDAP_AUTHENTICATION: Query timed out [Jun 14 10:24:05.867270 2014] M_LDAP: Error running LDAP query: Query timed out last response for this query from slapd: Jun 14 10:22:59 anope slapd[22502]: conn=7943 op=160 RESULT tag=97 err=0 text= **************************************************************************************************************** another example of a timeout: ================= [Jun 14 10:40:46.999947 2014] Debug: Received: :971AAAAAO PRIVMSG 00AAAAAAG :id testpass [Jun 14 10:40:46.000345 2014] Debug: *** ldap_search_ext(this->con, uid=DukePyrolator,ou=users,dc=anope,dc=org, LDAP_SCPE_SUBTREE, (email=*), NULL, 0, NULL, NULL, NULL, 0, 0); [Jun 14 10:40:46.000421 2014] Debug: *** ldap_sasl_bind(con, , LDAP_SASL_SIMPLE, , NULL, NULL, 32767); [Jun 14 10:40:46.000635 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:46.001082 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:46.001230 2014] Debug: m_ldap_authentication: searching for (&(uid=DukePyrolator)(objectClass=anopeUser)) [Jun 14 10:40:46.001256 2014] Debug: *** ldap_search_ext(this->con, ou=users,dc=anope,dc=org, LDAP_SCPE_SUBTREE, (&(uid=DukePyrolator)(objectClass=anopeUser)), NULL, 0, NULL, NULL, NULL, 0, 0); [Jun 14 10:40:47.002236 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:48.003393 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:49.021295 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:49.021361 2014] Debug: m_ldap_authenticationn: binding as uid=DukePyrolator,ou=users,dc=anope,dc=org [Jun 14 10:40:49.021384 2014] Debug: *** ldap_sasl_bind(con, uid=DukePyrolator,ou=users,dc=anope,dc=org, LDAP_SASL_SIMPLE, testpass, NULL, NULL, 32767); [Jun 14 10:40:49.021771 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:50.022860 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:51.023977 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:52.025086 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:53.026193 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:54.027299 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:40:54.027332 2014] M_LDAP: Error running LDAP query: Query timed out [Jun 14 10:40:54.027388 2014] M_LDAP_AUTHENTICATION: Query timed out Syslog messages from the same time: Jun 14 10:40:47 anope slapd[22502]: conn=7949 op=3 SRCH base="uid=DukePyrolator,ou=users,dc=anope,dc=org" scope=2 deref=0 filter="(email=*)" Jun 14 10:40:47 anope slapd[22502]: conn=7949 op=4 BIND anonymous mech=implicit ssf=0 Jun 14 10:40:47 anope slapd[22502]: conn=7949 op=4 BIND dn="" method=128 Jun 14 10:40:47 anope slapd[22502]: conn=7949 op=4 RESULT tag=97 err=0 text= Jun 14 10:40:49 anope slapd[22502]: conn=7949 op=5 SRCH base="ou=users,dc=anope,dc=org" scope=2 deref=0 filter="(&(uid=dukepyrolator)(objectClass=anope Jun 14 10:40:49 anope slapd[22502]: <= bdb_equality_candidates: (uid) not indexed Jun 14 10:40:49 anope slapd[22502]: conn=7949 op=5 SEARCH RESULT tag=101 err=0 nentries=1 text= Jun 14 10:40:49 anope slapd[22502]: conn=7949 op=6 BIND dn="uid=DukePyrolator,ou=users,dc=anope,dc=org" method=128 Jun 14 10:40:49 anope slapd[22502]: conn=7949 op=6 BIND dn="uid=DukePyrolator,ou=users,dc=anope,dc=org" mech=SIMPLE ssf=0 Jun 14 10:40:49 anope slapd[22502]: conn=7949 op=6 RESULT tag=97 err=0 text= ********************************************************************************************************* wrong password: [Jun 14 10:54:10.355461 2014] Debug: Received: :971AAAAAO PRIVMSG 00AAAAAAG :id wrongpass [Jun 14 10:54:10.355587 2014] Debug: *** ldap_sasl_bind(con, , LDAP_SASL_SIMPLE, , NULL, NULL, 32767); [Jun 14 10:54:10.355670 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:54:10.355963 2014] Debug: m_ldap_authentication: searching for (&(uid=DukePyrolator)(objectClass=anopeUser)) [Jun 14 10:54:10.355981 2014] Debug: *** ldap_search_ext(this->con, ou=users,dc=anope,dc=org, LDAP_SCPE_SUBTREE, (&(uid=DukePyrolator)(objectClass=anopeUser)), NULL, 0, NULL, NULL, NULL, 0, 0); [Jun 14 10:54:10.356039 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:54:10.372687 2014] Debug: m_ldap_authenticationn: binding as uid=DukePyrolator,ou=users,dc=anope,dc=org [Jun 14 10:54:10.372725 2014] Debug: *** ldap_sasl_bind(con, uid=DukePyrolator,ou=users,dc=anope,dc=org, LDAP_SASL_SIMPLE, wrongpass, NULL, NULL, 32767); [Jun 14 10:54:10.372790 2014] Debug: *** ldap_result(this->con, LDAP_RES_ANY, 1, &tv, &result); [Jun 14 10:54:10.373191 2014] M_LDAP: Error running LDAP query: Invalid credentials [Jun 14 10:54:10.373250 2014] Debug: Sent: :00AAAAAAG NOTICE 971AAAAAO :Password incorrect.