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
_______________________________________________
sssd-devel mailing list -- sssd-devel@lists.fedorahosted.org
To unsubscribe send an email to sssd-devel-le...@lists.fedorahosted.org

Reply via email to