<div dir="ltr">Oops sorry<div>here is the logs</div><div><br></div><div><div>==> sssd_pam.log <==</div><div>(Tue Mar 17 14:33:23 2015) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn: 0x7fdea7263bd0</div><div>(Tue Mar 17 14:33:23 2015) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching.</div><div>(Tue Mar 17 14:33:23 2015) [sssd[pam]] [sbus_message_handler] (0x4000): Received SBUS method [ping]</div><div>(Tue Mar 17 14:33:23 2015) [sssd[pam]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit</div><div>(Tue Mar 17 14:33:23 2015) [sssd[pam]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [ping]</div><div><br></div><div>==> sssd.log <==</div><div>(Tue Mar 17 14:33:23 2015) [sssd] [ping_check] (0x0100): Service solaris.local replied to ping</div><div>(Tue Mar 17 14:33:23 2015) [sssd] [ping_check] (0x0100): Service pac replied to ping</div><div>(Tue Mar 17 14:33:23 2015) [sssd] [ping_check] (0x0100): Service sudo replied to ping</div><div>(Tue Mar 17 14:33:23 2015) [sssd] [ping_check] (0x0100): Service ssh replied to ping</div><div>(Tue Mar 17 14:33:23 2015) [sssd] [ping_check] (0x0100): Service pam replied to ping</div><div>(Tue Mar 17 14:33:23 2015) [sssd] [ping_check] (0x0100): Service nss replied to ping</div><div><br></div><div>==> sssd_nss.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [<a href="mailto:bobby@infra.com">bobby@infra.com</a>].</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name '<a href="mailto:bobby@infra.com">bobby@infra.com</a>' matched expression for domain '<a href="http://infra.com">infra.com</a>', user is bobby</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [bobby] from [<a href="http://infra.com">infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [<a href="mailto:bobby@infra.com">bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [<a href="http://infra.com">infra.com</a>][4097][1][name=bobby]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div><br></div><div>==> sssd_solaris.local.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x7f6b7d2a5140</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): Dispatching.</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_message_handler] (0x4000): Received SBUS method [getAccountInfo]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [getAccountInfo]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_get_account_info] (0x0200): Got request for [0x1001][1][name=bobby]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_get_account_info] (0x0100): Request processed. Returned 1,11,Fast reply - offline</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_req_set_domain] (0x0400): Changing request domain from [solaris.local] to [<a href="http://infra.com">infra.com</a>]</div><div><br></div><div>==> sssd_nss.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider</div><div>Error: 1, 11, Fast reply - offline</div><div>Will try to return what we have in cache</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [<a href="mailto:bobby@infra.com">bobby@infra.com</a>].</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name '<a href="mailto:bobby@infra.com">bobby@infra.com</a>' matched expression for domain '<a href="http://infra.com">infra.com</a>', user is bobby</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [bobby] from [<a href="http://infra.com">infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [<a href="mailto:bobby@infra.com">bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [<a href="http://infra.com">infra.com</a>][4097][1][name=bobby]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div><br></div><div>==> sssd_solaris.local.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x7f6b7d2a5140</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): Dispatching.</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_message_handler] (0x4000): Received SBUS method [getAccountInfo]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [getAccountInfo]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_get_account_info] (0x0200): Got request for [0x1001][1][name=bobby]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_get_account_info] (0x0100): Request processed. Returned 1,11,Fast reply - offline</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_req_set_domain] (0x0400): Changing request domain from [solaris.local] to [<a href="http://infra.com">infra.com</a>]</div><div><br></div><div>==> sssd_nss.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider</div><div>Error: 1, 11, Fast reply - offline</div><div>Will try to return what we have in cache</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [<a href="mailto:bobby@infra.com">bobby@infra.com</a>].</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name '<a href="mailto:bobby@infra.com">bobby@infra.com</a>' matched expression for domain '<a href="http://infra.com">infra.com</a>', user is bobby</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [bobby] from [<a href="http://infra.com">infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [<a href="mailto:bobby@infra.com">bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [<a href="http://infra.com">infra.com</a>][4097][1][name=bobby]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div><br></div><div>==> sssd_solaris.local.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x7f6b7d2a5140</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): Dispatching.</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_message_handler] (0x4000): Received SBUS method [getAccountInfo]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [getAccountInfo]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_get_account_info] (0x0200): Got request for [0x1001][1][name=bobby]</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_get_account_info] (0x0100): Request processed. Returned 1,11,Fast reply - offline</div><div>(Tue Mar 17 14:33:27 2015) [sssd[be[solaris.local]]] [be_req_set_domain] (0x0400): Changing request domain from [solaris.local] to [<a href="http://infra.com">infra.com</a>]</div><div><br></div><div>==> sssd_nss.log <==</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider</div><div>Error: 1, 11, Fast reply - offline</div><div>Will try to return what we have in cache</div><div>(Tue Mar 17 14:33:27 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [<a href="mailto:0x7f426adbfb50%3A1%3Abobby@infra.com">0x7f426adbfb50:1:bobby@infra.com</a>]</div><div><br></div><div>==> sssd.log <==</div><div>(Tue Mar 17 14:33:30 2015) [sssd] [message_type] (0x0200): netlink Message type: 25</div><div><br></div><div>==> sssd_solaris.local.log <==</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x7f6b7d266a70</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sbus_dispatch] (0x4000): Dispatching.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sbus_message_handler] (0x4000): Received SBUS method [resetOffline]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sbus_handler_got_caller_id] (0x4000): Received SBUS method [resetOffline]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_run_unconditional_online_cb] (0x0400): Running unconditional online callbacks.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [check_if_online] (0x2000): Trying to go back online!</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service '<a href="http://gc_infra.com">gc_infra.com</a>' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server '(no name)' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service '<a href="http://infra.com">infra.com</a>' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server '(no name)' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_server_common_status] (0x0100): Marking server 'kwtpocpbis01.solaris.local' as 'name not resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server 'kwtpocpbis01.solaris.local' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0400): Marking port 0 of duplicate server 'kwtpocpbis01.solaris.local' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_server_status] (0x1000): Status of server 'kwtpocpbis01.solaris.local' is 'name not resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_port_status] (0x1000): Port status of port 0 for server 'kwtpocpbis01.solaris.local' is 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_server_status] (0x1000): Status of server 'kwtpocpbis01.solaris.local' is 'name not resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [resolv_is_address] (0x4000): [kwtpocpbis01.solaris.local] does not look like an IP address</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [resolv_gethostbyname_step] (0x2000): Querying files</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'kwtpocpbis01.solaris.local' in files</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_server_common_status] (0x0100): Marking server 'kwtpocpbis01.solaris.local' as 'resolving name'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_server_common_status] (0x0100): Marking server 'kwtpocpbis01.solaris.local' as 'name resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_resolve_server_process] (0x1000): Saving the first resolved server</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_resolve_server_process] (0x0200): Found address for server kwtpocpbis01.solaris.local: [172.16.107.244] TTL 7200</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [ipa_resolve_callback] (0x0400): Constructed uri 'ldap://kwtpocpbis01.solaris.local'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sss_ldap_init_send] (0x4000): Using file descriptor [22] for LDAP connection.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://kwtpocpbis01.solaris.local:389/??base] with fd [22].</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_rootdse_send] (0x4000): Getting rootdse</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_print_server] (0x2000): Searching 172.16.107.244</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [domainControllerFunctionality]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_process_result] (0x2000): Trace: sh[0x7f6b7d2c6140], connected[1], ops[0x7f6b7d2bf090], ldap[0x7f6b7d265a00]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_entry] (0x1000): OriginalDN: [].</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [vendorName]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [vendorVersion]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [dataversion]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [netscapemdsuffix]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [changeLog]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [firstchangenumber]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [lastchangenumber]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [namingContexts]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedControl]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedExtension]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedLDAPVersion]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [supportedSASLMechanisms]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [defaultNamingContext]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_parse_range] (0x2000): No sub-attributes for [lastUSN]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_process_result] (0x2000): Trace: sh[0x7f6b7d2c6140], connected[1], ops[0x7f6b7d2bf090], ldap[0x7f6b7d265a00]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_rootdse_done] (0x2000): Got rootdse</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_rootdse_done] (0x2000): Skipping auto-detection of match rule</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_server_opts_from_rootdse] (0x4000): USN value: 84351 (int: 84351)</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_kinit_send] (0x0400): Attempting kinit (default, host/kwtpocpbis01.solaris.local, SOLARIS.LOCAL, 86400)</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_kinit_next_kdc] (0x1000): Resolving next KDC for service IPA</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_server_status] (0x1000): Status of server 'kwtpocpbis01.solaris.local' is 'name resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_server_status] (0x1000): Status of server 'kwtpocpbis01.solaris.local' is 'name resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_resolve_server_process] (0x1000): Saving the first resolved server</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_resolve_server_process] (0x0200): Found address for server kwtpocpbis01.solaris.local: [172.16.107.244] TTL 7200</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_kinit_kdc_resolved] (0x1000): KDC resolved, attempting to get TGT...</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [create_tgt_req_send_buffer] (0x0400): buffer size: 68</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [16425]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [child_handler_setup] (0x2000): Signal handler set up for pid [16425]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_tgt_child_timeout] (0x0400): Setting 6 seconds timeout for tgt child</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_process_result] (0x2000): Trace: sh[0x7f6b7d2c6140], connected[1], ops[(nil)], ldap[0x7f6b7d265a00]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [write_pipe_handler] (0x0400): All data has been sent!</div><div><br></div><div>==> ldap_child.log <==</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x0400): ldap_child started.</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x2000): context initialized</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): total buffer size: 68</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): realm_str size: 13</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): got realm_str: SOLARIS.LOCAL</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): princ_str size: 31</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): got princ_str: host/kwtpocpbis01.solaris.local</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): keytab_name size: 0</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x1000): lifetime: 86400</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [unpack_buffer] (0x0200): Will run as [0][0].</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [privileged_krb5_setup] (0x2000): Kerberos context initialized</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x2000): Kerberos context initialized</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [become_user] (0x0200): Trying to become user [0][0].</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [become_user] (0x0200): Already user [0].</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x2000): Running as [0][0].</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x2000): getting TGT sync</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [ldap_child_get_tgt_sync] (0x2000): got realm_name: [SOLARIS.LOCAL]</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [ldap_child_get_tgt_sync] (0x0100): Principal name is: [host/kwtpocpbis01.solaris.local@SOLARIS.LOCAL]</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [ldap_child_get_tgt_sync] (0x0100): Using keytab [MEMORY:/etc/krb5.keytab]</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [ldap_child_get_tgt_sync] (0x0100): Will canonicalize principals</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.524474: Getting initial credentials for host/kwtpocpbis01.solaris.local@SOLARIS.LOCAL</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.526526: Looked up etypes in keytab: rc4-hmac, des3-cbc-sha1, aes128-cts, aes256-cts</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.526597: Sending request (196 bytes) to SOLARIS.LOCAL</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.526803: Initiating TCP connection to stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.526892: Sending TCP request to stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.528813: Received answer (371 bytes) from stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.528846: Terminating TCP connection to stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.528895: Response was from master KDC</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.528937: Received error from KDC: -1765328359/Additional pre-authentication required</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.528977: Processing preauth types: 136, 19, 2, 133</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529008: Selected etype info: etype aes256-cts, salt "jUdxx&tm\3}.mh_[", params ""</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529023: Received cookie: MIT</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529056: Retrieving host/kwtpocpbis01.solaris.local@SOLARIS.LOCAL from MEMORY:/etc/krb5.keytab (vno 0, enctype aes256-cts) with result: 0/Success</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529088: AS key obtained for encrypted timestamp: aes256-cts/445E</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529148: Encrypted timestamp (for 1426592010.528836): plain 301AA011180F32303135303331373131333333305AA10502030811C4, encrypted 98625FC80F84BE6C287E596D254D3862CE1A8938418E92A91224BB349985496C8AD8D7161D736951B4C3907B8F4CB964577C28F6C3B81708</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529173: Preauth module encrypted_timestamp (2) (real) returned: 0/Success</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529195: Produced preauth for next request: 133, 2</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529216: Sending request (291 bytes) to SOLARIS.LOCAL</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529248: Initiating TCP connection to stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.529293: Sending TCP request to stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.535622: Received answer (201 bytes) from stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.535657: Terminating TCP connection to stream <a href="http://172.16.107.244:88">172.16.107.244:88</a></div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.535705: Response was from master KDC</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [sss_child_krb5_trace_cb] (0x4000): [16425] 1426592010.535733: Received error from KDC: -1765328353/Decrypt integrity check failed</div><div><br></div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [ldap_child_get_tgt_sync] (0x0010): Failed to init credentials: Decrypt integrity check failed</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x0020): ldap_child_get_tgt_sync failed.</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [prepare_response] (0x0400): Building response for result [-1765328353]</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [pack_buffer] (0x2000): response size: 50</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [pack_buffer] (0x1000): result [14] krberr [-1765328353] msgsize [30] msg [Decrypt integrity check failed]</div><div>(Tue Mar 17 14:33:30 2015) [[sssd[ldap_child[16425]]]] [main] (0x0400): ldap_child completed successfully</div><div><br></div><div>==> sssd_solaris.local.log <==</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [child_sig_handler] (0x1000): Waiting for child [16425].</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [child_sig_handler] (0x0100): child [16425] finished successfully.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [read_pipe_handler] (0x0400): EOF received, client finished</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_tgt_recv] (0x0400): Child responded: 14 [Decrypt integrity check failed], expired on [0]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_kinit_done] (0x0100): Could not get TGT: 14 [Bad address]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_cli_kinit_done] (0x0400): Cannot get a TGT: ret [1432158219](Authentication Failed)</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server 'kwtpocpbis01.solaris.local' as 'not working'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0400): Marking port 0 of duplicate server 'kwtpocpbis01.solaris.local' as 'not working'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_handle_release] (0x2000): Trace: sh[0x7f6b7d2c6140], connected[1], ops[(nil)], ldap[0x7f6b7d265a00], destructor_lock[0], release_memory[0]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [remove_connection_callback] (0x4000): Successfully removed connection callback.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [check_online_callback] (0x0100): Backend returned: (3, 0, <NULL>) [Internal Error (Success)]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service '<a href="http://gc_infra.com">gc_infra.com</a>' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server '(no name)' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service '<a href="http://infra.com">infra.com</a>' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server '(no name)' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_server_common_status] (0x0100): Marking server 'kwtpocpbis01.solaris.local' as 'name not resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server 'kwtpocpbis01.solaris.local' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_set_port_status] (0x0400): Marking port 0 of duplicate server 'kwtpocpbis01.solaris.local' as 'neutral'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_ptask_disable] (0x0400): Task [Check if online (periodic)]: disabling task</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_run_online_cb] (0x0080): Going online. Running callbacks.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [ipa_subdom_reset_timeouts_cb] (0x4000): Resetting last_refreshed and disabled_until.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_sudo_schedule_refresh] (0x0400): Full refresh scheduled at: 1426592010</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_sudo_full_refresh_send] (0x0400): Issuing a full refresh of sudo rules</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_id_op_connect_step] (0x4000): beginning to connect</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_server_status] (0x1000): Status of server 'kwtpocpbis01.solaris.local' is 'name not resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [get_server_status] (0x1000): Status of server 'kwtpocpbis01.solaris.local' is 'name not resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [resolv_is_address] (0x4000): [kwtpocpbis01.solaris.local] does not look like an IP address</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [resolv_gethostbyname_step] (0x2000): Querying files</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'kwtpocpbis01.solaris.local' in files</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_server_common_status] (0x0100): Marking server 'kwtpocpbis01.solaris.local' as 'resolving name'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [set_server_common_status] (0x0100): Marking server 'kwtpocpbis01.solaris.local' as 'name resolved'</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_resolve_server_process] (0x1000): Saving the first resolved server</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [be_resolve_server_process] (0x0200): Found address for server kwtpocpbis01.solaris.local: [172.16.107.244] TTL 7200</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sss_ldap_init_send] (0x4000): Using file descriptor [22] for LDAP connection.</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://kwtpocpbis01.solaris.local:389/??base] with fd [22].</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_rootdse_send] (0x4000): Getting rootdse</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_print_server] (0x2000): Searching 172.16.107.244</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [domainControllerFunctionality]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN]</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 1</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [ad_online_cb] (0x0400): The AD provider is online</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [sdap_id_op_connect_step] (0x4000): waiting for connection to complete</div><div>(Tue Mar 17 14:33:30 2015) [sssd[be[solaris.local]]] [delayed_online_authentication_callback] (0x0200): Backend is online, starting delayed online authentication.</div></div><div><br></div></div><div class="gmail_extra"><br><div class="gmail_quote">On Tue, Mar 17, 2015 at 2:23 PM, Ben .T.George <span dir="ltr"><<a href="mailto:bentech4you@gmail.com" target="_blank">bentech4you@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr">HI<div><br></div><div>i have changed like this:</div><div><br></div><div><div>[root@kwtpocpbis01 yum.repos.d]# more /etc/sssd/sssd.conf</div><span class=""><div>[domain/solaris.local]</div><div>cache_credentials = True</div><div>krb5_store_password_if_offline = True</div><div>ipa_domain = solaris.local</div><div>id_provider = ipa</div><div>auth_provider = ipa</div><div>access_provider = ipa</div><div>ipa_hostname = kwtpocpbis01.solaris.local</div><div>chpass_provider = ipa</div><div>ipa_server = kwtpocpbis01.solaris.local</div><div>ipa_server_mode = True</div><div>ldap_tls_cacert = /etc/ipa/ca.crt</div></span><div>debug_level = 10</div><span class=""><div>[sssd]</div><div>services = nss, sudo, pam, ssh</div><div>config_file_version = 2</div></span><div>debug_level = 5</div><div>domains = solaris.local</div><span class=""><div>[nss]</div><div>homedir_substring = /home</div><div>debug_level = 6</div><div><br></div><div>[pam]</div></span><div>debug_level = 10</div><div>[sudo]</div><div>debug_level = 5</div><div>[autofs]</div><div>debug_level = 5</div><div>[ssh]</div><div>debug_level = 5</div><div>[pac]</div><div>debug_level = 5</div><div>[ifp]</div></div><div><br></div><div><br></div><div>but sssd.log looks same.</div><div><br></div><div><div>(Tue Mar 17 14:23:13 2015) [sssd] [ping_check] (0x0100): Service pam replied to ping</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [service_send_ping] (0x0100): Pinging solaris.local</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [service_send_ping] (0x0100): Pinging nss</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [service_send_ping] (0x0100): Pinging sudo</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [service_send_ping] (0x0100): Pinging pam</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [service_send_ping] (0x0100): Pinging ssh</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [service_send_ping] (0x0100): Pinging pac</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [ping_check] (0x0100): Service sudo replied to ping</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [ping_check] (0x0100): Service ssh replied to ping</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [ping_check] (0x0100): Service pam replied to ping</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [ping_check] (0x0100): Service solaris.local replied to ping</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [ping_check] (0x0100): Service pac replied to ping</div><div>(Tue Mar 17 14:23:23 2015) [sssd] [ping_check] (0x0100): Service nss replied to ping</div></div></div><div class="HOEnZb"><div class="h5"><div class="gmail_extra"><br><div class="gmail_quote">On Tue, Mar 17, 2015 at 1:27 PM, Jakub Hrozek <span dir="ltr"><<a href="mailto:jhrozek@redhat.com" target="_blank">jhrozek@redhat.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><span>On Tue, Mar 17, 2015 at 12:57:27PM +0300, Ben .T.George wrote:<br>
> HI<br>
><br>
</span><span>> i have enabled debug<br>
><br>
> here is my sssd.conf<br>
><br>
> [root@kwtpocpbis01 ~]# cat /etc/sssd/sssd.conf<br>
> [domain/solaris.local]<br>
><br>
> cache_credentials = True<br>
> krb5_store_password_if_offline = True<br>
> ipa_domain = solaris.local<br>
> id_provider = ipa<br>
> auth_provider = ipa<br>
> access_provider = ipa<br>
> ipa_hostname = kwtpocpbis01.solaris.local<br>
> chpass_provider = ipa<br>
> ipa_server = kwtpocpbis01.solaris.local<br>
> ipa_server_mode = True<br>
> ldap_tls_cacert = /etc/ipa/ca.crt<br>
<br>
</span>Please also add debug_level to this section, not just [sssd] and [nss]<br>
<div><div><br>
<br>
> [sssd]<br>
> services = nss, sudo, pam, ssh<br>
> config_file_version = 2<br>
><br>
> domains = solaris.local<br>
> debug_level = 6<br>
> [nss]<br>
> homedir_substring = /home<br>
> debug_level = 6<br>
><br>
> [pam]<br>
><br>
> [sudo]<br>
><br>
> [autofs]<br>
><br>
> [ssh]<br>
><br>
> [pac]<br>
><br>
> [ifp]<br>
</div></div></blockquote></div><br></div>
</div></div></blockquote></div><br></div>