I can `*id axisys*` and it *works* fine with ubuntu xenial running *sssd version 1.13.4* but *failing* on ubuntu trusty running *sssd version 1.11.8*
I have the same *sssd.conf* and *nsswitch.conf* on both servers and I also provided the *sssd_LDAP.log* and I noticed there is no *nss_cmd_getgrgid_search* on trusty / sssd 1.11.8 log *sssd.conf* ======== [sssd] domains = LDAP services = nss, pam, sudo config_file_version = 2 [nss] [pam] [sudo] [domain/LDAP] debug_level = 9 id_provider = ldap ldap_schema = rfc2307bis auth_provider = ldap access_provider = ldap ldap_default_bind_dn = uid=nattacp,ou=people,dc=mnet,dc=qintra,dc=com ldap_default_authtok = secret ldap_access_filter = objectClass=mnetperson ldap_uri = ldaps://192.168.0.34:1636 ldap_search_base = ou=People,dc=mnet,dc=qintra,dc=com ldap_user_object_class = mnetPerson ldap_group_object_class = inetOrgPerson ldap_default_authtok_type = password ldap_tls_reqcert = allow ldap_tls_cacert = /etc/ssl/certs/ctl.cer ldap_user_uid_number = mnetid ldap_user_gid_number = mnetid ldap_group_gid_number = mnetid cache_credentials = True *nsswitch.conf* =========== passwd: compat sss group: compat sss shadow: compat sss hosts: files dns networks: files protocols: db files services: db files sss ethers: db files rpc: db files netgroup: nis sss sudoers: files sss logs from the command `*id axisys*' ubuntu trusty *sssd_LDAP.log* (*fails*) =========================== (Wed Jun 6 19:12:22 2018) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[1000] egid[1000] pid[6291]. (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected! (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1]. (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1]. (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [axisys]. (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'axisys' matched without domain, user is axisys (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [axisys] from [<ALL>] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/LDAP/axisys] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [axisys@LDAP] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1e0e620 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1e0e750 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Running timer event 0x1e0e620 "ltdb_callback" (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x1e0e750 "ltdb_timeout" (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x1e0e620 "ltdb_callback" (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x417c90:1:axisys@LDAP] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [LDAP][4097][1][name=axisys] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x1e05a30 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x417c90:1:axisys@LDAP] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sbus_dispatch] (0x4000): dbus conn: 0x19204f0 (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sbus_message_handler] (0x4000): Received SBUS method [getAccountInfo] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [getAccountInfo] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [be_get_account_info] (0x0100): Got request for [4097][1][name=axisys] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [be_req_set_domain] (0x0400): Changing request domain from [LDAP] to [LDAP] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [ou=People,dc=mnet,dc=qintra,dc=com] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=axisys)(objectclass=mnetPerson)(uid=*)(&(mnetid=*)(!(mnetid=0))))][ou=People,dc=mnet,dc=qintra,dc=com]. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [mnetid] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [mnetid] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 4 (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1928340], connected[1], ops[0x19563c0], ldap[0x191baa0] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_ENTRY] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_parse_entry] (0x4000): OriginalDN: [uid=axisys,ou=People,dc=mnet,dc=qintra,dc=com]. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [mnetid] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1928340], connected[1], ops[0x19563c0], ldap[0x191baa0] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no errmsg set (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_search_user_process] (0x0400): Search for users, returned 1 results. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_search_user_process] (0x4000): Retrieved total 1 users (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_save_user] (0x0400): Save user (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_attrs_get_sid_str] (0x0080): No [objectSID] attribute while id-mapping. [0][Success] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_save_user] (0x4000): objectSID: not available for group [(null)]. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_primary_name] (0x0400): Processing object axisys (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_save_user] (0x0400): Processing user axisys (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_save_user] (0x0020): no gid provided for [axisys] in domain [LDAP]. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_save_user] (0x0020): Failed to save user [axisys] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_save_users] (0x0040): Failed to store user 0. Ignoring. (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [ldb] (0x4000): commit ldb transaction (nesting: 0) (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_get_users_done] (0x4000): Saving 1 Users - Done (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_id_op_done] (0x4000): releasing operation connection (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x1e05a30 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn: 0x1e08760 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/LDAP/axisys] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [axisys@LDAP] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1e13c40 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1e13d70 (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Running timer event 0x1e13c40 "ltdb_callback" (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x1e13d70 "ltdb_timeout" (Wed Jun 6 19:12:22 2018) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x1e13c40 "ltdb_callback" (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/LDAP/axisys] to negative cache (Wed Jun 6 19:12:22 2018) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0040): No results for getpwnam call (Wed Jun 6 19:12:22 2018) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x417c90:1:axisys@LDAP] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[nss]] [client_recv] (0x0200): Client disconnected! (Wed Jun 6 19:12:22 2018) [sssd[nss]] [client_destructor] (0x2000): Terminated client [0x1e09540][19] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1928340], connected[1], ops[(nil)], ldap[0x191baa0] (Wed Jun 6 19:12:22 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Wed Jun 6 19:12:23 2018) [sssd] [service_send_ping] (0x0100): Pinging LDAP (Wed Jun 6 19:12:23 2018) [sssd] [sbus_add_timeout] (0x2000): 0x2171a20 (Wed Jun 6 19:12:23 2018) [sssd] [service_send_ping] (0x0100): Pinging nss (Wed Jun 6 19:12:23 2018) [sssd] [sbus_add_timeout] (0x2000): 0x2177990 (Wed Jun 6 19:12:23 2018) [sssd] [service_send_ping] (0x0100): Pinging pam (Wed Jun 6 19:12:23 2018) [sssd] [sbus_add_timeout] (0x2000): 0x2177de0 (Wed Jun 6 19:12:23 2018) [sssd] [service_send_ping] (0x0100): (Wed Jun 6 19:12:23 2018) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x1df85b0 (Wed Jun 6 19:12:23 2018) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd[pam]] [sbus_message_handler] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd[pam]] [sbus_get_sender_id_send] (0x2000): Pinging sudo (Wed Jun 6 19:12:23 2018) [sssd] [sbus_add_timeout] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:12:23 2018) [sssd[pam]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [ping] 0x217d310 (Wed Jun 6 19:12:23 2018) [sssd[sudo]] [sbus_dispatch] (0x4000): dbus conn: 0x12245b0 (Wed Jun 6 19:12:23 2018) [sssd[sudo]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd[sudo]] [sbus_message_handler] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd[sudo]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:12:23 2018) [sssd[sudo]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x217d310 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x217bca0 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd] [ping_check] (0x0100): Service sudo replied to ping (Wed Jun 6 19:12:23 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x2177de0 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x2178100 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd] [ping_check] (0x0100): Service pam replied to ping (Wed Jun 6 19:12:23 2018) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn: 0x1e055b0 (Wed Jun 6 19:12:23 2018) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd[nss]] [sbus_message_handler] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd[nss]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:12:23 2018) [sssd[nss]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x2177990 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x21782e0 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd] [ping_check] (0x0100): Service nss replied to ping (Wed Jun 6 19:12:23 2018) [sssd[be[LDAP]]] [sbus_dispatch] (0x4000): dbus conn: 0x190f130 (Wed Jun 6 19:12:23 2018) [sssd[be[LDAP]]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd[be[LDAP]]] [sbus_message_handler] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd[be[LDAP]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:12:23 2018) [sssd[be[LDAP]]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [ping] (Wed Jun 6 19:12:23 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x2171a20 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x2172000 (Wed Jun 6 19:12:23 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:12:23 2018) [sssd] [ping_check] (0x0100): Service LDAP replied to ping ubuntu xenial *sssd_LDAP.log* (*works*!) ============================== (Wed Jun 6 19:09:40 2018) [sssd] [services_startup_timeout] (0x0400): Handling timeout (Wed Jun 6 19:09:44 2018) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[1000] egid[1000] pid[4418]. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected! (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1]. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1]. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17][SSS_NSS_GETPWNAM] with input [axisys]. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'axisys' matched without domain, user is axisys (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [axisys] from [<ALL>] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/LDAP/axisys] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [axisys@LDAP] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x119a170 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x119a230 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Running timer event 0x119a170 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x119a230 "ltdb_timeout" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x119a170 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [get_dp_name_and_id] (0x0400): Not a LOCAL view, continuing with provided values. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning.. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [axisys@LDAP] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [expand_homedir_template] (0x0020): Missing template. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getbyid] (0x0400): Running command [34][SSS_NSS_GETGRGID] with id [408462]. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/GID/408462] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0100): Requesting info for [408462@LDAP] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x11953b0 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1195470 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Running timer event 0x11953b0 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x1195470 "ltdb_timeout" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x11953b0 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [get_dp_name_and_id] (0x0400): Not a LOCAL view, continuing with provided values. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning.. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [*nss_cmd_getgrgid_search*] (0x0400): Returning info for gid [408462@LDAP] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0080): No matching domain found for [408462] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/GROUP/LDAP/Asif Iqbal] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [38][SSS_NSS_INITGR] with input [axisys]. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'axisys' matched without domain, user is axisys (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [axisys] from [<ALL>] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/LDAP/axisys] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_initgroups_search] (0x0100): Requesting info for [axisys@LDAP] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x119a170 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x119a230 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Running timer event 0x119a170 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x119a230 "ltdb_timeout" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x119a170 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x118f740 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x119bfb0 (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Running timer event 0x118f740 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x119bfb0 "ltdb_timeout" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x118f740 "ltdb_callback" (Wed Jun 6 19:09:44 2018) [sssd[nss]] [get_dp_name_and_id] (0x0400): Not a LOCAL view, continuing with provided values. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning.. (Wed Jun 6 19:09:44 2018) [sssd[nss]] [nss_cmd_initgroups_search] (0x0400): Initgroups for [axisys@LDAP] completed (Wed Jun 6 19:09:44 2018) [sssd[nss]] [calc_flat_name] (0x0080): Flat name requested but domain has noflat name set, falling back to domain name (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x118f5c0][21] (Wed Jun 6 19:09:44 2018) [sssd[nss]] [client_recv] (0x0200): Client disconnected! (Wed Jun 6 19:09:44 2018) [sssd[nss]] [client_destructor] (0x2000): Terminated client [0x118f5c0][21] (Wed Jun 6 19:09:45 2018) [sssd] [service_send_ping] (0x2000): Pinging LDAP (Wed Jun 6 19:09:45 2018) [sssd] [sbus_add_timeout] (0x2000): 0x1df8380 (Wed Jun 6 19:09:45 2018) [sssd] [service_send_ping] (0x2000): Pinging nss (Wed Jun 6 19:09:45 2018) [sssd] [sbus_add_timeout] (0x2000): 0x1df26c0 (Wed Jun 6 19:09:45 2018) [sssd] [service_send_ping] (0x2000): Pinging pam (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sbus_dispatch] (0x4000): (Wed Jun 6 19:09:45 2018) [sssd] [sbus_add_timeout] (0x2000): 0x1df7140 dbus conn: 0x1c29d50 (Wed Jun 6 19:09:45 2018) [sssd] [service_send_ping] (0x2000): Pinging sudo (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd] [sbus_add_timeout] (0x2000): 0x1df2ef0 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:09:45 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x1df8380 (Wed Jun 6 19:09:45 2018) [sssd[nss]] [sbus_dispatch] (0x4000): (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x1df1b10 dbus conn: 0x118a260 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd] [ping_check] (0x2000): Service LDAP replied to ping (Wed Jun 6 19:09:45 2018) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd[nss]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service (Wed Jun 6 19:09:45 2018) [sssd[sudo]] [sbus_dispatch] (0x4000): dbus conn: 0x12f1270 (Wed Jun 6 19:09:45 2018) [sssd[sudo]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd[sudo]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service (Wed Jun 6 19:09:45 2018) [sssd[nss]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:09:45 2018) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x2527260 (Wed Jun 6 19:09:45 2018) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd[pam]] [sbus_message_handler] (0x2000): (Wed Jun 6 19:09:45 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x1df26c0 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x1df76b0 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd] [ping_check] (0x2000): Service nss replied to ping Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service (Wed Jun 6 19:09:45 2018) [sssd[pam]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:09:45 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x1df7140 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x1df90a0 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd] [ping_check] (0x2000): Service pam replied to ping (Wed Jun 6 19:09:45 2018) [sssd[sudo]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Wed Jun 6 19:09:45 2018) [sssd] [sbus_remove_timeout] (0x2000): 0x1df2ef0 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): dbus conn: 0x1dfb540 (Wed Jun 6 19:09:45 2018) [sssd] [sbus_dispatch] (0x4000): Dispatching. (Wed Jun 6 19:09:45 2018) [sssd] [ping_check] (0x2000): Service sudo replied to ping (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_execute] (0x0400): Task [SUDO Full Refresh]: executing task, timeout 21600 seconds (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_full_refresh_send] (0x0400): Issuing a full refresh of sudo rules (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_id_op_connect_step] (0x4000): beginning to connect (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [get_server_status] (0x1000): Status of server '192.168.0.34' is 'name not resolved' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [get_port_status] (0x1000): Port status of port 1636 for server '192.168.0.34' is 'neutral' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [get_server_status] (0x1000): Status of server '192.168.0.34' is 'name not resolved' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [set_server_common_status] (0x0100): Marking server '192.168.0.34' as 'resolving name' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [set_server_common_status] (0x0100): Marking server '192.168.0.34' as 'name resolved' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_resolve_server_process] (0x0200): Found address for server 192.168.0.34: [192.168.0.34] TTL 7200 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_uri_callback] (0x0400): Constructed uri 'ldaps://192.168.0.34:1636' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sss_ldap_init_send] (0x4000): Using file descriptor [21] for LDAP connection. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldaps:// 192.168.0.34:1636/??base] with fd [21]. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_rootdse_send] (0x4000): Getting rootdse (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_print_server] (0x2000): Searching 192.168.0.34 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][]. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [domainControllerFunctionality] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_op_add] (0x2000): New operation 1 timeout 6 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[0x1c60140], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_ENTRY] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_entry] (0x1000): OriginalDN: []. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [namingContexts] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedExtension] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedControl] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedSASLMechanisms] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedLDAPVersion] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [vendorName] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [vendorVersion] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [dataversion] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [netscapemdsuffix] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedSSLCiphers] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [enabledSSLCiphers] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_parse_range] (0x2000): No sub-attributes for [attributeencryptionciphers] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[0x1c60140], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_op_destructor] (0x2000): Operation 1 finished (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_rootdse_done] (0x2000): Got rootdse (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_rootdse_done] (0x2000): Skipping auto-detection of match rule (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_server_opts_from_rootdse] (0x0200): No known USN scheme is supported by this server! (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_server_opts_from_rootdse] (0x0200): Will use modification timestamp as usn! (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_cli_auth_step] (0x1000): the connection will expire at 1528313085 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [simple_bind_send] (0x0100): Executing simple bind as: uid=nattacp,ou=people,dc=mnet,dc=qintra,dc=com (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [simple_bind_send] (0x2000): ldap simple bind sent, msgid = 2 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_op_add] (0x2000): New operation 2 timeout 6 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[0x1c6b080], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[0x1c6b080], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_BIND] ... (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [simple_bind_done] (0x1000): Password Policy Response: expire [-1] grace [-1] error [No error]. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [simple_bind_done] (0x0400): Bind result: Success(0), no errmsg set (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_op_destructor] (0x2000): Operation 2 finished (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [_be_fo_set_port_status] (0x8000): Setting status: PORT_WORKING. Called from: ../src/providers/ldap/sdap_async_connection.c: sdap_cli_connect_recv: 2052 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [fo_set_port_status] (0x0100): Marking port 1636 of server '192.168.0.34' as 'working' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [set_server_common_status] (0x0100): Marking server '192.168.0.34' as 'working' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [fo_set_port_status] (0x0400): Marking port 1636 of duplicate server '192.168.0.34' as 'working' (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_id_op_connect_done] (0x4000): notify connected to op #1 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_refresh_connect_done] (0x0400): SUDO LDAP connection successful (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [check_ipv4_addr] (0x0200): Loopback IPv4 address 127.0.0.1 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: 10.0.2.15 in network 10.0.2.0/24 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: 10.193.69.239 in network 10.193.68.0/23 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [check_ipv6_addr] (0x0200): Loopback IPv6 address ::1 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: fe80::e9:3aff:fe83:3ee2 in network fe80::/64 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_get_ip_addresses] (0x2000): Found IP address: fe80::a00:27ff:fe9e:c1bf in network fe80::/64 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_get_hostnames_send] (0x2000): Found hostname: ubuntu-xenial (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [resolv_is_address] (0x4000): [ubuntu-xenial] does not look like an IP address (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [resolv_gethostbyname_step] (0x2000): Querying files (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'ubuntu-xenial' in files (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_id_op_connect_done] (0x4000): caching successful connection after 1 notifies (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_run_unconditional_online_cb] (0x4000): List of unconditional online callbacks is empty, nothing to do. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_run_online_cb] (0x0080): Going online. Running callbacks. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_get_hostnames_done] (0x2000): Found fqdn: ubuntu-xenial (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_load_sudoers_send] (0x0400): About to fetch sudo rules (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_search_bases_next_base] (0x0400): Issuing LDAP lookup with base [ou=People,dc=mnet,dc=qintra,dc=com] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_print_server] (0x2000): Searching 192.168.0.34 .... (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoCommand] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoHost] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoUser] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOption] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAs] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsUser] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoRunAsGroup] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotBefore] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoNotAfter] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sudoOrder] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 3 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_op_add] (0x2000): New operation 3 timeout 6 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[0x1c66150], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_online_cb] (0x0400): Back end is online (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_enable] (0x0080): Task [SUDO Smart Refresh]: already enabled (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_online_cb] (0x0400): Back end is online (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_enable] (0x0080): Task [SUDO Full Refresh]: already enabled (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[0x1c66150], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_op_destructor] (0x2000): Operation 3 finished (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_search_bases_done] (0x0400): Receiving data from base [ou=People,dc=mnet,dc=qintra,dc=com] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_load_sudoers_done] (0x0040): Received 0 sudo rules (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_id_op_done] (0x4000): releasing operation connection (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_refresh_done] (0x0400): Received 0 rules (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): start ldb transaction (nesting: 1) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sysdb_sudo_purge_all] (0x0400): Deleting all cached sudo rules (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): start ldb transaction (nesting: 2) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1c68a20 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1c68ae0 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Running timer event 0x1c68a20 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Destroying timer event 0x1c68ae0 "ltdb_timeout" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Ending timer event 0x1c68a20 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sysdb_delete_recursive] (0x4000): Found [1] items to delete. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sysdb_delete_recursive] (0x4000): Trying to delete [cn=sudorules,cn=custom,cn=LDAP,cn=sysdb]. (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): start ldb transaction (nesting: 3) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1c67050 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1c67110 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Running timer event 0x1c67050 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1c67290 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1c677e0 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Destroying timer event 0x1c67110 "ltdb_timeout" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Ending timer event 0x1c67050 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Running timer event 0x1c67290 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Destroying timer event 0x1c677e0 "ltdb_timeout" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Ending timer event 0x1c67290 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): commit ldb transaction (nesting: 3) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): commit ldb transaction (nesting: 2) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): commit ldb transaction (nesting: 1) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): commit ldb transaction (nesting: 0) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_refresh_done] (0x0400): Sudoers is successfuly stored in cache (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_set_usn] (0x0200): SUDO higher USN value: [1] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1c4fd20 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1c4f6d0 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Running timer event 0x1c4fd20 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Destroying timer event 0x1c4f6d0 "ltdb_timeout" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Ending timer event 0x1c4fd20 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1c6ddc0 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1c6de80 (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Running timer event 0x1c6ddc0 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Destroying timer event 0x1c6de80 "ltdb_timeout" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): Ending timer event 0x1c6ddc0 "ltdb_callback" (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [ldb] (0x4000): commit ldb transaction (nesting: 0) (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_full_refresh_done] (0x0400): Successful full refresh of sudo rules (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_done] (0x0400): Task [SUDO Full Refresh]: finished successfully (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [be_ptask_schedule] (0x0400): Task [SUDO Full Refresh]: scheduling task 21600 seconds from last execution time [1528333785] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: sh[0x1c505c0], connected[1], ops[(nil)], ldap[0x1c40820] (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Wed Jun 6 19:09:45 2018) [sssd[be[LDAP]]] [sdap_sudo_online_cb] (0x0400): We are back online. SUDO host information will be renewed on next refresh. -- Asif Iqbal PGP Key: 0xE62693C5 KeyServer: pgp.mit.edu A: Because it messes up the order in which people normally read text. Q: Why is top-posting such a bad thing?
_______________________________________________ sssd-users mailing list -- sssd-users@lists.fedorahosted.org To unsubscribe send an email to sssd-users-le...@lists.fedorahosted.org Fedora Code of Conduct: https://getfedora.org/code-of-conduct.html List Guidelines: https://fedoraproject.org/wiki/Mailing_list_guidelines List Archives: https://lists.fedoraproject.org/archives/list/sssd-users@lists.fedorahosted.org/message/ONRDOTUHOTCPXE56IWMT4PYFZDBGGJSJ/