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:20:35 2016) [sssd[be[LDAP]]] [simple_bind_send] (0x0100): Executing simple
bind as: uid=mof_user1,dc=example,dc=com
gnutls[5]: REC[0x1a380b0]: Preparing Packet Application Data(23) with length: 85 and min
pad: 0
gnutls[5]: REC[0x1a380b0]: Sent Packet[2] Application Data(23) in epoch 0 and length: 90
(Thu Oct 27 11:20:35 2016) [sssd[be[LDAP]]] [simple_bind_send] (0x2000): ldap simple bind
sent, msgid = 2
(Thu Oct 27 11:20:35 2016) [sssd[be[LDAP]]] [sdap_op_add] (0x2000): New operation 2
timeout 6
(Thu Oct 27 11:20:35 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace:
sh[0x1a30cc0], connected[1], ops[0x1a2f660], ldap[0x1a48b60]
(Thu Oct 27 11:20:35 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: end of
ldap_result list
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace:
sh[0x1a30cc0], connected[1], ops[0x1a2f660], ldap[0x1a48b60]
gnutls[5]: REC[0x1a380b0]: SSL 3.3 Handshake packet received. Epoch 0, length: 1124
gnutls[5]: REC[0x1a380b0]: Expected Packet Application Data(23)
gnutls[5]: REC[0x1a380b0]: Received Packet Handshake(22) with length: 1124
gnutls[5]: REC[0x1a380b0]: 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:20:36 2016) [sssd[be[LDAP]]] [sdap_process_result] (0x0040): ldap_result
error: [Can't contact LDAP server]
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [sdap_handle_release] (0x2000): Trace:
sh[0x1a30cc0], connected[1], ops[0x1a2f660], ldap[0x1a48b60], destructor_lock[0],
release_memory[0]
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [remove_connection_callback] (0x4000):
Successfully removed connection callback.
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [sdap_op_destructor] (0x1000): Abandoning
operation 2
gnutls[5]: REC[0x1a380b0]: Preparing Packet Application Data(23) with length: 8 and min
pad: 0
gnutls[5]: REC[0x1a380b0]: Sent Packet[3] Application Data(23) in epoch 0 and length: 13
gnutls[3]: ASSERT: buffers.c[_gnutls_io_write_flush]:694
gnutls[5]: REC: Sending Alert[1|0] - Close notify
gnutls[5]: REC[0x1a380b0]: Preparing Packet Alert(21) with length: 2 and min pad: 0
gnutls[5]: REC[0x1a380b0]: Sent Packet[4] Alert(21) in epoch 0 and length: 7
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [dp_req_done] (0x0400): DP Request [PAM
Authenticate #5]: Request handler finished [0]: Success
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [_dp_req_recv] (0x0400): DP Request [PAM
Authenticate #5]: Receiving request data.
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [dp_req_destructor] (0x0400): DP Request [PAM
Authenticate #5]: Request removed.
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [dp_req_destructor] (0x0400): Number of active
DP request: 0
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [dp_method_enabled] (0x0400): Target selinux
is not configured
(Thu Oct 27 11:20:36 2016) [sssd[be[LDAP]]] [dp_pam_reply] (0x1000): DP Request [PAM
Authenticate #5]: Sending result [4][LDAP]
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x8c86e0
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x8bbdb0
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [pam_dp_process_reply] (0x0200): received: [4
(System error)][LDAP]
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result
[4]: System error.
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [pam_reply] (0x0200): blen: 21
(Thu Oct 27 11:20:36 2016) [sssd[pam]] [reset_idle_timer] (0x4000): Idle timer re-set for
client [0x8c8d30][16]
gnutls[3]: ASSERT: buffers.c[_gnutls_io_write_flush]:694
gnutls[5]: REC: Sending Alert[1|0] - Close notify
gnutls[5]: REC[0x1a380b0]: Preparing Packet Alert(21) with length: 2 and min pad: 0
gnutls[3]: ASSERT: buffers.c[_gnutls_writev_emu]:462
gnutls[2]: WRITE: -1 returned from 0x19e78c0, errno: 9
gnutls[3]: ASSERT: buffers.c[errno_to_gerr]:228
gnutls[3]: ASSERT: buffers.c[_gnutls_io_write_flush]:720
gnutls[3]: ASSERT: record.c[_gnutls_send_tlen_int]:554
gnutls[3]: ASSERT: record.c[gnutls_bye]:302
gnutls[5]: REC[0x1a380b0]: Start of epoch cleanup
gnutls[5]: REC[0x1a380b0]: End of epoch cleanup
gnutls[5]: REC[0x1a380b0]: Epoch #0 freed
gnutls[5]: REC[0x1a380b0]: Epoch #1 freed
(Thu Oct 27 11:20:40 2016) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [mof_user1]
removed from PAM initgroup cache
```
"""
See the full comment at
https://github.com/SSSD/sssd/pull/67#issuecomment-256591142