More progress! We seem to be past the stuff that has to do with missing files now. However, it looks like the patches didn't quite do the trick. Something is causing the LDAP entries not to get returned. In fact, it doesn't even seem to try and process anything past the first user found. Here are the log entries (sssd -d 10 -f): (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [fo_set_port_status] (4): Marking port 389 of server 'auth.mossholder.com' as 'working' (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [set_server_common_status] (4): Marking server 'auth.mossholder.com' as 'working' (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (6): calling ldap_search_ext with [(&(uid=*)(objectclass=posixAccount))][dc=mossho lder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [objectClass] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [uid] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [userPassword] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [uidNumber] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [gidNumber] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [gecos] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [homeDirectory] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [loginShell] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [krbPrincipalName] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [cn] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [modifyTimestamp] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowLastChange] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowMin] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowMax] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowWarning] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowInactive] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowExpire] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [shadowFlag] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [krbLastPwdChange] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [krbPasswordExpiration] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (7): Requesting attrs: [pwdAttribute] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_send] (8): ldap_search_ext called, msgid = 3 (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=mattcm,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=jenn,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=hayley,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=mythtv,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=darcy,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=robin,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=cadwyn,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=tim,ou=Guests,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=mattcm/admin,ou=Kerberos,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=test,ou=Guests,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_parse_entry] (9): OriginalDN: [uid=tester,ou=People,dc=mossholder,dc=com]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[0xbeccf0], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_generic_done] (6): Search result: Success(0), (null) (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_get_users_process] (6): Search for users, returned 11 results. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: sh[0xbe2930], connected[1], ops[(nil)], ldap[0xbe3360] (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_process_result] (8): Trace: ldap_result found nothing! (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [ldb] (9): start ldb transaction (nesting: 0) (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (9): Save user (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (7): Adding original DN [uid=mattcm,ou=People,dc=mossholder,dc=com] to attributes of [mattcm]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (7): Original memberOf is not available for [mattcm]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (7): User principal is not available for [mattcm]. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (9): Adding [shadowLastChange]=[12905] to user attributes. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (9): Adding [shadowMax]=[99999] to user attributes. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (9): Adding [shadowWarning]=[7] to user attributes. (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [sdap_save_user_send] (6): Storing info for user mattcm (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [ldb] (9): tevent: Added timed event "ltdb_callback": 0xc01850 (Sat Apr 9 17:27:42 2011) [sssd[be[MOSSHOLDER.COM]]] [ldb] (9): tevent: Added timed event "ltdb_timeout": 0xc01900 (Sat Apr 9 17:27:51 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): dbus conn: BCF3A0 (Sat Apr 9 17:27:51 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): Dispatching. (Sat Apr 9 17:27:51 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [ping] (Sat Apr 9 17:27:54 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): dbus conn: BDA3C0 (Sat Apr 9 17:27:54 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): Dispatching. (Sat Apr 9 17:27:54 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo] (Sat Apr 9 17:27:54 2011) [sssd[be[MOSSHOLDER.COM]]] [be_get_account_info] (4): Got request for [4097][1][name=*] (Sat Apr 9 17:27:54 2011) [sssd[be[MOSSHOLDER.COM]]] [acctinfo_callback] (4): Request processed. Returned 0,0,Success ### These three lines repeat for a while... (Sat Apr 9 17:28:01 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): dbus conn: BCF3A0 (Sat Apr 9 17:28:01 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): Dispatching. (Sat Apr 9 17:28:01 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [ping] ### ...and then... (Sat Apr 9 17:32:20 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [getAccountInfo] (Sat Apr 9 17:32:20 2011) [sssd[be[MOSSHOLDER.COM]]] [be_get_account_info] (4): Got request for [4097][1][name=*] (Sat Apr 9 17:32:20 2011) [sssd[be[MOSSHOLDER.COM]]] [acctinfo_callback] (4): Request processed. Returned 0,0,Success (Sat Apr 9 17:32:21 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): dbus conn: BCF3A0 (Sat Apr 9 17:32:21 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): Dispatching. (Sat Apr 9 17:32:21 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [ping] (Sat Apr 9 17:32:31 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): dbus conn: BCF3A0 (Sat Apr 9 17:32:31 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): Dispatching. (Sat Apr 9 17:32:31 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [ping] (Sat Apr 9 17:32:41 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): dbus conn: BCF3A0 (Sat Apr 9 17:32:41 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_dispatch] (9): Dispatching. (Sat Apr 9 17:32:41 2011) [sssd[be[MOSSHOLDER.COM]]] [sbus_message_handler] (9): Received SBUS method [ping] (Sat Apr 9 17:32:42 2011) [sssd[be[MOSSHOLDER.COM]]] [ldap_id_enumerate_timeout] (1): Enumeration timed out! Timeout too small? (300s)! (Sat Apr 9 17:32:42 2011) [sssd[be[MOSSHOLDER.COM]]] [ldap_id_enumerate_set_timer] (6): Scheduling next enumeration at 1302385062.842369 (Sat Apr 9 17:32:42 2011) [sssd[be[MOSSHOLDER.COM]]] [ldb] (9): cancel ldb transaction (nesting: 0) You can tell that the connectivity to the LDAP backend actually works, because it is able to enumerate the users, and also because it can tell that the memberOf and User Principal attributes are not available. It also seems to think the LDAP enumeration times out. --Matt On Sat, Apr 9, 2011 at 4:13 PM, Fabrice Coutadeur