[SSSD-users] Problem getting sssd to work with LDAP authentication

[Jane Eason]

Hello,

We are attempting to get LDAP logins set up with sssd on RHEL 7.6.

sssd is able to look up the user against the backend LDAP using a secure connection on
port 636. However, it does not attempt to authenticate.
We think pam authentication is not getting called for remote logins. Local logins are
working.

On the ldap server, we can see the search but not an authentication attempt.

In case it matters: We don't store the POSIX uid in LDAP.

Where should we look for the missing setting(s)? Some log snips follow.

In /var/log/secure on the client vm, we see:

Aug  8 16:45:32 clientvm polkitd[5652]: Registered Authentication Agent for
unix-process:8478:2629285 (system bus name :1.92 [/usr/bin/pkttyagent --notify-fd 5
--fallback], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale
en_US.UTF-8)
Aug  8 16:45:32 clientvm polkitd[5652]: Unregistered Authentication Agent for
unix-process:8478:2629285 (system bus name :1.92, object path
/org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from
bus)
Aug  8 16:45:53 clientvm unix_chkpwd[8491]: password check failed for user (myuser)
Aug  8 16:45:53 clientvm sshd[8489]: pam_unix(sshd:auth): authentication failure; logname=
uid=0 euid=0 tty=ssh ruser= rhost=REDACTED  user=myuser
Aug  8 16:45:53 clientvm sshd[8489]: pam_sss(sshd:auth): authentication failure; logname=
uid=0 euid=0 tty=ssh ruser= rhost=REDACTED user=myuser
Aug  8 16:45:53 clientvm sshd[8489]: pam_sss(sshd:auth): received for user myuser: 10
(User not known to the underlying authentication module)
Aug  8 16:45:55 clientvm sshd[8489]: Failed password for myuser from REDACTED port 10320
ssh2
Aug  8 16:46:07 clientvm sshd[8489]: Connection closed by REDACTED port 10320 [preauth]

In /var/log/sssd/sssd_mydomain.log:

(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_get_account_info_handler] (0x0200):
Got request for [0x3][BE_REQ_INITGROUPS][name=myuser@mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sss_domain_get_state] (0x1000): Domain
mydomain is Active
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x561aa191ac20

(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x561aa191ace0

(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): Running timer event
0x561aa191ac20 "ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): Destroying timer event
0x561aa191ace0 "ltdb_timeout"

(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): Ending timer event
0x561aa191ac20 "ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_attach_req] (0x0400): DP Request
[Initgroups #2]: New request. Flags [0x0001].
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_attach_req] (0x0400): Number of active
DP request: 1
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sss_domain_get_state] (0x1000): Domain
mydomain is Active
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_id_op_connect_step] (0x4000):
reusing cached connection
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_initgr_send] (0x4000):
Retrieving info for initgroups call
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_initgr_next_base] (0x0400):
Searching for users with base [ou=people,ou=primary,ou=eid,dc=my,dc=edu]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_print_server] (0x2000): Searching
REDACTED:636
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x0400):
calling ldap_search_ext with
[(&(uid=myuser)(objectclass=inetorgperson)(&(uid=*)(!(uid=0))))][ou=people,ou=primary,ou=eid,dc=my,dc=edu].
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [objectClass]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [uid]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [userPassword]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [uid]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [gidNumber]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [gecos]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [homeDirectory]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [loginShell]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [krbPrincipalName]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [cn]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [modifyTimestamp]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [modifyTimestamp]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowLastChange]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowMin]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowMax]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowWarning]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowInactive]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowExpire]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [shadowFlag]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [krbLastPwdChange]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [krbPasswordExpiration]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [pwdAttribute]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [authorizedService]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [accountExpires]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [userAccountControl]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [nsAccountLock]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [host]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [rhost]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [loginDisabled]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [loginExpirationTime]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [loginAllowedTimeMap]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [sshPublicKey]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [userCertificate;binary]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x1000):
Requesting attrs: [mail]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_ext_step] (0x2000):
ldap_search_ext called, msgid = 5
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_op_add] (0x2000): New operation 5
timeout 6
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_process_result] (0x2000): Trace:
sh[0x561aa17fdf50], connected[1], ops[0x561aa191d380], ldap[0x561aa17dbd00]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_process_message] (0x4000): Message
type: [LDAP_RES_SEARCH_ENTRY]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_parse_entry] (0x1000): OriginalDN:
[uid=myuser,ou=people,ou=primary,ou=eid,dc=my,dc=edu].
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_parse_range] (0x2000): No
sub-attributes for [mail]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_parse_range] (0x2000): No
sub-attributes for [uid]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_parse_range] (0x2000): No
sub-attributes for [objectClass]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_parse_range] (0x2000): No
sub-attributes for [modifyTimestamp]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_process_result] (0x2000): Trace:
sh[0x561aa17fdf50], connected[1], ops[0x561aa191d380], ldap[0x561aa17dbd00]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_process_message] (0x4000): Message
type: [LDAP_RES_SEARCH_RESULT]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_generic_op_finished] (0x0400):
Search result: Success(0), no errmsg set
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_op_destructor] (0x2000): Operation 5
finished
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_initgr_user] (0x4000): Receiving
info for the user
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): start ldb transaction
(nesting: 0)
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_initgr_user] (0x4000): Storing
the user
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_save_user] (0x0400): Save user
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_attrs_get_sid_str] (0x1000): No
[objectSID] attribute. [0][Success]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_save_user] (0x4000): objectSID: not
available for user
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_save_user] (0x4000): Failed to
retrieve UUID [2][No such file or directory].
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_get_primary_name] (0x0400):
Processing object myuser
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_save_user] (0x0400): Processing user
myuser@mydomain
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_save_user] (0x0020): Cannot retrieve
UID for [myuser@mydomain] in domain [mydomain].
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_save_user] (0x0020): Failed to save
user [myuser@mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [ldb] (0x4000): cancel ldb transaction
(nesting: 0)
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_id_op_done] (0x4000): releasing
operation connection
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_req_done] (0x0400): DP Request
[Initgroups #2]: Request handler finished [0]: Success
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [_dp_req_recv] (0x0400): DP Request
[Initgroups #2]: Receiving request data.
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_req_reply_list_success] (0x0400): DP
Request [Initgroups #2]: Finished. Success.
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_req_reply_std] (0x1000): DP Request
[Initgroups #2]: Returning [Internal Error]: 3,1432158246,Init group lookup failed
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_table_value_destructor] (0x0400):
Removing [0:1:0x0001:3::mydomain:name=myuser@mydomain] from reply table
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_req_destructor] (0x0400): DP Request
[Initgroups #2]: Request removed.
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_process_result] (0x2000): Trace:
sh[0x561aa17fdf50], connected[1], ops[(nil)], ldap[0x561aa17dbd00]
(Thu Aug  8 16:45:53 2019) [sssd[be[mydomain]]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list


in /var/log/sssd/sssd_pam.log:

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_cmd_authenticate] (0x0100): entering
pam_cmd_authenticate
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name
'myuser' matched without domain, user is myuser
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): command:
SSS_PAM_AUTHENTICATE
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): user: myuser
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): rhost: REDACTED
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 1
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 8489
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_print_data] (0x0100): logon name: myuser
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_initgr_check_timeout] (0x4000): User [myuser]
not found in PAM cache.
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_set_plugin] (0x2000): CR #0: Setting
"Initgroups by name" plugin
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_send] (0x0400): CR #0: New request
'Initgroups by name'
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_process_input] (0x0400): CR #0: Parsing
input name [myuser]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name
'myuser' matched without domain, user is myuser
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_set_name] (0x0400): CR #0: Setting name
[myuser]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_select_domains] (0x0400): CR #0:
Performing a multi-domain search
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_domains] (0x0400): CR #0: Search
will bypass the cache and check the data provider
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain mydomain type POSIX is valid
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_set_domain] (0x0400): CR #0: Using
domain [mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_prepare_domain_data] (0x0400): CR #0:
Preparing input data for domain [mydomain] rules
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_send] (0x0400): CR #0: Looking up
myuser@mydomain
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_ncache] (0x0400): CR #0: Checking
negative cache for [myuser@mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/mydomain/myuser@mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_ncache] (0x0400): CR #0:
[myuser@mydomain] is not present in negative cache
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_dp] (0x0400): CR #0: Looking up
[myuser@mydomain] in data provider
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x5624540cd550:3:myuser@mydomain@mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request
for [mydomain][0x3][BE_REQ_INITGROUPS][name=myuser@mydomain:-]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x562454f181e0
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x5624540cd550:3:myuser@mydomain@mydomain]
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x562454f181e0
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sbus_dispatch] (0x4000): dbus conn:
0x562454f1bc40
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 3 errno: 1432158246 error message: Init group lookup failed
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_common_dp_recv] (0x0040): CR #0: Data
Provider Error: 3, 1432158246, Init group lookup failed
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_common_dp_recv] (0x0400): CR #0: Due to
an error we will return cached data
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_cache] (0x0400): CR #0: Looking
up [myuser@mydomain] in cache
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x562454f289b0

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x562454f28a70

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Running timer event 0x562454f289b0
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Destroying timer event
0x562454f28a70 "ltdb_timeout"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x562454f289b0
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_search_cache] (0x0400): CR #0: Object
[myuser@mydomain] was not found in cache
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [cache_req_process_result] (0x0400): CR #0:
Finished: Not found
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x562454f1d530

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x562454f20ff0

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Running timer event 0x562454f1d530
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Destroying timer event
0x562454f20ff0 "ltdb_timeout"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x562454f1d530
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result
[10]: User not known to the underlying authentication module.
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x562454f29580

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x562454f29640

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Running timer event 0x562454f29580
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Destroying timer event
0x562454f29640 "ltdb_timeout"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x562454f29580
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x562454f248b0

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x562454f23ec0

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Running timer event 0x562454f248b0
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Destroying timer event
0x562454f23ec0 "ltdb_timeout"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [ldb] (0x4000): Ending timer event 0x562454f248b0
"ltdb_callback"

(Thu Aug  8 16:45:53 2019) [sssd[pam]] [filter_responses] (0x0100): [pam_response_filter]
not available, not fatal.
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [pam_reply] (0x0200): blen: 8
(Thu Aug  8 16:45:53 2019) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x5624540cd550:3:myuser@mydomain@mydomain]
(Thu Aug  8 16:46:07 2019) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
(Thu Aug  8 16:46:07 2019) [sssd[pam]] [client_close_fn] (0x2000): Terminated client
[0x562454f245a0][19]

Thanks,

Jane