Re: debug Pass-Trough authentication with SASL

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



On 02/08/16 14:28 +0100, Thomas Peter via Cyrus-sasl wrote:
Hi,

I run
saslauthd 2.1.26
authentication mechanisms: getpwent kerberos5 rimap shadow ldap

and in my etc/saslauthd.conf I do have
ldap_servers: ldap://example.com:389
which is a round-robin dns (10 IPs behind)

Now I have the problem that sometimes the connection to example.com seems to hang:

Jan 18 17:06:15 uit-algo-p-11 slapd[130335]: conn=1410 op=0 BIND dn="cn=petert,dc=example,dc=com" method=128 Jan 18 17:06:15 uit-algo-p-11 slapd[130335]: conn=1410 op=0 BIND dn="cn=petert,dc=example,dc=com" mech=SIMPLE ssf=0 Jan 18 17:06:15 uit-algo-p-11 slapd[130335]: conn=1410 op=0 RESULT tag=97 err=0 text= Jan 18 17:06:15 uit-algo-p-11 slapd[130335]: conn=1411 fd=15 ACCEPT from IP=127.0.0.1:54144 (IP=127.0.0.1:9389) Jan 18 17:06:15 uit-algo-p-11 slapd[130335]: conn=1411 op=0 BIND dn="cn=petert,dc=example,dc=com" method=128
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: conn=1410 op=1 UNBIND
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: conn=1410 fd=14 closed
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: connection_read(14): no connection!
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: conn=1408 op=2 UNBIND
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: conn=1408 fd=13 closed
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: connection_read(13): no connection!
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: conn=1407 op=1 UNBIND
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: conn=1407 fd=10 closed
Jan 18 17:06:16 uit-algo-p-11 slapd[130335]: connection_read(10): no connection! Jan 18 17:21:49 uit-algo-p-11 saslauthd[131005]: ldap_simple_bind() failed -1 (Can't contact LDAP server).
Jan 18 17:21:49 uit-algo-p-11 saslauthd[131005]: Retrying authentication
Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1411 op=0 BIND dn="cn=petert,dc=example,dc=com" mech=SIMPLE ssf=0 Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1411 op=0 RESULT tag=97 err=0 text= Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1411 op=1 SRCH base="dc=example,dc=com" scope=1 deref=3 filter="(&(uid=petert))" Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: <= mdb_equality_candidates: (uid) not indexed Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1411 op=1 SEARCH RESULT tag=101 err=0 nentries=1 text= Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1412 fd=10 ACCEPT from IP=127.0.0.1:54761 (IP=127.0.0.1:9389) Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1412 op=0 BIND dn="cn=petert,dc=example,dc=com" method=128 Jan 18 17:21:49 uit-algo-p-11 saslauthd[131009]: ldap_simple_bind() failed -1 (Can't contact LDAP server).
Jan 18 17:21:49 uit-algo-p-11 saslauthd[131009]: Retrying authentication
Jan 18 17:21:49 uit-algo-p-11 slapd[130335]: conn=1412 op=0 BIND dn="cn=petert,dc=example,dc=com" mech=SIMPLE ssf=0

The message "ldap_simple_bind() failed -1 (Can't contact LDAP server)." is quite common and it looks like I get it every bind. What I'm now looking for is some help in diagnosis like "to which IP is saslauthd now connecting to and trying the bind"

Any hints?
btw: I can't run my openldap and saslauthd as root hence the portnumbers > 1024.

You can enable debugging output by running saslauthd in debug mode (-d),
and by specifying a debug level for the libldap library with 'ldap_debug'
within your saslauthd.conf. That's an undocumented option for the saslauthd
ldap backend, but is used to set the LDAP_OPT_DEBUG_LEVEL. See
ldap_set_option(3), and consult include/ldap_log.h for valid values
(LDAP_DEBUG_*).

Also, check your ldap.conf (see ldap.conf(5)) as the ldap backend will use
any defaults you've configured there.

Try reproducing the trouble with ldapsearch/ldapwhoami on the server
running saslauthd, which may ease trouble shooting.

--
Dan White



[Index of Archives]     [Info Cyrus]     [Squirrel Mail]     [Linux Media]     [Yosemite News]     [gtk]     [KDE]     [Gimp on Windows]     [Steve's Art]

  Powered by Linux