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

Authenticating from MacOS X 10.5.3 to OpenLDAP



I have spent 2+ days trying to get a MacOS 10.5.3 machine configured to
authenticate against a basic openldap 2.4.10 server with SSL on a Linux
box - no SASL, no kerberos - I was hoping it would be trivial, and from
the looks of all the postings around on the web, it should be.  I know
I'm doing the right things, yet I must be missing one minor detail.

I did the initial openldap setup, and within a very short time, I was
able to authenticate the MacOS box against the Linux box NON-SSSL
without any trouble.  When I enable SSL on the Mac, I can use ldapsearch
to probe the whole ldaps:// server without any trouble at all.  On the
Mac, I can verify the SSL certificate:

openssl s_client -connect server:636 -CApath /certs

(I've created the proper hash files for the parts of the CA path)

and I can see:
.
.
Verify return code: 0 (ok)

In the /etc/openldap/ldap.conf on the MAC, I have:

TLS_REQCERT demand (MAC default)
TLS_CACERTDIR /certs

(Even if I change "TLS_REQCERT to never", it doesn't make any difference.)

When I'm in the "Directory Utility" and creating a new connection, I
click "Continue", then it hangs for a bit, and just comes back.  The
connection doesn't work.

The slapd.config on the Linux side has all the correct TLS paths as well
(of course if it didn't, my ldapsearch -x queries wouldn't even work):

TLSCipherSuite HIGH:MEDIUM:+SSLv2
TLSCACertificatePath  /openssl/certs
TLSCertificateFile /openssl/certs/mycert.pem
TLSCertificateKeyFile /openssl/certs/mycert.pem

(The certificate and key are appended together in mycert.pem - that
seems to be allowed)

The certificate is a paid one - not a self-signed one.

Here's the openldap debug info that is printed while I'm waiting for
initialization of the connection ...  I do see:

TLS: can't accept.

connection_read(14): TLS accept failure error=-1 id=0, closing
connection_closing: readying conn=0 sd=14 for close

... but I just don't understand why given that everything seems set up
properly.  If it wasn't, why would the ldapsearch work!?

Thanks!

jas.

------ verbose openldap debugging output

connection_get(15)
connection_get(15): got connid=1
connection_read(15): checking for input on id=1
TLS trace: SSL_accept:before/accept initialization
tls_read: want=11, got=11
tls_read: want=107, got=107
TLS trace: SSL_accept:SSLv3 read client hello A
TLS trace: SSL_accept:SSLv3 write server hello A
tls_write: want=4096, written=4096
TLS trace: SSL_accept:SSLv3 write certificate A
TLS trace: SSL_accept:SSLv3 write server done A
tls_write: want=723, written=723
TLS trace: SSL_accept:SSLv3 flush data
tls_read: want=5 error=Resource temporarily unavailable
TLS trace: SSL_accept:error in SSLv3 read client certificate A
TLS trace: SSL_accept:error in SSLv3 read client certificate A
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=1
connection_read(15): checking for input on id=1
tls_read: want=5, got=5
tls_read: want=134, got=134
TLS trace: SSL_accept:SSLv3 read client key exchange A
tls_read: want=5, got=5
tls_read: want=1, got=1
tls_read: want=5, got=5
tls_read: want=48, got=48
TLS trace: SSL_accept:SSLv3 read finished A
TLS trace: SSL_accept:SSLv3 write change cipher spec A
TLS trace: SSL_accept:SSLv3 write finished A
tls_write: want=59, written=59
TLS trace: SSL_accept:SSLv3 flush data
connection_read(15): unable to get TLS client DN, error=49 id=1
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=1
connection_read(15): checking for input on id=1
ber_get_next
tls_read: want=5, got=5
tls_read: want=32, got=32
TLS trace: SSL3 alert read:warning:close notify
ldap_read: want=8, got=0

ber_get_next on fd 15 failed errno=0 (Success)
connection_read(15): input error=-2 id=1, closing.
connection_closing: readying conn=1 sd=15 for close
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_close: conn=1 sd=15
daemon: removing 15
tls_write: want=37, written=37
TLS trace: SSL3 alert write:warning:close notify
daemon: activity on 1 descriptor
daemon: activity on: 14r
daemon: read active on 14
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(14)
connection_get(14): got connid=0
connection_read(14): checking for input on id=0
TLS trace: SSL_accept:before/accept initialization
tls_read: want=11, got=0

TLS: can't accept.
connection_read(14): TLS accept failure error=-1 id=0, closing
connection_closing: readying conn=0 sd=14 for close
connection_close: conn=0 sd=14
daemon: removing 14
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on:
slap_listener_activate(8):
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 busy
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
slap_listener(ldap:///)
daemon: listen=8, new connection on 14
daemon: added 14r (active) listener=(nil)
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on:
slap_listener_activate(8):
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 busy
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
slap_listener(ldap:///)
daemon: activity on 1 descriptor
daemon: activity on:
daemon: listen=8, new connection on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: added 15r (active) listener=(nil)
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=3
connection_read(15): checking for input on id=3
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=94, got=94
ber_get_next: tag 0x30 len 100 contents:
ber_dump: buf=0x82b9598 ptr=0x82b9598 end=0x82b95fc len=100
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=3 op=0 do_search
daemon: activity on 1 descriptor
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82b9598 ptr=0x82b959b end=0x82b95fc len=97
dnPrettyNormal: <>
<<< dnPrettyNormal: <>, <>
SRCH "" 0 0begin get_filter
PRESENT
ber_scanf fmt (m) ber:
ber_dump: buf=0x82b9598 ptr=0x82b95ae end=0x82b95fc len=78
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82b9598 ptr=0x82b95bb end=0x82b95fc len=65
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
=> test_filter
=> access_allowed: search access to "" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 3 dn=""
=> access_allowed: read access to "" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "" "namingContexts" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 54 bytes to sd 15
ldap_write: want=54, written=54
<= send_search_entry: conn 3 exit.
send_ldap_result: conn=3 op=0 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=1 tag=101 err=0
ber_flush2: 14 bytes to sd 15
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 14r
daemon: read active on 14
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(14)
connection_get(14): got connid=2
connection_read(14): checking for input on id=2
ber_get_next
ldap_read: want=8, got=0

ber_get_next on fd 14 failed errno=0 (Success)
connection_read(14): input error=-2 id=2, closing.
connection_closing: readying conn=2 sd=14 for close
connection_close: conn=2 sd=14
daemon: removing 14
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=3
connection_read(15): checking for input on id=3
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=50, got=50
ber_get_next: tag 0x30 len 56 contents:
ber_dump: buf=0x82cab08 ptr=0x82cab08 end=0x82cab40 len=56
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=3 op=1 do_search
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82cab08 ptr=0x82cab0b end=0x82cab40 len=53
dnPrettyNormal: <>
<<< dnPrettyNormal: <>, <>
SRCH "" 0 0begin get_filter
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
PRESENT
ber_scanf fmt (m) ber:
ber_dump: buf=0x82cab08 ptr=0x82cab1e end=0x82cab40 len=34
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82cab08 ptr=0x82cab2b end=0x82cab40 len=21
=> test_filter
=> access_allowed: search access to "" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 3 dn=""
=> access_allowed: read access to "" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "" "subschemaSubentry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "" "subschemaSubentry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 48 bytes to sd 15
ldap_write: want=48, written=48
<= send_search_entry: conn 3 exit.
send_ldap_result: conn=3 op=1 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=2 tag=101 err=0
ber_flush2: 14 bytes to sd 15
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=3
connection_read(15): checking for input on id=3
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=71, got=71
ber_get_next: tag 0x30 len 77 contents:
ber_dump: buf=0x82b9578 ptr=0x82b9578 end=0x82b95c5 len=77
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=3 op=2 do_search
ber_scanf fmt ({miiiib) ber:
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
ber_dump: buf=0x82b9578 ptr=0x82b957b end=0x82b95c5 len=74
dnPrettyNormal: <cn=Subschema>
=> ldap_bv2dn(cn=Subschema,0)
<= ldap_bv2dn(cn=Subschema)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=Subschema)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=subschema)=0
<<< dnPrettyNormal: <cn=Subschema>, <cn=subschema>
SRCH "cn=Subschema" 0 0begin get_filter
EQUALITY
ber_scanf fmt ({mm}) ber:
ber_dump: buf=0x82b9578 ptr=0x82b959a end=0x82b95c5 len=43
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82b9578 ptr=0x82b95b4 end=0x82b95c5 len=17
=> test_filter
=> access_allowed: search access to "cn=Subschema" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 3 dn="cn=Subschema"
=> access_allowed: read access to "cn=Subschema" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 16753 bytes to sd 15
ldap_write: want=16753, written=13032
ldap_write: want=3721, written=3721
<= send_search_entry: conn 3 exit.
send_ldap_result: conn=3 op=2 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=3 tag=101 err=0
ber_flush2: 14 bytes to sd 15
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=3
connection_read(15): checking for input on id=3
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=111, got=111
ber_get_next: tag 0x30 len 117 contents:
ber_dump: buf=0x82ba928 ptr=0x82ba928 end=0x82ba99d len=117
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
conn=3 op=3 do_search
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82ba928 ptr=0x82ba92b end=0x82ba99d len=114
dnPrettyNormal: <dc=cse,dc=yorku,dc=ca>
=> ldap_bv2dn(dc=cse,dc=yorku,dc=ca,0)
<= ldap_bv2dn(dc=cse,dc=yorku,dc=ca)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=cse,dc=yorku,dc=ca)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=cse,dc=yorku,dc=ca)=0
<<< dnPrettyNormal: <dc=cse,dc=yorku,dc=ca>, <dc=cse,dc=yorku,dc=ca>
SRCH "dc=cse,dc=yorku,dc=ca" 2 0begin get_filter
AND
begin get_filter_list
begin get_filter
EQUALITY
ber_scanf fmt ({mm}) ber:
ber_dump: buf=0x82ba928 ptr=0x82ba955 end=0x82ba99d len=72
end get_filter 0
begin get_filter
EQUALITY
ber_scanf fmt ({mm}) ber:
ber_dump: buf=0x82ba928 ptr=0x82ba978 end=0x82ba99d len=37
end get_filter 0
end get_filter_list
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82ba928 ptr=0x82ba98e end=0x82ba99d len=15
==> limits_get: conn=3 op=3 dn="[anonymous]"
=> bdb_search
bdb_dn2entry("dc=cse,dc=yorku,dc=ca")
=> bdb_dn2id("dc=cse,dc=yorku,dc=ca")
<= bdb_dn2id: got id=0x1
entry_decode: "dc=cse,dc=yorku,dc=ca"
<= entry_decode(dc=cse,dc=yorku,dc=ca)
=> access_allowed: search access to "dc=cse,dc=yorku,dc=ca" "entry"
requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
search_candidates: base="dc=cse,dc=yorku,dc=ca" (0x00000001) scope=2
=> bdb_dn2idl("dc=cse,dc=yorku,dc=ca")
=> bdb_filter_candidates
	AND
=> bdb_list_candidates 0xa0
=> bdb_filter_candidates
	OR
=> bdb_list_candidates 0xa1
=> bdb_filter_candidates
	EQUALITY
=> bdb_equality_candidates (objectClass)
=> key_read
bdb_idl_fetch_key: [b49d1940]
<= bdb_index_read: failed (-30989)
<= bdb_equality_candidates: id=0, first=0, last=0
<= bdb_filter_candidates: id=0 first=0 last=0
=> bdb_filter_candidates
	AND
=> bdb_list_candidates 0xa0
=> bdb_filter_candidates
	EQUALITY
=> bdb_equality_candidates (objectClass)
=> key_read
bdb_idl_fetch_key: [9bee355f]
<= bdb_index_read 2 candidates
<= bdb_equality_candidates: id=2, first=3, last=4
<= bdb_filter_candidates: id=2 first=3 last=4
=> bdb_filter_candidates
	EQUALITY
=> bdb_equality_candidates (ou)
<= bdb_equality_candidates: (ou) not indexed
<= bdb_filter_candidates: id=-1 first=1 last=7
<= bdb_list_candidates: id=-1 first=3 last=4
<= bdb_filter_candidates: id=-1 first=3 last=4
<= bdb_list_candidates: id=-1 first=3 last=4
<= bdb_filter_candidates: id=-1 first=3 last=4
<= bdb_list_candidates: id=-1 first=3 last=4
<= bdb_filter_candidates: id=-1 first=3 last=4
bdb_search_candidates: id=-1 first=3 last=4
entry_decode: "ou=people,dc=cse,dc=yorku,dc=ca"
<= entry_decode(ou=people,dc=cse,dc=yorku,dc=ca)
=> bdb_dn2id("ou=people,dc=cse,dc=yorku,dc=ca")
<= bdb_dn2id: got id=0x3
=> test_filter
=> test_filter_and
=> test_filter
=> access_allowed: search access to "ou=people,dc=cse,dc=yorku,dc=ca"
"objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> test_filter
=> access_allowed: search access to "ou=people,dc=cse,dc=yorku,dc=ca"
"ou" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 5
<= test_filter_and 5
<= test_filter 5
bdb_search: 3 does not match filter
entry_decode: "ou=group,dc=cse,dc=yorku,dc=ca"
<= entry_decode(ou=group,dc=cse,dc=yorku,dc=ca)
=> bdb_dn2id("ou=group,dc=cse,dc=yorku,dc=ca")
<= bdb_dn2id: got id=0x4
=> test_filter
=> test_filter_and
=> test_filter
=> access_allowed: search access to "ou=group,dc=cse,dc=yorku,dc=ca"
"objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> test_filter
=> access_allowed: search access to "ou=group,dc=cse,dc=yorku,dc=ca"
"ou" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 5
<= test_filter_and 5
<= test_filter 5
bdb_search: 4 does not match filter
send_ldap_result: conn=3 op=3 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=4 tag=101 err=0
ber_flush2: 14 bytes to sd 15
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
connection_get(15)
connection_get(15): got connid=3
connection_read(15): checking for input on id=3
ber_get_next
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
ldap_read: want=8, got=8
ldap_read: want=42, got=42
ber_get_next: tag 0x30 len 48 contents:
ber_dump: buf=0x82cab08 ptr=0x82cab08 end=0x82cab38 len=48
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=3 op=4 do_search
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82cab08 ptr=0x82cab0b end=0x82cab38 len=45
dnPrettyNormal: <>
<<< dnPrettyNormal: <>, <>
SRCH "" 0 0begin get_filter
PRESENT
ber_scanf fmt (m) ber:
ber_dump: buf=0x82cab08 ptr=0x82cab1e end=0x82cab38 len=26
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82cab08 ptr=0x82cab2b end=0x82cab38 len=13
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
=> test_filter
=> access_allowed: search access to "" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 3 dn=""
=> access_allowed: read access to "" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 11 bytes to sd 15
ldap_write: want=11, written=11
<= send_search_entry: conn 3 exit.
send_ldap_result: conn=3 op=4 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=5 tag=101 err=0
ber_flush2: 14 bytes to sd 15
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=3
connection_read(15): checking for input on id=3
ber_get_next
ldap_read: want=8, got=7
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x82caea0 ptr=0x82caea0 end=0x82caea5 len=5
ber_get_next
ldap_read: want=8, got=0

ber_get_next on fd 15 failed errno=0 (Success)
connection_read(15): input error=-2 id=3, closing.
connection_closing: readying conn=3 sd=15 for close
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_close: deferring conn=3 sd=15
conn=3 op=5 do_unbind
connection_resched: attempting closing conn=3 sd=15
connection_close: conn=3 sd=15
daemon: removing 15
daemon: activity on 1 descriptor
daemon: activity on:
slap_listener_activate(10):
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 busy
slap_listener(ldaps:///)
daemon: activity on 1 descriptor
daemon: activity on:
daemon: listen=10, new connection on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: added 15r (active) listener=(nil)
daemon: activity on 2 descriptors
daemon: activity on:
slap_listener_activate(10):
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 busy
slap_listener(ldaps:///)
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: listen=10, new connection on 16
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: added 16r (active) listener=(nil)
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16): got connid=5
connection_read(16): checking for input on id=5
TLS trace: SSL_accept:before/accept initialization
tls_read: want=11, got=11
tls_read: want=107, got=107
TLS trace: SSL_accept:SSLv3 read client hello A
TLS trace: SSL_accept:SSLv3 write server hello A
tls_write: want=4096, written=4096
TLS trace: SSL_accept:SSLv3 write certificate A
TLS trace: SSL_accept:SSLv3 write server done A
tls_write: want=723, written=723
TLS trace: SSL_accept:SSLv3 flush data
tls_read: want=5 error=Resource temporarily unavailable
TLS trace: SSL_accept:error in SSLv3 read client certificate A
TLS trace: SSL_accept:error in SSLv3 read client certificate A
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=5
connection_read(16): checking for input on id=5
tls_read: want=5, got=5
tls_read: want=134, got=134
TLS trace: SSL_accept:SSLv3 read client key exchange A
tls_read: want=5, got=5
tls_read: want=1, got=1
tls_read: want=5, got=5
tls_read: want=48, got=48
TLS trace: SSL_accept:SSLv3 read finished A
TLS trace: SSL_accept:SSLv3 write change cipher spec A
TLS trace: SSL_accept:SSLv3 write finished A
tls_write: want=59, written=59
TLS trace: SSL_accept:SSLv3 flush data
connection_read(16): unable to get TLS client DN, error=49 id=5
daemon: activity on 2 descriptors
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=5
connection_read(16): checking for input on id=5
ber_get_next
tls_read: want=5, got=5
tls_read: want=32, got=32
TLS trace: SSL3 alert read:warning:close notify
ldap_read: want=8, got=0

ber_get_next on fd 16 failed errno=0 (Success)
connection_read(16): input error=-2 id=5, closing.
connection_closing: readying conn=5 sd=16 for close
connection_close: conn=5 sd=16
daemon: removing 16
tls_write: want=37, written=37
TLS trace: SSL3 alert write:warning:close notify
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(15)
connection_get(15): got connid=4
connection_read(15): checking for input on id=4
TLS trace: SSL_accept:before/accept initialization
tls_read: want=11, got=0

TLS: can't accept.
connection_read(15): TLS accept failure error=-1 id=4, closing
connection_closing: readying conn=4 sd=15 for close
connection_close: conn=4 sd=15
daemon: removing 15
daemon: activity on 2 descriptors
daemon: activity on:
slap_listener_activate(8):
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 busy
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
slap_listener(ldap:///)
daemon: listen=8, new connection on 15
daemon: added 15r (active) listener=(nil)
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on:
slap_listener_activate(8):
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 busy
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
slap_listener(ldap:///)
daemon: listen=8, new connection on 16
daemon: activity on 1 descriptor
daemon: activity on:
daemon: added 16r (active) listener=(nil)
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 2 descriptors
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=7
connection_read(16): checking for input on id=7
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=94, got=94
ber_get_next: tag 0x30 len 100 contents:
ber_dump: buf=0x82bd918 ptr=0x82bd918 end=0x82bd97c len=100
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=7 op=0 do_search
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82bd918 ptr=0x82bd91b end=0x82bd97c len=97
dnPrettyNormal: <>
<<< dnPrettyNormal: <>, <>
SRCH "" 0 0begin get_filter
PRESENT
ber_scanf fmt (m) ber:
ber_dump: buf=0x82bd918 ptr=0x82bd92e end=0x82bd97c len=78
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82bd918 ptr=0x82bd93b end=0x82bd97c len=65
=> test_filter
=> access_allowed: search access to "" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 7 dn=""
=> access_allowed: read access to "" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "" "namingContexts" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 54 bytes to sd 16
ldap_write: want=54, written=54
<= send_search_entry: conn 7 exit.
send_ldap_result: conn=7 op=0 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=1 tag=101 err=0
ber_flush2: 14 bytes to sd 16
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 15r
daemon: read active on 15
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
connection_get(15)
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=7
connection_read(16): checking for input on id=7
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=50, got=50
ber_get_next: tag 0x30 len 56 contents:
connection_get(15): got connid=6
ber_dump: buf=0x82bbcd8 ptr=0x82bbcd8 end=0x82bbd10 len=56
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=7 op=1 do_search
ber_scanf fmt ({miiiib) ber:
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
ber_dump: buf=0x82bbcd8 ptr=0x82bbcdb end=0x82bbd10 len=53
dnPrettyNormal: <>
<<< dnPrettyNormal: <>, <>
SRCH "" 0 0connection_read(15): checking for input on id=6
begin get_filter
PRESENT
ber_scanf fmt (m) ber:
ber_dump: buf=0x82bbcd8 ptr=0x82bbcee end=0x82bbd10 len=34
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82bbcd8 ptr=0x82bbcfb end=0x82bbd10 len=21
=> test_filter
=> access_allowed: search access to "" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 7 dn=""
=> access_allowed: read access to "" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "" "subschemaSubentry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "" "subschemaSubentry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 48 bytes to sd 16
ldap_write: want=48, written=48
<= send_search_entry: conn 7 exit.
send_ldap_result: conn=7 op=1 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=2 tag=101 err=0
ber_flush2: 14 bytes to sd 16
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=7
connection_read(16): checking for input on id=7
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=71, got=71
ber_get_next: tag 0x30 len 77 contents:
ber_dump: buf=0x82cb5b0 ptr=0x82cb5b0 end=0x82cb5fd len=77
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=7 op=2 do_search
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82cb5b0 ptr=0x82cb5b3 end=0x82cb5fd len=74
dnPrettyNormal: <cn=Subschema>
=> ldap_bv2dn(cn=Subschema,0)
<= ldap_bv2dn(cn=Subschema)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=Subschema)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(cn=subschema)=0
<<< dnPrettyNormal: <cn=Subschema>, <cn=subschema>
SRCH "cn=Subschema" 0 0begin get_filter
EQUALITY
ber_scanf fmt ({mm}) ber:
ber_dump: buf=0x82cb5b0 ptr=0x82cb5d2 end=0x82cb5fd len=43
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_get_next
ber_dump: buf=0x82cb5b0 ptr=0x82cb5ec end=0x82cb5fd len=17
ldap_read: want=8, got=0

ber_get_next on fd 15 failed errno=0 (Success)
connection_read(15): input error=-2 id=6, closing.
connection_closing: readying conn=6 sd=15 for close
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_close: conn=6 sd=15
daemon: removing 15
=> test_filter
=> access_allowed: search access to "cn=Subschema" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 7 dn="cn=Subschema"
=> access_allowed: read access to "cn=Subschema" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
=> access_allowed: read access to "cn=Subschema" "objectClasses" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 16753 bytes to sd 16
ldap_write: want=16753, written=13032
ldap_write: want=3721, written=3721
<= send_search_entry: conn 7 exit.
send_ldap_result: conn=7 op=2 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=3 tag=101 err=0
ber_flush2: 14 bytes to sd 16
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=7
connection_read(16): checking for input on id=7
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=111, got=111
ber_get_next: tag 0x30 len 117 contents:
ber_dump: buf=0x82bedb8 ptr=0x82bedb8 end=0x82bee2d len=117
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
conn=7 op=3 do_search
ber_scanf fmt ({miiiib) ber:
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
ber_dump: buf=0x82bedb8 ptr=0x82bedbb end=0x82bee2d len=114
dnPrettyNormal: <dc=cse,dc=yorku,dc=ca>
=> ldap_bv2dn(dc=cse,dc=yorku,dc=ca,0)
<= ldap_bv2dn(dc=cse,dc=yorku,dc=ca)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=cse,dc=yorku,dc=ca)=0
=> ldap_dn2bv(272)
<= ldap_dn2bv(dc=cse,dc=yorku,dc=ca)=0
<<< dnPrettyNormal: <dc=cse,dc=yorku,dc=ca>, <dc=cse,dc=yorku,dc=ca>
SRCH "dc=cse,dc=yorku,dc=ca" 2 0begin get_filter
AND
begin get_filter_list
begin get_filter
EQUALITY
ber_scanf fmt ({mm}) ber:
ber_dump: buf=0x82bedb8 ptr=0x82bede5 end=0x82bee2d len=72
end get_filter 0
begin get_filter
EQUALITY
ber_scanf fmt ({mm}) ber:
ber_dump: buf=0x82bedb8 ptr=0x82bee08 end=0x82bee2d len=37
end get_filter 0
end get_filter_list
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82bedb8 ptr=0x82bee1e end=0x82bee2d len=15
==> limits_get: conn=7 op=3 dn="[anonymous]"
=> bdb_search
bdb_dn2entry("dc=cse,dc=yorku,dc=ca")
=> access_allowed: search access to "dc=cse,dc=yorku,dc=ca" "entry"
requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
search_candidates: base="dc=cse,dc=yorku,dc=ca" (0x00000001) scope=2
=> bdb_dn2idl("dc=cse,dc=yorku,dc=ca")
=> bdb_filter_candidates
	AND
=> bdb_list_candidates 0xa0
=> bdb_filter_candidates
	OR
=> bdb_list_candidates 0xa1
=> bdb_filter_candidates
	EQUALITY
=> bdb_equality_candidates (objectClass)
=> key_read
bdb_idl_fetch_key: [b49d1940]
<= bdb_index_read: failed (-30989)
<= bdb_equality_candidates: id=0, first=0, last=0
<= bdb_filter_candidates: id=0 first=0 last=0
=> bdb_filter_candidates
	AND
=> bdb_list_candidates 0xa0
=> bdb_filter_candidates
	EQUALITY
=> bdb_equality_candidates (objectClass)
=> key_read
bdb_idl_fetch_key: [9bee355f]
<= bdb_index_read 2 candidates
<= bdb_equality_candidates: id=2, first=3, last=4
<= bdb_filter_candidates: id=2 first=3 last=4
=> bdb_filter_candidates
	EQUALITY
=> bdb_equality_candidates (ou)
<= bdb_equality_candidates: (ou) not indexed
<= bdb_filter_candidates: id=-1 first=1 last=7
<= bdb_list_candidates: id=-1 first=3 last=4
<= bdb_filter_candidates: id=-1 first=3 last=4
<= bdb_list_candidates: id=-1 first=3 last=4
<= bdb_filter_candidates: id=-1 first=3 last=4
<= bdb_list_candidates: id=-1 first=3 last=4
<= bdb_filter_candidates: id=-1 first=3 last=4
bdb_search_candidates: id=-1 first=3 last=4
=> test_filter
=> test_filter_and
=> test_filter
=> access_allowed: search access to "ou=people,dc=cse,dc=yorku,dc=ca"
"objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> test_filter
=> access_allowed: search access to "ou=people,dc=cse,dc=yorku,dc=ca"
"ou" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 5
<= test_filter_and 5
<= test_filter 5
bdb_search: 3 does not match filter
=> test_filter
=> test_filter_and
=> test_filter
=> access_allowed: search access to "ou=group,dc=cse,dc=yorku,dc=ca"
"objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> test_filter
=> access_allowed: search access to "ou=group,dc=cse,dc=yorku,dc=ca"
"ou" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 5
<= test_filter_and 5
<= test_filter 5
bdb_search: 4 does not match filter
send_ldap_result: conn=7 op=3 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=4 tag=101 err=0
ber_flush2: 14 bytes to sd 16
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=7
connection_read(16): checking for input on id=7
ber_get_next
ldap_read: want=8, got=8
ldap_read: want=42, got=42
ber_get_next: tag 0x30 len 48 contents:
ber_dump: buf=0x82bbcd8 ptr=0x82bbcd8 end=0x82bbd08 len=48
ber_get_next
ldap_read: want=8 error=Resource temporarily unavailable
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
conn=7 op=4 do_search
ber_scanf fmt ({miiiib) ber:
ber_dump: buf=0x82bbcd8 ptr=0x82bbcdb end=0x82bbd08 len=45
dnPrettyNormal: <>
<<< dnPrettyNormal: <>, <>
SRCH "" 0 0begin get_filter
PRESENT
ber_scanf fmt (m) ber:
ber_dump: buf=0x82bbcd8 ptr=0x82bbcee end=0x82bbd08 len=26
end get_filter 0
ber_scanf fmt ({M}}) ber:
ber_dump: buf=0x82bbcd8 ptr=0x82bbcfb end=0x82bbd08 len=13
=> test_filter
=> access_allowed: search access to "" "objectClass" requested
=> slap_access_allowed: backend default search access granted to
"(anonymous)"
=> access_allowed: search access granted by read(=rscxd)
<= test_filter 6
=> send_search_entry: conn 7 dn=""
=> access_allowed: read access to "" "entry" requested
=> slap_access_allowed: backend default read access granted to
"(anonymous)"
=> access_allowed: read access granted by read(=rscxd)
ber_flush2: 11 bytes to sd 16
ldap_write: want=11, written=11
<= send_search_entry: conn 7 exit.
send_ldap_result: conn=7 op=4 p=3
send_ldap_result: err=0 matched="" text=""
send_ldap_response: msgid=5 tag=101 err=0
ber_flush2: 14 bytes to sd 16
ldap_write: want=14, written=14
daemon: activity on 1 descriptor
daemon: activity on: 16r
daemon: read active on 16
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
connection_get(16)
connection_get(16): got connid=7
connection_read(16): checking for input on id=7
ber_get_next
ldap_read: want=8, got=7
ber_get_next: tag 0x30 len 5 contents:
ber_dump: buf=0x82cac98 ptr=0x82cac98 end=0x82cac9d len=5
ber_get_next
ldap_read: want=8, got=0

ber_get_next on fd 16 failed errno=0 (Success)
connection_read(16): input error=-2 id=7, closing.
connection_closing: readying conn=7 sd=16 for close
connection_close: deferring conn=7 sd=16
conn=7 op=5 do_unbind
connection_resched: attempting closing conn=7 sd=16
connection_close: conn=7 sd=16
daemon: removing 16
daemon: activity on 1 descriptor
daemon: activity on:
daemon: epoll: listen=7 active_threads=0 tvp=NULL
daemon: epoll: listen=8 active_threads=0 tvp=NULL
daemon: epoll: listen=9 active_threads=0 tvp=NULL
daemon: epoll: listen=10 active_threads=0 tvp=NULL
daemon: shutdown requested and initiated.
daemon: closing 7
daemon: closing 8
daemon: closing 9
daemon: closing 10