URL:
https://github.com/SSSD/sssd/pull/67
Title: #67: UTIL: Unset O_NONBLOCK for ldap connection
lslebodn commented:
"""
client log + gnutls debug
```
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [simple_bind_send] (0x0100): Executing simple
bind as: uid=mof_user1,dc=example,dc=com
gnutls[5]: REC[0x6ae7d0]: Preparing Packet Application Data(23) with length: 86 and min
pad: 0
gnutls[9]: ENC[0x6ae7d0]: cipher: NULL, MAC: MAC-NULL, Epoch: 0
gnutls[11]: WRITE: enqueued 91 bytes for 0x64aec0. Total 91 bytes.
gnutls[11]: WRITE FLUSH: 91 bytes in buffer.
gnutls[11]: WRITE: wrote 91 bytes, 0 bytes left.
gnutls[5]: REC[0x6ae7d0]: Sent Packet[2] Application Data(23) in epoch 0 and length: 91
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [simple_bind_send] (0x2000): ldap simple bind
sent, msgid = 2
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_op_add] (0x2000): New operation 2
timeout 6
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace:
sh[0x696940], connected[1], ops[0x696a90], ldap[0x698cf0]
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: end of
ldap_result list
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace:
sh[0x696940], connected[1], ops[0x696a90], ldap[0x698cf0]
gnutls[10]: READ: Got 5 bytes from 0x64aec0
gnutls[10]: READ: read 5 bytes from 0x64aec0
gnutls[10]: RB: Have 0 bytes into buffer. Adding 5 bytes.
gnutls[10]: RB: Requested 5 bytes
gnutls[5]: REC[0x6ae7d0]: SSL 3.3 Handshake packet received. Epoch 0, length: 1124
gnutls[5]: REC[0x6ae7d0]: Expected Packet Application Data(23)
gnutls[5]: REC[0x6ae7d0]: Received Packet Handshake(22) with length: 1124
gnutls[10]: READ: Got 1124 bytes from 0x64aec0
gnutls[10]: READ: read 1124 bytes from 0x64aec0
gnutls[10]: RB: Have 5 bytes into buffer. Adding 1124 bytes.
gnutls[10]: RB: Requested 1129 bytes
gnutls[5]: REC[0x6ae7d0]: Decrypted Packet[0] Handshake(22) with length: 1124
gnutls[3]: ASSERT: handshake.c[_gnutls_recv_hello_request]:3384
gnutls[3]: ASSERT: record.c[_gnutls_recv_in_buffers]:1323
gnutls[3]: ASSERT: record.c[_gnutls_recv_int]:1468
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x0040): ldap_result
error: [Can't contact LDAP server]
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_handle_release] (0x2000): Trace:
sh[0x696940], connected[1], ops[0x696a90], ldap[0x698cf0], destructor_lock[0],
release_memory[0]
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [remove_connection_callback] (0x4000):
Successfully removed connection callback.
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [sdap_op_destructor] (0x1000): Abandoning
operation 2
gnutls[5]: REC[0x6ae7d0]: Preparing Packet Application Data(23) with length: 8 and min
pad: 0
gnutls[9]: ENC[0x6ae7d0]: cipher: NULL, MAC: MAC-NULL, Epoch: 0
gnutls[11]: WRITE: enqueued 13 bytes for 0x64aec0. Total 13 bytes.
gnutls[11]: WRITE FLUSH: 13 bytes in buffer.
gnutls[11]: WRITE: wrote 13 bytes, 0 bytes left.
gnutls[5]: REC[0x6ae7d0]: Sent Packet[3] Application Data(23) in epoch 0 and length: 13
gnutls[11]: WRITE FLUSH: 0 bytes in buffer.
gnutls[3]: ASSERT: buffers.c[_gnutls_io_write_flush]:694
gnutls[5]: REC: Sending Alert[1|0] - Close notify
gnutls[5]: REC[0x6ae7d0]: Preparing Packet Alert(21) with length: 2 and min pad: 0
gnutls[9]: ENC[0x6ae7d0]: cipher: NULL, MAC: MAC-NULL, Epoch: 0
gnutls[11]: WRITE: enqueued 7 bytes for 0x64aec0. Total 7 bytes.
gnutls[11]: WRITE FLUSH: 7 bytes in buffer.
gnutls[11]: WRITE: wrote 7 bytes, 0 bytes left.
gnutls[5]: REC[0x6ae7d0]: Sent Packet[4] Alert(21) in epoch 0 and length: 7
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [dp_req_done] (0x0400): DP Request [PAM
Authenticate #5]: Request handler finished [0]: Success
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [_dp_req_recv] (0x0400): DP Request [PAM
Authenticate #5]: Receiving request data.
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [dp_req_destructor] (0x0400): DP Request [PAM
Authenticate #5]: Request removed.
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [dp_req_destructor] (0x0400): Number of active
DP request: 0
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [dp_method_enabled] (0x0400): Target selinux
is not configured
(Thu Oct 27 11:49:01 2016) [sssd[be[LDAP]]] [dp_pam_reply] (0x1000): DP Request [PAM
Authenticate #5]: Sending result [4][LDAP]
```
"""
See the full comment at
https://github.com/SSSD/sssd/pull/67#issuecomment-256591142