Hello,
For the ldap.conf change, I don't think it matters where this line is
added as long as it is uncommented. On my system it is at the end of the
file:
SASL_NOCANON on
For the krb5.conf, yes it should be under the [libdefaults] section
[libdefaults]
rdns = false
I am assuming this is SSSD direct integration to AD, so yes these
changes are on the Linux systems(clients).
I hope it helps.
Kind regards,
Justin Stephenson
On 01/27/2017 10:22 AM, Thomas Beaudry wrote:
> Hi Justin,
>
> This is what I have:
>
> root@perf-imglab08:~# cat /etc/ldap/ldap.conf
> #
> # LDAP Defaults
> #
>
> # See ldap.conf(5) for details
> # This file should be world readable but not world writable.
>
> #BASE dc=example,dc=com
> #URI
ldap://ldap.example.com ldap://ldap-master.example.com:666
>
> #SIZELIMIT 12
> #TIMELIMIT 15
> #DEREF never
>
> # TLS certificates (needed for GnuTLS)
> TLS_CACERT /etc/ssl/certs/ca-certificates.crt
>
>
> Note that this is not the server (the servers are windows DC). Am i supposed to add
- SASL_NOCANON on the client side? Do i just add "SASL_NOCANON on" under
the #DEREF line?
>
>
> As for rdns=false in /etc/krb5.conf that is for the client as well right? Do i just
add it to the [libdefaults] section?
>
>
> Sorry for so many questions, I just want to make sure I get it right!
> Thomas
>
>
> ________________________________________
> From: Justin Stephenson <jstephen(a)redhat.com>
> Sent: Friday, January 27, 2017 9:19 AM
> To: End-user discussions about the System Security Services Daemon
> Subject: [SSSD-users] Re: SSSD - user id mapping
>
> Hello,
>
> The problem appears to be here:
>
> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send]
> (0x0100): Executing sasl bind mech: gssapi, user: PERF-IMGLAB08$
> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_sasl_log]
> (0x0040): SASL: GSSAPI Error: An invalid name was supplied (Success)
> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send]
> (0x0020): ldap_sasl_bind failed (-2)[Local error]
> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send]
> (0x0080): Extended failure message: [SASL(-1): generic failure: GSSAPI
> Error: An invalid name was supplied (Success)]
> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
> [_be_fo_set_port_status] (0x8000): Setting status: PORT_NOT_WORKING.
> Called from: ../src/providers/ldap/sdap_async_connection.c:
> sdap_cli_connect_recv: 2039
> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
> (0x0100): Marking port 389 of server 'int-con-dc-5.concordia.ca' as 'not
> working'
>
> SSSD calls into the SASL library and attempts a SASL bind but this fails
> as shown above.
>
> I suspect there may be a problem with reverse dns, can you verify the
> following are set:
>
> - SASL_NOCANON on in /etc/openldap/ldap.conf
> - rdns=false in /etc/krb5.conf
>
> Then try testing again to see if that helps. If it the same issue, you
> may need to use tcpdump and look at a packet capture to determine what
> is causing the SASL bind to fail.
>
> Kind regards,
> Justin Stephenson
>
> On 01/26/2017 04:35 PM, Thomas Beaudry wrote:
>> Hi Justin,
>>
>> Thanks for answering. Here is an instance where it has a mark_offline event (and
everything that happens before it with the same time stamp.
>>
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_async_sys_connect_timeout] (0x0100): The LDAP connection timed out
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sss_ldap_init_sys_connect_done] (0x0020): sdap_async_sys_connect request failed: [110]:
Connection timed out.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sss_ldap_init_state_destructor] (0x0400): closing socket [24]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_sys_connect_done]
(0x0020): sdap_async_connect_call request failed: [110]: Connection timed out.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_handle_release]
(0x2000): Trace: sh[0x13d9300], connected[0], ops[(nil)], ldap[(nil)], destructor_lock[0],
release_memory[0]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [_be_fo_set_port_status]
(0x8000): Setting status: PORT_NOT_WORKING. Called from:
../src/providers/ldap/sdap_async_connection.c: sdap_cli_connect_done: 1567
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
(0x0100): Marking port 3268 of server 'charity.encs.concordia.ca' as 'not
working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
(0x0400): Marking port 3268 of duplicate server 'charity.encs.concordia.ca' as
'not working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'AD_GC'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-4.concordia.ca' is 'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_port_status] (0x1000):
Port status of port 3268 for server 'int-con-dc-4.concordia.ca' is
'neutral'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolve_srv_send] (0x0200):
The status of SRV lookup is resolved
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-4.concordia.ca' is 'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x0200): Found address for server int-con-dc-4.concordia.ca: [132.205.123.31] TTL 86400
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_resolve_callback]
(0x0100): Constructed uri 'ldap://int-con-dc-4.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_resolve_callback]
(0x0100): Constructed GC uri 'ldap://int-con-dc-4.concordia.ca:3268'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sss_ldap_init_send]
(0x4000): Using file descriptor [24] for LDAP connection.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sss_ldap_init_send]
(0x0400): Setting 6 seconds timeout for connecting
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to
[ldap://int-con-dc-4.concordia.ca:3268/??base] with fd [24].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_rootdse_send]
(0x4000): Getting rootdse
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_print_server] (0x2000):
Searching 132.205.123.31
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x0400): calling ldap_search_ext with [(objectclass=*)][].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [*]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [altServer]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [namingContexts]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedControl]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedExtension]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedFeatures]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedLDAPVersion]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedSASLMechanisms]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [domainControllerFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [defaultNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [lastUSN]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [highestCommittedUSN]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x2000): ldap_search_ext called, msgid = 1
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_add] (0x2000): New
operation 1 timeout 6
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[0x13e51e0], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: ldap_result found nothing!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[0x13e51e0], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_entry] (0x1000):
OriginalDN: [].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [currentTime]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [subschemaSubentry]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [dsServiceName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [namingContexts]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [defaultNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [schemaNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [configurationNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [rootDomainNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedControl]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedLDAPVersion]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedLDAPPolicies]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [highestCommittedUSN]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedSASLMechanisms]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [dnsHostName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [ldapServiceName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [serverName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedCapabilities]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [isSynchronized]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [isGlobalCatalogReady]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedExtension]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [domainFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [forestFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [domainControllerFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[0x13e51e0], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_destructor]
(0x2000): Operation 1 finished
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_rootdse_done]
(0x2000): Got rootdse
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_rootdse_done]
(0x2000): Skipping auto-detection of match rule
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_server_opts_from_rootdse] (0x4000): USN value: 206406385 (int: 206406385)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [4]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_kinit_send] (0x0400):
Attempting kinit (default, PERF-IMGLAB08$, CONCORDIA.CA, 86400)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_kinit_next_kdc]
(0x1000): Resolving next KDC for service AD
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-4.concordia.ca' is 'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_port_status] (0x1000):
Port status of port 389 for server 'int-con-dc-4.concordia.ca' is
'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolve_srv_send] (0x0200):
The status of SRV lookup is expired
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [collapse_srv_lookup]
(0x0100): Need to refresh SRV lookup for domain
Default-First-Site-Name._sites.concordia.ca
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_srv_plugin_send]
(0x0400): About to find domain controllers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_get_dc_servers_send]
(0x0400): Looking up domain controllers in domain concordia.ca
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will
use DNS discovery domain 'concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_getsrv_send]
(0x0100): Trying to resolve SRV record of '_ldap._tcp.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[(nil)], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: ldap_result found nothing!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_request_timeout]
(0x2000): Scheduling a timeout of 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_timeout_watcher]
(0x2000): Scheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unschedule_timeout_watcher]
(0x4000): Unscheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_getsrv_done]
(0x1000): Using TTL [600]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [request_watch_destructor]
(0x0400): Deleting request watch
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_srv_done]
(0x0400): Got answer. Processing...
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_srv_done]
(0x0400): Got 5 servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_get_dc_servers_done]
(0x0400): Found 5 domain controllers in domain concordia.ca
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_srv_plugin_dcs_done]
(0x0400): About to locate suitable site
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_connect_host_send]
(0x0400): Resolving host int-con-dc-2.concordia.ca
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_is_address] (0x4000):
[int-con-dc-2.concordia.ca] does not look like an IP address
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_step]
(0x2000): Querying files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of
'int-con-dc-2.concordia.ca' in files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_step]
(0x2000): Querying files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of
'int-con-dc-2.concordia.ca' in files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_next]
(0x0200): No more address families to retry
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_step]
(0x2000): Querying DNS
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of
'int-con-dc-2.concordia.ca' in DNS
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_request_timeout]
(0x2000): Scheduling a timeout of 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_timeout_watcher]
(0x2000): Scheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unschedule_timeout_watcher]
(0x4000): Unscheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [request_watch_destructor]
(0x0400): Deleting request watch
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_connect_host_resolv_done] (0x0400): Connecting to
ldap://int-con-dc-2.concordia.ca:389
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sss_ldap_init_send]
(0x4000): Using file descriptor [27] for LDAP connection.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sss_ldap_init_send]
(0x0400): Setting 6 seconds timeout for connecting
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to
[ldap://int-con-dc-2.concordia.ca:389/??base] with fd [27].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_connect_host_done]
(0x0400): Successful connection to ldap://int-con-dc-2.concordia.ca:389
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_print_server] (0x2000):
Searching 132.205.123.22
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x0400): calling ldap_search_ext with
[(&(DnsDomain=concordia.ca)(NtVer=\14\00\00\00))][].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [netlogon]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x2000): ldap_search_ext called, msgid = 1
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_add] (0x2000): New
operation 1 timeout 6
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e4e00], connected[1], ops[0x13dc2e0], ldap[0x13dac70]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_entry] (0x1000):
OriginalDN: [].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [netlogon]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e4e00], connected[1], ops[0x13dc2e0], ldap[0x13dac70]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_destructor]
(0x2000): Operation 1 finished
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_handle_release]
(0x2000): Trace: sh[0x13e4e00], connected[1], ops[(nil)], ldap[0x13dac70],
destructor_lock[0], release_memory[0]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [remove_connection_callback]
(0x4000): Successfully removed connection callback.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_get_client_site_done]
(0x0400): Found site: Default-First-Site-Name
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_srv_plugin_site_done]
(0x0400): About to discover primary and backup servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_servers_send]
(0x0400): Looking up primary servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will
use DNS discovery domain 'Default-First-Site-Name._sites.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_getsrv_send]
(0x0100): Trying to resolve SRV record of
'_ldap._tcp.Default-First-Site-Name._sites.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_request_timeout]
(0x2000): Scheduling a timeout of 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_timeout_watcher]
(0x2000): Scheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unschedule_timeout_watcher]
(0x4000): Unscheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_getsrv_done]
(0x1000): Using TTL [600]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [request_watch_destructor]
(0x0400): Deleting request watch
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_srv_done]
(0x0400): Got answer. Processing...
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_srv_done]
(0x0400): Got 5 servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[fo_discover_servers_primary_done] (0x0400): Looking up backup servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will
use DNS discovery domain 'concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_getsrv_send]
(0x0100): Trying to resolve SRV record of '_ldap._tcp.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_request_timeout]
(0x2000): Scheduling a timeout of 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_timeout_watcher]
(0x2000): Scheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unschedule_timeout_watcher]
(0x4000): Unscheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_getsrv_done]
(0x1000): Using TTL [600]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [request_watch_destructor]
(0x0400): Deleting request watch
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_srv_done]
(0x0400): Got answer. Processing...
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_discover_srv_done]
(0x0400): Got 5 servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_srv_plugin_servers_done]
(0x0400): Got 5 primary and 5 backup servers
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Inserted primary server 'int-con-dc-5.concordia.ca:389' to service
'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Inserted primary server 'int-con-dc-3.concordia.ca:389' to service
'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Inserted primary server 'int-con-dc-1.concordia.ca:389' to service
'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Inserted primary server 'int-con-dc-2.concordia.ca:389' to service
'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Inserted primary server 'int-con-dc-4.concordia.ca:389' to service
'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Server 'int-con-dc-4.concordia.ca:389' for service 'AD' is
already present
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Server 'int-con-dc-5.concordia.ca:389' for service 'AD' is
already present
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Server 'int-con-dc-3.concordia.ca:389' for service 'AD' is
already present
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Server 'int-con-dc-1.concordia.ca:389' for service 'AD' is
already present
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_add_server_to_list]
(0x0400): Server 'int-con-dc-2.concordia.ca:389' for service 'AD' is
already present
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [set_srv_data_status]
(0x0100): Marking SRV lookup of service 'AD' as 'resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-5.concordia.ca' is 'name not resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_is_address] (0x4000):
[int-con-dc-5.concordia.ca] does not look like an IP address
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_step]
(0x2000): Querying files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of
'int-con-dc-5.concordia.ca' in files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [set_server_common_status]
(0x0100): Marking server 'int-con-dc-5.concordia.ca' as 'resolving name'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_step]
(0x2000): Querying files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of
'int-con-dc-5.concordia.ca' in files
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_next]
(0x0200): No more address families to retry
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolv_gethostbyname_step]
(0x2000): Querying DNS
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of
'int-con-dc-5.concordia.ca' in DNS
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_request_timeout]
(0x2000): Scheduling a timeout of 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [schedule_timeout_watcher]
(0x2000): Scheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unschedule_timeout_watcher]
(0x4000): Unscheduling DNS timeout watcher
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [request_watch_destructor]
(0x0400): Deleting request watch
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [set_server_common_status]
(0x0100): Marking server 'int-con-dc-5.concordia.ca' as 'name resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x1000): Saving the first resolved server
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x0200): Found address for server int-con-dc-5.concordia.ca: [132.205.123.32] TTL 86400
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_resolve_callback]
(0x0100): Constructed uri 'ldap://int-con-dc-5.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_resolve_callback]
(0x0100): Constructed GC uri 'ldap://int-con-dc-5.concordia.ca'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unique_filename_destructor]
(0x2000): Unlinking [/var/lib/sss/pubconf/.krb5info_dummy_NhdMtd]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [unlink_dbg] (0x2000): File
already removed: [/var/lib/sss/pubconf/.krb5info_dummy_NhdMtd]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_kinit_kdc_resolved]
(0x1000): KDC resolved, attempting to get TGT...
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [create_tgt_req_send_buffer]
(0x0400): buffer size: 50
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_handler_setup]
(0x2000): Setting up signal handler up for pid [29943]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_handler_setup]
(0x2000): Signal handler set up for pid [29943]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [set_tgt_child_timeout]
(0x0400): Setting 6 seconds timeout for tgt child
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [write_pipe_handler]
(0x0400): All data has been sent!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_sig_handler] (0x1000):
Waiting for child [29943].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_sig_handler] (0x0100):
child [29943] finished successfully.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [read_pipe_handler] (0x0400):
EOF received, client finished
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_tgt_recv] (0x0400):
Child responded: 0 [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA], expired on [1485210908]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_cli_auth_step]
(0x0100): expire timeout is 900
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_cli_auth_step]
(0x1000): the connection will expire at 1485175808
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send] (0x0100):
Executing sasl bind mech: gssapi, user: PERF-IMGLAB08$
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [_be_fo_set_port_status]
(0x8000): Setting status: PORT_WORKING. Called from:
../src/providers/ldap/sdap_async_connection.c: sdap_cli_connect_recv: 2052
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
(0x0100): Marking port 3268 of server 'int-con-dc-4.concordia.ca' as
'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [set_server_common_status]
(0x0100): Marking server 'int-con-dc-4.concordia.ca' as 'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
(0x0400): Marking port 3268 of duplicate server 'int-con-dc-4.concordia.ca' as
'working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_id_op_connect_done]
(0x2000): Old USN: 206405159, New USN: 206406385
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_id_op_connect_done]
(0x4000): notify connected to op #1
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_initgr_send]
(0x4000): Retrieving info for initgroups call
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_initgr_next_base]
(0x0400): Searching for users with base [DC=concordia,DC=ca]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_print_server] (0x2000):
Searching 132.205.123.31
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x0400): calling ldap_search_ext with
[(&(sAMAccountName=man)(objectclass=user)(objectSID=*))][DC=concordia,DC=ca].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [objectClass]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [sAMAccountName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [unixUserPassword]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [uidNumber]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [gidNumber]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [gecos]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [unixHomeDirectory]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [loginShell]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [userPrincipalName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [name]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [memberOf]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [objectGUID]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [objectSID]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [primaryGroupID]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [whenChanged]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [uSNChanged]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [accountExpires]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [userAccountControl]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x2000): ldap_search_ext called, msgid = 5
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_add] (0x2000): New
operation 5 timeout 6
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_id_op_connect_done]
(0x4000): caching successful connection after 1 notifies
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[be_run_unconditional_online_cb] (0x4000): List of unconditional online callbacks is
empty, nothing to do.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[0x13db2b0], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_entry] (0x1000):
OriginalDN: [CN=man,OU=People,DC=concordia,DC=ca].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [objectClass]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [whenChanged]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [memberOf]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [uSNChanged]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [name]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [objectGUID]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [userAccountControl]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [primaryGroupID]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [objectSid]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [sAMAccountName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [userPrincipalName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [uidNumber]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [gidNumber]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[0x13db2b0], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_destructor]
(0x2000): Operation 5 finished
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_initgr_user]
(0x4000): Receiving info for the user
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 0)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_initgr_user]
(0x4000): Storing the user
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x0400):
Save user
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_primary_name]
(0x0400): Processing object man
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x0400):
Processing user man
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x1000):
Mapping user [man] objectSID [S-1-5-21-2025429265-616249376-725345543-2599092] to unix ID
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x2000):
Adding originalDN [CN=man,OU=People,DC=concordia,DC=ca] to attributes of [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x0400):
Adding original memberOf attributes to [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): Adding original mod-Timestamp [20170114155523.0Z] to attributes of [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x0400):
Adding user principal [man(a)CONCORDIA.CA] to attributes of [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowLastChange is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowMin is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowMax is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowWarning is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowInactive is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowExpire is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): shadowFlag is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): krbLastPwdChange is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): krbPasswordExpiration is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): pwdAttribute is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): authorizedService is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): adAccountExpires is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): Adding adUserAccountControl [544] to attributes of [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): nsAccountLock is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): authorizedHost is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): ndsLoginDisabled is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): ndsLoginExpirationTime is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): ndsLoginAllowedTimeMap is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): sshPublicKey is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): authType is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_attrs_add_ldap_attr]
(0x2000): userCertificate is not available for [man].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sysdb_attrs_get_aliases]
(0x2000): Domain is case-insensitive; will add lowercased aliases
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_save_user] (0x0400):
Storing info for user man
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 1)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x13e36c0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x13e3780
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x13e36c0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x13e3780 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x13e36c0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 2)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x13dd720
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x13e3600
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x13dd720 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x13e3600 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x13dd720 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): commit ldb
transaction (nesting: 2)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 2)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sysdb_remove_attrs]
(0x2000): Removing attribute [userPassword] from [man]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x2408670
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x13e27b0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x2408670 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x13e27b0 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x2408670 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): cancel ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sysdb_remove_attrs]
(0x2000): Removing attribute [homeDirectory] from [man]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x13e46c0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x2409650
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x13e46c0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x2409650 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x13e46c0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): cancel ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sysdb_remove_attrs]
(0x2000): Removing attribute [loginShell] from [man]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x13e27b0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x240a6b0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x13e27b0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x240a6b0 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x13e27b0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): cancel ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sysdb_remove_attrs]
(0x2000): Removing attribute [adAccountExpires] from [man]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): start ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x24110b0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x13f1320
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x24110b0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x13f1320 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x24110b0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): cancel ldb
transaction (nesting: 3)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): commit ldb
transaction (nesting: 2)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): commit ldb
transaction (nesting: 1)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_initgr_user]
(0x4000): Commit change
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): commit ldb
transaction (nesting: 0)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_callback": 0x13e35e0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Added timed
event "ltdb_timeout": 0x13e36a0
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Running timer
event 0x13e35e0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Destroying
timer event 0x13e36a0 "ltdb_timeout"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ldb] (0x4000): Ending timer
event 0x13e35e0 "ltdb_callback"
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_initgr_user]
(0x4000): Process user's groups
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_id_op_connect_step]
(0x4000): beginning to connect
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-5.concordia.ca' is 'name resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolve_srv_send] (0x0200):
The status of SRV lookup is resolved
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-5.concordia.ca' is 'name resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x1000): Saving the first resolved server
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x0200): Found address for server int-con-dc-5.concordia.ca: [132.205.123.32] TTL 86400
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sss_ldap_init_send]
(0x4000): Using file descriptor [26] for LDAP connection.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sss_ldap_init_send]
(0x0400): Setting 6 seconds timeout for connecting
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e7e30], connected[1], ops[(nil)], ldap[0x1389710]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: ldap_result found nothing!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to
[ldap://int-con-dc-5.concordia.ca:389/??base] with fd [26].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_rootdse_send]
(0x4000): Getting rootdse
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_print_server] (0x2000):
Searching 132.205.123.32
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x0400): calling ldap_search_ext with [(objectclass=*)][].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [*]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [altServer]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [namingContexts]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedControl]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedExtension]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedFeatures]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedLDAPVersion]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [supportedSASLMechanisms]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [domainControllerFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [defaultNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [lastUSN]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x1000): Requesting attrs: [highestCommittedUSN]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_generic_ext_step]
(0x2000): ldap_search_ext called, msgid = 1
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_add] (0x2000): New
operation 1 timeout 6
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e44f0], connected[1], ops[0x13de9f0], ldap[0x23fd560]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: ldap_result found nothing!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e44f0], connected[1], ops[0x13de9f0], ldap[0x23fd560]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_entry] (0x1000):
OriginalDN: [].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [currentTime]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [subschemaSubentry]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [dsServiceName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [namingContexts]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [defaultNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [schemaNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [configurationNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [rootDomainNamingContext]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedControl]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedLDAPVersion]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedLDAPPolicies]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [highestCommittedUSN]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedSASLMechanisms]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [dnsHostName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [ldapServiceName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [serverName]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedCapabilities]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [isSynchronized]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [isGlobalCatalogReady]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [supportedExtension]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [domainFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [forestFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_parse_range] (0x2000):
No sub-attributes for [domainControllerFunctionality]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e44f0], connected[1], ops[0x13de9f0], ldap[0x23fd560]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_message]
(0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_op_destructor]
(0x2000): Operation 1 finished
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_rootdse_done]
(0x2000): Got rootdse
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_rootdse_done]
(0x2000): Skipping auto-detection of match rule
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_server_opts_from_rootdse] (0x4000): USN value: 193586616 (int: 193586616)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [4]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_kinit_send] (0x0400):
Attempting kinit (default, PERF-IMGLAB08$, CONCORDIA.CA, 86400)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_kinit_next_kdc]
(0x1000): Resolving next KDC for service AD
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_resolve_service_send]
(0x0100): Trying to resolve service 'AD'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-5.concordia.ca' is 'name resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]]
[fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [resolve_srv_send] (0x0200):
The status of SRV lookup is resolved
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [get_server_status] (0x1000):
Status of server 'int-con-dc-5.concordia.ca' is 'name resolved'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x1000): Saving the first resolved server
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_resolve_server_process]
(0x0200): Found address for server int-con-dc-5.concordia.ca: [132.205.123.32] TTL 86400
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_kinit_kdc_resolved]
(0x1000): KDC resolved, attempting to get TGT...
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [create_tgt_req_send_buffer]
(0x0400): buffer size: 50
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_handler_setup]
(0x2000): Setting up signal handler up for pid [29944]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_handler_setup]
(0x2000): Signal handler set up for pid [29944]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [set_tgt_child_timeout]
(0x0400): Setting 6 seconds timeout for tgt child
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: sh[0x13e44f0], connected[1], ops[(nil)], ldap[0x23fd560]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_process_result]
(0x2000): Trace: ldap_result found nothing!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [write_pipe_handler]
(0x0400): All data has been sent!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_sig_handler] (0x1000):
Waiting for child [29944].
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [child_sig_handler] (0x0100):
child [29944] finished successfully.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [read_pipe_handler] (0x0400):
EOF received, client finished
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_get_tgt_recv] (0x0400):
Child responded: 0 [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA], expired on [1485210908]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_cli_auth_step]
(0x0100): expire timeout is 900
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_cli_auth_step]
(0x1000): the connection will expire at 1485175808
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send] (0x0100):
Executing sasl bind mech: gssapi, user: PERF-IMGLAB08$
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [ad_sasl_log] (0x0040): SASL:
GSSAPI Error: An invalid name was supplied (Success)
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send] (0x0020):
ldap_sasl_bind failed (-2)[Local error]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sasl_bind_send] (0x0080):
Extended failure message: [SASL(-1): generic failure: GSSAPI Error: An invalid name was
supplied (Success)]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [_be_fo_set_port_status]
(0x8000): Setting status: PORT_NOT_WORKING. Called from:
../src/providers/ldap/sdap_async_connection.c: sdap_cli_connect_recv: 2039
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
(0x0100): Marking port 389 of server 'int-con-dc-5.concordia.ca' as 'not
working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [fo_set_port_status]
(0x0400): Marking port 389 of duplicate server 'int-con-dc-5.concordia.ca' as
'not working'
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [sdap_handle_release]
(0x2000): Trace: sh[0x13e44f0], connected[1], ops[(nil)], ldap[0x23fd560],
destructor_lock[0], release_memory[0]
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [remove_connection_callback]
(0x4000): Successfully removed connection callback.
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_mark_offline] (0x2000):
Going offline!
>> (Mon Jan 23 07:35:08 2017) [sssd[be[concordia.ca]]] [be_mark_offline] (0x2000):
Enable check_if_online_ptask.
>>
>>
>> Thanks!
>> Thomas
>> ________________________________________
>> From: Justin Stephenson <jstephen(a)redhat.com>
>> Sent: Thursday, January 26, 2017 4:26 PM
>> To: End-user discussions about the System Security Services Daemon
>> Subject: [SSSD-users] Re: SSSD - user id mapping
>>
>> Hello,
>>
>> This message below indicates SSSD may be in an offline state, this can
>> happen for a number of different reasons.
>>
>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>> [be_get_account_info] (0x0100): Request processed. Returned 1,11,Fast
>> reply - offline
>>
>> Could you search the sssd_concordia.ca.log for text such as
>> 'mark_offline'? Once found, the messages above this point in the logs
>> should indicate why SSSD was set into an offline state.
>>
>> Kind regards,
>> Justin Stephenson
>>
>> On 01/26/2017 12:21 PM, Thomas Beaudry wrote:
>>> ? Hi Everyone,
>>>
>>> I am running into a problem with usernames disappearing (and being
>>> replaced by their uids). The prompt in the terminal also changes to
>>> "i have no name!@<HOST>"
>>>
>>> Jakub suggested that it could be that the getgr* is failing for some reason
>>>
>>> Here is the timestamp for when the error happens:
>>>
>>> I have no name!@perf-imglab08:~$ date
>>> Wed Jan 18 17:22:19 EST 2017
>>> I have no name!@perf-imglab08:~$ getent group 1234
>>> I have no name!@perf-imglab08:~$ date
>>> Wed Jan 18 17:22:36 EST 2017
>>>
>>> and here are my sssd logs:
>>>
>>> *sssd.log*
>>>
>>> (Wed Jan 18 17:22:13 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> concordia.ca
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:13 2017) [sssd] [service_send_ping] (0x2000): Pinging nss
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:13 2017) [sssd] [service_send_ping] (0x2000): Pinging pam
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [service_send_ping] (0x2000): Pinging ssh
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> autofs
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x146e7c0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:13 2017) [sssd] [ping_check] (0x2000): Service nss
>>> replied to ping
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1468c00
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:13 2017) [sssd] [ping_check] (0x2000): Service
>>> concordia.ca replied to ping
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1474870
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:13 2017) [sssd] [ping_check] (0x2000): Service pam
>>> replied to ping
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x14700f0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:13 2017) [sssd] [ping_check] (0x2000): Service ssh
>>> replied to ping
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1471e30
>>> (Wed Jan 18 17:22:13 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:13 2017) [sssd] [ping_check] (0x2000): Service autofs
>>> replied to ping
>>> (Wed Jan 18 17:22:23 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> concordia.ca
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [service_send_ping] (0x2000): Pinging nss
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [service_send_ping] (0x2000): Pinging pam
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [service_send_ping] (0x2000): Pinging ssh
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:23 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> autofs
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x146e7c0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:23 2017) [sssd] [ping_check] (0x2000): Service nss
>>> replied to ping
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1474870
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:23 2017) [sssd] [ping_check] (0x2000): Service pam
>>> replied to ping
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x14700f0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:23 2017) [sssd] [ping_check] (0x2000): Service ssh
>>> replied to ping
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1468c00
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:23 2017) [sssd] [ping_check] (0x2000): Service
>>> concordia.ca replied to ping
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1471e30
>>> (Wed Jan 18 17:22:23 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:23 2017) [sssd] [ping_check] (0x2000): Service autofs
>>> replied to ping
>>> (Wed Jan 18 17:22:33 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> concordia.ca
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:33 2017) [sssd] [service_send_ping] (0x2000): Pinging nss
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [service_send_ping] (0x2000): Pinging pam
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:33 2017) [sssd] [service_send_ping] (0x2000): Pinging ssh
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> autofs
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x146e7c0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd] [ping_check] (0x2000): Service nss
>>> replied to ping
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1474870
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd] [ping_check] (0x2000): Service pam
>>> replied to ping
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1468c00
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd] [ping_check] (0x2000): Service
>>> concordia.ca replied to ping
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x14700f0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd] [ping_check] (0x2000): Service ssh
>>> replied to ping
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1471e30
>>> (Wed Jan 18 17:22:33 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd] [ping_check] (0x2000): Service autofs
>>> replied to ping
>>> (Wed Jan 18 17:22:43 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> concordia.ca
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [service_send_ping] (0x2000): Pinging nss
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [service_send_ping] (0x2000): Pinging pam
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_add_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:43 2017) [sssd] [service_send_ping] (0x2000): Pinging ssh
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:43 2017) [sssd] [service_send_ping] (0x2000): Pinging
>>> autofs
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_add_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4e0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x146e7c0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:43 2017) [sssd] [ping_check] (0x2000): Service nss
>>> replied to ping
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1473220
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1474870
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:43 2017) [sssd] [ping_check] (0x2000): Service pam
>>> replied to ping
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x1478db0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1468c00
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:43 2017) [sssd] [ping_check] (0x2000): Service
>>> concordia.ca replied to ping
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a570
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x14700f0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:43 2017) [sssd] [ping_check] (0x2000): Service ssh
>>> replied to ping
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_remove_timeout] (0x2000): 0x146a4a0
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): dbus conn:
>>> 0x1471e30
>>> (Wed Jan 18 17:22:43 2017) [sssd] [sbus_dispatch] (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:43 2017) [sssd] [ping_check] (0x2000): Service autofs
>>> replied to ping
>>>
>>>
>>>
>>> *sssd_concordia.ca.log*
>>>
>>> (Wed Jan 18 17:22:13 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): dbus conn: 0xaa5740
>>> (Wed Jan 18 17:22:13 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:13 2017) [sssd[be[concordia.ca]]]
>>> [sbus_message_handler] (0x2000): Received SBUS method
>>> org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
>>> (Wed Jan 18 17:22:13 2017) [sssd[be[concordia.ca]]]
>>> [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
>>> (Wed Jan 18 17:22:23 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): dbus conn: 0xaa5740
>>> (Wed Jan 18 17:22:23 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:23 2017) [sssd[be[concordia.ca]]]
>>> [sbus_message_handler] (0x2000): Received SBUS method
>>> org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
>>> (Wed Jan 18 17:22:23 2017) [sssd[be[concordia.ca]]]
>>> [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): dbus conn: 0xaa5740
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [sbus_message_handler] (0x2000): Received SBUS method
>>> org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): dbus conn: 0xaeeb80
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [sbus_message_handler] (0x2000): Received SBUS method
>>> org.freedesktop.sssd.dataprovider.getAccountInfo on path
>>> /org/freedesktop/sssd/dataprovider
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [be_get_account_info] (0x0200): Got request for [0x1002][FAST
>>> BE_REQ_GROUP][1][idnumber=1234]
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [be_get_account_info] (0x0100): Request processed. Returned 1,11,Fast
>>> reply - offline
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [be_req_set_domain]
>>> (0x0400): Changing request domain from [concordia.ca] to [concordia.ca]
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): dbus conn: 0xaeeb80
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [sbus_message_handler] (0x2000): Received SBUS method
>>> org.freedesktop.sssd.dataprovider.getAccountInfo on path
>>> /org/freedesktop/sssd/dataprovider
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [be_get_account_info] (0x0200): Got request for [0x1002][FAST
>>> BE_REQ_GROUP][1][idnumber=1234]
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]]
>>> [be_get_account_info] (0x0100): Request processed. Returned 1,11,Fast
>>> reply - offline
>>> (Wed Jan 18 17:22:33 2017) [sssd[be[concordia.ca]]] [be_req_set_domain]
>>> (0x0400): Changing request domain from [concordia.ca] to
>>> [forestroot.concordia.montreal.qc.ca]
>>> (Wed Jan 18 17:22:43 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): dbus conn: 0xaa5740
>>> (Wed Jan 18 17:22:43 2017) [sssd[be[concordia.ca]]] [sbus_dispatch]
>>> (0x4000): Dispatching.
>>> (Wed Jan 18 17:22:43 2017) [sssd[be[concordia.ca]]]
>>> [sbus_message_handler] (0x2000): Received SBUS method
>>> org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
>>> (Wed Jan 18 17:22:43 2017) [sssd[be[concordia.ca]]]
>>> [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
>>>
>>>
>>> *ldap_child.log*
>>>
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [main] (0x0400):
>>> ldap_child started.
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [main] (0x2000):
>>> context initialized
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): total buffer size: 50
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): realm_str size: 12
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): got realm_str: CONCORDIA.CA
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): princ_str size: 14
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): got princ_str: PERF-IMGLAB08$
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): keytab_name size: 0
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x1000): lifetime: 86400
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unpack_buffer]
>>> (0x0200): Will run as [0][0].
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [privileged_krb5_setup] (0x2000): Kerberos context initialized
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [main] (0x2000):
>>> Kerberos context initialized
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [become_user]
>>> (0x0200): Trying to become user [0][0].
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [become_user]
>>> (0x0200): Already user [0].
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [main] (0x2000):
>>> Running as [0][0].
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [main] (0x2000):
>>> getting TGT sync
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): got realm_name: [CONCORDIA.CA]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x0100): Principal name is:
>>> [PERF-IMGLAB08$(a)CONCORDIA.CA]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x0100): Using keytab [MEMORY:/etc/krb5.keytab]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.728189: Getting
>>> initial credentials for PERF-IMGLAB08$(a)CONCORDIA.CA
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.728262: Looked up
>>> etypes in keytab: des-cbc-crc, des, des-cbc-crc, rc4-hmac, aes128-cts,
>>> aes256-cts
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.728291: Sending
>>> request (195 bytes) to CONCORDIA.CA
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.728317: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.729762: Sending
>>> initial UDP request to dgram 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.731123: Received
>>> answer (210 bytes) from dgram 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734046: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734082: Received
>>> error from KDC: -1765328359/Additional pre-authentication required
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734104:
>>> Processing preauth types: 16, 15, 19, 2
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734112: Selected
>>> etype info: etype aes256-cts, salt
>>> "CONCORDIA.CAhostperf-imglab08.concordia.ca", params ""
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734127:
>>> Retrieving PERF-IMGLAB08$(a)CONCORDIA.CA from MEMORY:/etc/krb5.keytab (vno
>>> 0, enctype aes256-cts) with result: 0/Success
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734137: AS key
>>> obtained for encrypted timestamp: aes256-cts/FC36
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734161: Encrypted
>>> timestamp (for 1484778122.795789): plain
>>> 301AA011180F32303137303131383232323230325AA10502030C248D, encrypted
>>>
77571640DED5412F7668B68A1684793B574BFD047B5D9F7CC62D4197B088FB59018B3BB6E748E651507780452E6B4E8CC31F67FF4A31EEBD
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734168: Preauth
>>> module encrypted_timestamp (2) (real) returned: 0/Success
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734173: Produced
>>> preauth for next request: 2
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734189: Sending
>>> request (275 bytes) to CONCORDIA.CA
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.734196: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.735879: Sending
>>> initial UDP request to dgram 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.737712: Received
>>> answer (96 bytes) from dgram 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.739940: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.739968: Received
>>> error from KDC: -1765328332/Response too big for UDP, retry with TCP
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.739978: Request
>>> or response is too big for UDP; retrying with TCP
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.739986: Sending
>>> request (275 bytes) to CONCORDIA.CA (tcp only)
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.739996: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.741680:
>>> Initiating TCP connection to stream 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.742475: Sending
>>> TCP request to stream 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.744227: Received
>>> answer (1508 bytes) from stream 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.744240:
>>> Terminating TCP connection to stream 132.205.123.21:88
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745376: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745404:
>>> Processing preauth types: 19
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745412: Selected
>>> etype info: etype aes256-cts, salt
>>> "CONCORDIA.CAhostperf-imglab08.concordia.ca", params ""
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745419: Produced
>>> preauth for next request: (empty)
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745425: AS key
>>> determined by preauth: aes256-cts/FC36
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745450: Decrypted
>>> AS reply; session key is: aes256-cts/7A2A
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745455: FAST
>>> negotiation: unavailable
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): credentials initialized
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): keytab ccname:
>>> [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_OaPYPl]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745483:
>>> Initializing FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_OaPYPl with
>>> default princ PERF-IMGLAB08$(a)CONCORDIA.CA
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25844] 1484778122.745537: Storing
>>> PERF-IMGLAB08$(a)CONCORDIA.CA -> krbtgt/CONCORDIA.CA(a)CONCORDIA.CA in
>>> FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_OaPYPl
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): credentials stored
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): Got KDC time offset
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): Renaming
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA_OaPYPl] to
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [unique_filename_destructor] (0x2000): Unlinking
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA_OaPYPl]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [unlink_dbg]
>>> (0x2000): File already removed: [/var/lib/sss/db/ccache_CONCORDIA.CA_OaPYPl]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]]
>>> [prepare_response] (0x0400): Building response for result [0]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [pack_buffer]
>>> (0x2000): response size: 60
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [pack_buffer]
>>> (0x1000): result [0] krberr [0] msgsize [40] msg
>>> [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA]
>>> (Wed Jan 18 17:22:02 2017) [[sssd[ldap_child[25844]]]] [main] (0x0400):
>>> ldap_child completed successfully
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [main] (0x0400):
>>> ldap_child started.
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [main] (0x2000):
>>> context initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): total buffer size: 50
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): realm_str size: 12
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): got realm_str: CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): princ_str size: 14
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): got princ_str: PERF-IMGLAB08$
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): keytab_name size: 0
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x1000): lifetime: 86400
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unpack_buffer]
>>> (0x0200): Will run as [0][0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [privileged_krb5_setup] (0x2000): Kerberos context initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [main] (0x2000):
>>> Kerberos context initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [become_user]
>>> (0x0200): Trying to become user [0][0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [become_user]
>>> (0x0200): Already user [0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [main] (0x2000):
>>> Running as [0][0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [main] (0x2000):
>>> getting TGT sync
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): got realm_name: [CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x0100): Principal name is:
>>> [PERF-IMGLAB08$(a)CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x0100): Using keytab [MEMORY:/etc/krb5.keytab]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.791179: Getting
>>> initial credentials for PERF-IMGLAB08$(a)CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.791382: Looked up
>>> etypes in keytab: des-cbc-crc, des, des-cbc-crc, rc4-hmac, aes128-cts,
>>> aes256-cts
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.791490: Sending
>>> request (195 bytes) to CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.791569: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.793862: Sending
>>> initial UDP request to dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.795622: Received
>>> answer (210 bytes) from dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798139: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798175: Received
>>> error from KDC: -1765328359/Additional pre-authentication required
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798194:
>>> Processing preauth types: 16, 15, 19, 2
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798201: Selected
>>> etype info: etype aes256-cts, salt
>>> "CONCORDIA.CAhostperf-imglab08.concordia.ca", params ""
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798214:
>>> Retrieving PERF-IMGLAB08$(a)CONCORDIA.CA from MEMORY:/etc/krb5.keytab (vno
>>> 0, enctype aes256-cts) with result: 0/Success
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798226: AS key
>>> obtained for encrypted timestamp: aes256-cts/FC36
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798251: Encrypted
>>> timestamp (for 1484778188.864327): plain
>>> 301AA011180F32303137303131383232323330385AA10502030D3047, encrypted
>>>
D1207C76AB678F2BBC0336F2F2EE373DF28682250D27A98B1249180CF5319FC7199D6018C0A44399945C235ECF9B295704D6EAEC3F5FF5FD
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798259: Preauth
>>> module encrypted_timestamp (2) (real) returned: 0/Success
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798264: Produced
>>> preauth for next request: 2
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798278: Sending
>>> request (275 bytes) to CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.798286: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.799446: Sending
>>> initial UDP request to dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.801292: Received
>>> answer (96 bytes) from dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.803814: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.803836: Received
>>> error from KDC: -1765328332/Response too big for UDP, retry with TCP
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.803844: Request
>>> or response is too big for UDP; retrying with TCP
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.803850: Sending
>>> request (275 bytes) to CONCORDIA.CA (tcp only)
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.803858: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.805392:
>>> Initiating TCP connection to stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.806522: Sending
>>> TCP request to stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.808296: Received
>>> answer (1508 bytes) from stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.808314:
>>> Terminating TCP connection to stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809712: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809737:
>>> Processing preauth types: 19
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809747: Selected
>>> etype info: etype aes256-cts, salt
>>> "CONCORDIA.CAhostperf-imglab08.concordia.ca", params ""
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809755: Produced
>>> preauth for next request: (empty)
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809763: AS key
>>> determined by preauth: aes256-cts/FC36
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809790: Decrypted
>>> AS reply; session key is: aes256-cts/A0EB
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809796: FAST
>>> negotiation: unavailable
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): credentials initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): keytab ccname:
>>> [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_btC2E5]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809829:
>>> Initializing FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_btC2E5 with
>>> default princ PERF-IMGLAB08$(a)CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25873] 1484778188.809880: Storing
>>> PERF-IMGLAB08$(a)CONCORDIA.CA -> krbtgt/CONCORDIA.CA(a)CONCORDIA.CA in
>>> FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_btC2E5
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): credentials stored
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): Got KDC time offset
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): Renaming
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA_btC2E5] to
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [unique_filename_destructor] (0x2000): Unlinking
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA_btC2E5]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [unlink_dbg]
>>> (0x2000): File already removed: [/var/lib/sss/db/ccache_CONCORDIA.CA_btC2E5]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]]
>>> [prepare_response] (0x0400): Building response for result [0]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [pack_buffer]
>>> (0x2000): response size: 60
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [pack_buffer]
>>> (0x1000): result [0] krberr [0] msgsize [40] msg
>>> [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25873]]]] [main] (0x0400):
>>> ldap_child completed successfully
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [main] (0x0400):
>>> ldap_child started.
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [main] (0x2000):
>>> context initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): total buffer size: 50
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): realm_str size: 12
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): got realm_str: CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): princ_str size: 14
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): got princ_str: PERF-IMGLAB08$
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): keytab_name size: 0
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x1000): lifetime: 86400
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unpack_buffer]
>>> (0x0200): Will run as [0][0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [privileged_krb5_setup] (0x2000): Kerberos context initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [main] (0x2000):
>>> Kerberos context initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [become_user]
>>> (0x0200): Trying to become user [0][0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [become_user]
>>> (0x0200): Already user [0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [main] (0x2000):
>>> Running as [0][0].
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [main] (0x2000):
>>> getting TGT sync
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): got realm_name: [CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x0100): Principal name is:
>>> [PERF-IMGLAB08$(a)CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x0100): Using keytab [MEMORY:/etc/krb5.keytab]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.911434: Getting
>>> initial credentials for PERF-IMGLAB08$(a)CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.911637: Looked up
>>> etypes in keytab: des-cbc-crc, des, des-cbc-crc, rc4-hmac, aes128-cts,
>>> aes256-cts
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.911713: Sending
>>> request (195 bytes) to CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.911787: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.914393: Sending
>>> initial UDP request to dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.915860: Received
>>> answer (210 bytes) from dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.918866: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.918976: Received
>>> error from KDC: -1765328359/Additional pre-authentication required
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919054:
>>> Processing preauth types: 16, 15, 19, 2
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919087: Selected
>>> etype info: etype aes256-cts, salt
>>> "CONCORDIA.CAhostperf-imglab08.concordia.ca", params ""
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919141:
>>> Retrieving PERF-IMGLAB08$(a)CONCORDIA.CA from MEMORY:/etc/krb5.keytab (vno
>>> 0, enctype aes256-cts) with result: 0/Success
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919180: AS key
>>> obtained for encrypted timestamp: aes256-cts/FC36
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919280: Encrypted
>>> timestamp (for 1484778188.973670): plain
>>> 301AA011180F32303137303131383232323330385AA10502030EDB66, encrypted
>>>
D33317E3C9292ABF582F8D1E389DF6A3B5FFABFC7E0420B45A45B610EFD80E1D9C19CE9AAE0D7959B44E0773747AC7B0DCB56C9DFBF97DA3
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919315: Preauth
>>> module encrypted_timestamp (2) (real) returned: 0/Success
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919337: Produced
>>> preauth for next request: 2
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919431: Sending
>>> request (275 bytes) to CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.919472: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.921847: Sending
>>> initial UDP request to dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.924168: Received
>>> answer (96 bytes) from dgram 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.927183: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.927216: Received
>>> error from KDC: -1765328332/Response too big for UDP, retry with TCP
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.927222: Request
>>> or response is too big for UDP; retrying with TCP
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.927227: Sending
>>> request (275 bytes) to CONCORDIA.CA (tcp only)
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.927234: Resolving
>>> hostname Int-con-dc-1.concordia.ca
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.928427:
>>> Initiating TCP connection to stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.929201: Sending
>>> TCP request to stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.931067: Received
>>> answer (1508 bytes) from stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.931080:
>>> Terminating TCP connection to stream 132.205.123.21:88
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932326: Response
>>> was not from master KDC
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932365:
>>> Processing preauth types: 19
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932374: Selected
>>> etype info: etype aes256-cts, salt
>>> "CONCORDIA.CAhostperf-imglab08.concordia.ca", params ""
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932380: Produced
>>> preauth for next request: (empty)
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932387: AS key
>>> determined by preauth: aes256-cts/FC36
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932409: Decrypted
>>> AS reply; session key is: aes256-cts/C509
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932415: FAST
>>> negotiation: unavailable
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): credentials initialized
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): keytab ccname:
>>> [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_BHwy1c]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932442:
>>> Initializing FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_BHwy1c with
>>> default princ PERF-IMGLAB08$(a)CONCORDIA.CA
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [sss_child_krb5_trace_cb] (0x4000): [25874] 1484778188.932486: Storing
>>> PERF-IMGLAB08$(a)CONCORDIA.CA -> krbtgt/CONCORDIA.CA(a)CONCORDIA.CA in
>>> FILE:/var/lib/sss/db/ccache_CONCORDIA.CA_BHwy1c
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): credentials stored
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): Got KDC time offset
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [ldap_child_get_tgt_sync] (0x2000): Renaming
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA_BHwy1c] to
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [unique_filename_destructor] (0x2000): Unlinking
>>> [/var/lib/sss/db/ccache_CONCORDIA.CA_BHwy1c]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [unlink_dbg]
>>> (0x2000): File already removed: [/var/lib/sss/db/ccache_CONCORDIA.CA_BHwy1c]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]]
>>> [prepare_response] (0x0400): Building response for result [0]
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [pack_buffer]
>>> (0x2000): response size: 60
>>> (Wed Jan 18 17:23:08 2017) [[sssd[ldap_child[25874]]]] [pack_buffer]
>>> (0x1000): result [0] krberr [0] msgsize [40] msg
>>> [FILE:/var/lib/sss/db/ccache_CONCORDIA.CA]
>>>
>>>
>>>
>>> Any help would be greatly appreciated, I've been trying to solve this
>>> problem over the last 2 weeks to no success.
>>>
>>> Thanks!
>>> Thomas
>>>
>>>
>>>
>>>
>>> _______________________________________________
>>> sssd-users mailing list -- sssd-users(a)lists.fedorahosted.org
>>> To unsubscribe send an email to sssd-users-leave(a)lists.fedorahosted.org
>>>
>> _______________________________________________
>> sssd-users mailing list -- sssd-users(a)lists.fedorahosted.org
>> To unsubscribe send an email to sssd-users-leave(a)lists.fedorahosted.org
>> _______________________________________________
>> sssd-users mailing list -- sssd-users(a)lists.fedorahosted.org
>> To unsubscribe send an email to sssd-users-leave(a)lists.fedorahosted.org
>>
> _______________________________________________
> sssd-users mailing list -- sssd-users(a)lists.fedorahosted.org
> To unsubscribe send an email to sssd-users-leave(a)lists.fedorahosted.org
> _______________________________________________
> sssd-users mailing list -- sssd-users(a)lists.fedorahosted.org
> To unsubscribe send an email to sssd-users-leave(a)lists.fedorahosted.org
>