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