This is the debug with level=10.


(Mon Nov 13 10:35:40 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): dbus conn: 0xe76180
(Mon Nov 13 10:35:40 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:40 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
(Mon Nov 13 10:35:40 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit

==> sssd_sudo.log <==
(Mon Nov 13 10:35:40 2017) [sssd[sudo]] [sbus_dispatch] (0x4000): dbus conn: 0x1f3d6d0
(Mon Nov 13 10:35:40 2017) [sssd[sudo]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:40 2017) [sssd[sudo]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
(Mon Nov 13 10:35:40 2017) [sssd[sudo]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit

==> sssd_MYDOMAIN.COM.log <==

==> sssd_sudo.log <==
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [get_client_cred] (0x4000): Client creds: COMid[0] egid[0] pid[21339].
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [accept_fd_handler] (0x0400): Client connected!
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_cmd] (0x2000): Using protocol version [1]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_parse_name_for_domains] (0x0200): name 'MYUSER' matched without domain, user is MYUSER
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_parse_name_for_domains] (0x0200): name 'MYUSER' matched without domain, user is MYUSER
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_cmd_parse_query_done] (0x0200): Requesting default options for [MYUSER] from [<ALL>]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/MYDOMAIN.COM/MYUSER]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_user] (0x0200): Requesting info about [MYUSER@MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f4ac50

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f4ad10

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f4ac50 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f4ad10 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f4ac50 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_user] (0x0400): Returning info for user [MYUSER@MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_rules] (0x0400): Retrieving default options for [MYUSER] from [MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f401c0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f47bf0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f401c0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f47bf0 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f401c0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f46f60

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f401c0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f46f60 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f401c0 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f46f60 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_sudorules_query_cache] (0x0200): Searching sysdb with [(&(objectClass=sudoRule)(|(sudoUser=ALL)(name=defaults)(sudoUser=MYUSER)(sudoUser=#1126)(sudoUser=%SystemAdmin)(sudoUser=%MYUSER)(sudoUser=+*))(&(dataExpireTimestamp<=1510565743)))]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f58370

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f54840

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f58370 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f54840 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f58370 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_rules] (0x2000): About to get sudo rules from cache
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_sudorules_query_cache] (0x0200): Searching sysdb with [(&(objectClass=sudoRule)(|(name=defaults)))]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f47bf0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f46f60

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f47bf0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f46f60 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f47bf0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sort_sudo_rules] (0x0400): Sorting rules with higher-wins logic
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_sudorules_from_cache] (0x0400): Returning 1 rules for [<default options>@MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_cmd] (0x2000): Using protocol version [1]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_parse_name_for_domains] (0x0200): name 'MYUSER' matched without domain, user is MYUSER
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_parse_name_for_domains] (0x0200): name 'MYUSER' matched without domain, user is MYUSER
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_cmd_parse_query_done] (0x0200): Requesting rules for [MYUSER] from [<ALL>]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/MYDOMAIN.COM/MYUSER]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_user] (0x0200): Requesting info about [MYUSER@MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f4dcf0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f4ddb0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f4dcf0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f4ddb0 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f4dcf0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_user] (0x0400): Returning info for user [MYUSER@MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_rules] (0x0400): Retrieving rules for [MYUSER] from [MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f46f60

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f4dcf0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f46f60 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f4dcf0 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f46f60 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f46f60

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f4e710

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f46f60 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f4e710 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f46f60 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_sudorules_query_cache] (0x0200): Searching sysdb with [(&(objectClass=sudoRule)(|(sudoUser=ALL)(name=defaults)(sudoUser=MYUSER)(sudoUser=#1126)(sudoUser=%SystemAdmin)(sudoUser=%MYUSER)(sudoUser=+*))(&(dataExpireTimestamp<=1510565743)))]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f53930

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f539f0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f53930 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f539f0 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f53930 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_rules] (0x2000): About to get sudo rules from cache
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f4e710

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f46f60

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f4e710 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f46f60 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f4e710 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f450c0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f4e710

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f450c0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f4e710 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f450c0 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_sudorules_query_cache] (0x0200): Searching sysdb with [(&(objectClass=sudoRule)(|(sudoUser=ALL)(sudoUser=MYUSER)(sudoUser=#1126)(sudoUser=%SystemAdmin)(sudoUser=%MYUSER)(sudoUser=+*)))]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1f53930

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1f539f0

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Running timer event 0x1f53930 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Destroying timer event 0x1f539f0 "ltdb_timeout"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [ldb] (0x4000): Ending timer event 0x1f53930 "ltdb_callback"

(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sort_sudo_rules] (0x0400): Sorting rules with higher-wins logic
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [sudosrv_get_sudorules_from_cache] (0x0400): Returning 1 rules for [MYUSER@MYDOMAIN.COM]
(Mon Nov 13 10:35:43 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]

==> sssd_MYDOMAIN.COM.log <==
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): dbus conn: 0xe8f930
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_get_account_info] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][1][name=MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [MYDOMAIN.COM] to [MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab390

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a69e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab390 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a69e0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab390 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12b1d30

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12add40

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12b1d30 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12a8510

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ad2e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12add40 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12b1d30 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12a8510 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ad2e0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12a8510 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_id_op_connect_step] (0x4000): rCOMsing cached connection
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_send] (0x4000): Retrieving info for initgroups call
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=MYDOMAIN,dc=COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_print_server] (0x2000): Searching MYLDAPSERVER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=MYUSER)(objectclass=posixAccount)(&(uidNumber=*)(!(uidNumber=0))))][dc=MYDOMAIN,dc=COM].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sshPublicKey]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 20
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_add] (0x2000): New operation 20 timeout 6
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0xe73180], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [uid=MYUSER,ou=people,dc=MYDOMAIN,dc=COM].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0xe73180], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_destructor] (0x2000): Operation 20 finished
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_user] (0x4000): Receiving info for the user
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_user] (0x4000): Storing the user
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x0400): Save user
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSID] attribute. [0][Success]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x4000): objectSID: not available for user
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x4000): Failed to retrieve UUID [2][No such file or directory].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_primary_name] (0x0400): Processing object MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x0400): Processing user MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x2000): Adding originalDN [uid=MYUSER,ou=people,dc=MYDOMAIN,dc=COM] to attributes of [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x0400): Original memberOf is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20171110171438Z] to attributes of [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x0400): User principal is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowLastChange is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMin is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMax is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowWarning is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowInactive is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowExpire is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowFlag is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): krbLastPwdChange is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): krbPasswordExpiration is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): pwdAttribute is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedService is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): adAccountExpires is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): adUserAccountControl is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): nsAccountLock is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedHost is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginDisabled is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginExpirationTime is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginAllowedTimeMap is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): sshPublicKey is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): authType is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_attrs_add_ldap_attr] (0x2000): userCertificate is not available for [MYUSER].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_save_user] (0x0400): Storing info for user MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 1)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab630

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ab6f0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab630 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ab6f0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab630 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 2)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ae070

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ae130

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ae070 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ae130 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ae070 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 2)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 2)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPassword] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12acaf0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a8ab0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12acaf0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a8ab0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12acaf0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPrincipalName] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aca90

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ace90

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aca90 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ace90 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aca90 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowLastChange] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ace70

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12aab10

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ace70 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12aab10 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ace70 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMin] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12af5e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12afa00

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12af5e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12afa00 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12af5e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMax] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ace40

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12afa00

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ace40 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12afa00 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ace40 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowWarning] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab290

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12af710

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab290 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12af710 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab290 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowInactive] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab290

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ae750

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab290 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ae750 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab290 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowExpire] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ab290

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ab290 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowFlag] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ab290

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ab290 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [krbLastPwdChange] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ab290

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ab290 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [krbPasswordExpiration] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ad200

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ad200 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [pwdAttribute] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ad200

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ad200 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedService] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12abff0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12abff0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adAccountExpires] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12abfd0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12abfd0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adUserAccountControl] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12af710

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12af710 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [nsAccountLock] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12aff90

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12aff90 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedHost] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12aff90

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12aff90 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginDisabled] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ad200

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ad200 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginExpirationTime] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12aff90

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12aff90 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginAllowedTimeMap] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aecb0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12afa00

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12afa00 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aecb0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sysdb_remove_attrs] (0x2000): Removing attribute [sshPublicKey] from [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab6e0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12c2cd0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12c2cd0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab6e0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 2)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 1)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_user] (0x4000): Commit change
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12ab5c0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ab680

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12ab5c0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ab680 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12ab5c0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_user] (0x4000): Process user's groups
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_initgr_rfc2307_next_base] (0x0400): Searching for groups with base [dc=MYDOMAIN,dc=COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_print_server] (0x2000): Searching MYLDAPSERVER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(memberuid=MYUSER)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=MYDOMAIN,dc=COM].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 21
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_add] (0x2000): New operation 21 timeout 6
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0x12a8c40], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0x12a8c40], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=SystemAdmin,ou=groups,dc=MYDOMAIN,dc=COM].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0x12a8c40], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_op_finished] (0x2000): Total count [0]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_destructor] (0x2000): Operation 21 finished
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aea00

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a6fd0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aea00 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a6fd0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aea00 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_initgr_common_store] (0x2000): Updating memberships for MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 1)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 1)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_done] (0x4000): Initgroups done
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aed50

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12aee10

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aed50 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12aee10 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aed50 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_initgr_done] (0x0400): Primary group already cached, nothing to do.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_id_op_done] (0x4000): releasing operation connection
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aab10

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12ab230

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aab10 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12ab230 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aab10 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12a9530

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a95f0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12a9530 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a95f0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12a9530 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_add_timeout] (0x2000): 0x12aa300
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[(nil)], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_remove_timeout] (0x2000): 0x12aa300
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): dbus conn: 0xe8d090
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): dbus conn: 0xe8f930
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [MYDOMAIN.COM] to [MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler] (0x0100): Got request with the following data
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): command: SSS_PAM_AUTHENTICATE
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): domain: MYDOMAIN.COM
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): user: MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): service: sudo
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): tty: /dev/pts/4
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): ruser: MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): rhost:
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): authtok type: 1
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): newauthtok type: 0
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): priv: 0
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): cli_pid: 21339
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): logon name: not set
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'LDAP'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [get_server_status] (0x1000): Status of server 'MYLDAPSERVER' is 'working'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [get_port_status] (0x1000): Port status of port 389 for server 'MYLDAPSERVER' is 'working'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [get_server_status] (0x1000): Status of server 'MYLDAPSERVER' is 'working'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_resolve_server_process] (0x1000): Saving the first resolved server
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_resolve_server_process] (0x0200): Found address for server MYLDAPSERVER: [MYLDAPSERVER] TTL 1438
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_uri_callback] (0x0400): Constructed uri 'ldap://MYLDAPSERVER'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sss_ldap_init_send] (0x4000): Using file descriptor [24] for LDAP connection.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://MYLDAPSERVER:389/??base] with fd [24].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_sys_connect_done] (0x0100): Executing START TLS
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_add] (0x2000): New operation 1 timeout 6
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0x12b1a00], connected[1], ops[0xe93770], ldap[0x1294710]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_connect_done] (0x0080): START TLS result: Success(0), (null)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_destructor] (0x2000): Operation 1 finished
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [_be_fo_set_port_status] (0x8000): Setting status: PORT_WORKING. Called from: ../src/providers/ldap/ldap_auth.c: auth_connect_done: 762
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'MYLDAPSERVER' as 'working'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [set_server_common_status] (0x0100): Marking server 'MYLDAPSERVER' as 'working'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [fo_set_port_status] (0x0400): Marking port 389 of duplicate server 'MYLDAPSERVER' as 'working'
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12aa610

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12aa6d0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12aa610 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12aa6d0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12aa610 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [find_password_expiration_attributes] (0x4000): No password policy requested.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [simple_bind_send] (0x0100): Executing simple bind as: uid=MYUSER,ou=people,dc=MYDOMAIN,dc=COM
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [simple_bind_send] (0x2000): ldap simple bind sent, msgid = 2
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_add] (0x2000): New operation 2 timeout 6
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0x12b1a00], connected[1], ops[0x12a9a90], ldap[0x1294710]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0x12b1a00], connected[1], ops[0x12a9a90], ldap[0x1294710]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_BIND]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [simple_bind_done] (0x2000): Server returned control [1.3.6.1.4.1.42.2.27.8.5.1].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [simple_bind_done] (0x1000): Password Policy Response: expire [-1] grace [-1] error [No error].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [simple_bind_done] (0x0400): Bind result: Success(0), no errmsg set
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_destructor] (0x2000): Operation 2 finished
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [auth_bind_user_done] (0x4000): Found ppolicy data, assuming LDAP password policies are active.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_handle_release] (0x2000): Trace: sh[0x12b1a00], connected[1], ops[(nil)], ldap[0x1294710], destructor_lock[0], release_memory[0]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [remove_connection_callback] (0x4000): Successfully removed connection callback.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12a71f0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a72b0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12a71f0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a72b0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12a71f0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_pam_auth_done] (0x0100): Password successfully cached for MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0100): Sending result [0][MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0100): Sent result [0][MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): dbus conn: 0xe8f930
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [MYDOMAIN.COM] to [MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler] (0x0100): Got request with the following data
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): command: SSS_PAM_ACCT_MGMT
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): domain: MYDOMAIN.COM
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): user: MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): service: sudo
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): tty: /dev/pts/4
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): ruser: MYUSER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): rhost:
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): authtok type: 0
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): newauthtok type: 0
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): priv: 0
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): cli_pid: 21339
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [pam_print_data] (0x0100): logon name: not set
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_access_send] (0x0400): Performing access check for user [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12a7080

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a7140

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12a7080 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a7140 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12a7080 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_access_filter_send] (0x0400): Performing access filter check for user [MYUSER]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_access_filter_send] (0x0400): Checking filter against LDAP
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_id_op_connect_step] (0x4000): rCOMsing cached connection
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_print_server] (0x2000): Searching MYLDAPSERVER
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=MYUSER)(objectclass=posixAccount)(uidNumber=*))][uid=MYUSER,ou=people,dc=MYDOMAIN,dc=COM].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 22
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_add] (0x2000): New operation 22 timeout 6
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0x12927d0], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_ENTRY]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [uid=MYUSER,ou=people,dc=MYDOMAIN,dc=COM].
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [sn]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [givenName]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [fdPhotoVisible]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [mail]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [gosaMailDeliveryMode]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusTunnelMediumType]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusTunnelType]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusFramedProtocol]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusFramedIPAddress]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusFramedMTU]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusFramedCompression]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusServiceType]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusIdleTimeout]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [radiusPortLimit]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[0x12927d0], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_op_destructor] (0x2000): Operation 22 finished
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_id_op_done] (0x4000): releasing operation connection
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_access_filter_done] (0x0400): Access granted by online lookup
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): start ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x12a7ff0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x12a80b0

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Running timer event 0x12a7ff0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Destroying timer event 0x12a80b0 "ltdb_timeout"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): Ending timer event 0x12a7ff0 "ltdb_callback"

(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [ldb] (0x4000): commit ldb transaction (nesting: 0)
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0400): SELinux provider doesn't exist, not sending the request to it.
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0100): Sending result [0][MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [be_pam_handler_callback] (0x0100): Sent result [0][MYDOMAIN.COM]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: sh[0xe95500], connected[1], ops[(nil)], ldap[0xe93370]
(Mon Nov 13 10:35:47 2017) [sssd[be[MYDOMAIN.COM]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!

==> sssd_sudo.log <==
(Mon Nov 13 10:35:47 2017) [sssd[sudo]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x1f4b430][19]
(Mon Nov 13 10:35:47 2017) [sssd[sudo]] [client_recv] (0x0200): Client disconnected!
(Mon Nov 13 10:35:47 2017) [sssd[sudo]] [client_destructor] (0x2000): Terminated client [0x1f4b430][19]

==> sssd_MYDOMAIN.COM.log <==
(Mon Nov 13 10:35:50 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): dbus conn: 0xe76180
(Mon Nov 13 10:35:50 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:50 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
(Mon Nov 13 10:35:50 2017) [sssd[be[MYDOMAIN.COM]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit

==> sssd_sudo.log <==
(Mon Nov 13 10:35:50 2017) [sssd[sudo]] [sbus_dispatch] (0x4000): dbus conn: 0x1f3d6d0
(Mon Nov 13 10:35:50 2017) [sssd[sudo]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Nov 13 10:35:50 2017) [sssd[sudo]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service
(Mon Nov 13 10:35:50 2017) [sssd[sudo]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit