Hi Alexander
Thanks for clarifying.
I don't see anything in the sssd_domain.log
I see something though in the sssd_nss.log file.
I crosschecked my sssd.conf file and corrected some spelling error and it seems to work
now. I can su and ssh with the AD Domain users on the ipa client. Only thing now is that
when i try to login in the gui, it just restarts the gui.
Maybe you have an idea what to look into with this issue.
Here's the output of nss.log:
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #486:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [cache_req_search_done] (0x0400): CR #486:
Returning updated object [user(a)domain.ad]
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#486: Found 1 entries in domain domain.ad
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:1:user@domain.ad@domain.ad]
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #486: Finished:
Success
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:42:46 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:16 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a642f0][21]
(Mon Oct 28 16:43:16 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a55990][22]
(Mon Oct 28 16:43:16 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a626b0][23]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[997]
egid[992] pid[2279].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a64ec0][24]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client
version [1].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version
[1].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
user(a)domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #487: Setting
"Object by name" plugin
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #487: New request
'Object by name'
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #487:
Parsing input name [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'user(a)domain.ad' matched expression for domain 'domain.ad', user is user
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #487: Setting
name [user]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #487:
Performing a single domain search
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #487:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #487: Using
domain [domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #487:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #487: Looking
up user(a)domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #487:
Checking negative cache for [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #487:
[user(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #487: Looking
up [user(a)domain.ad] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a60440
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a60510
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a60440
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60510 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60440 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a665c0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a66690
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a665c0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66690 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a665c0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #487:
Returning [user(a)domain.ad] from cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #487:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#487: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #487: Finished:
Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
user(a)domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #488: Setting
"Initgroups by name" plugin
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #488: New request
'Initgroups by name'
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #488:
Parsing input name [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'user(a)domain.ad' matched expression for domain 'domain.ad', user is user
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #488: Setting
name [user]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #488:
Performing a single domain search
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #488:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #488: Using
domain [domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #488:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #488: Looking
up user(a)domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #488:
Checking negative cache for [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #488:
[user(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #488: Looking
up [user(a)domain.ad] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a661d0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a662a0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a661d0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a662a0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a661d0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5afc0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5b090
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5afc0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5b090 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5afc0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a66070
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a66140
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a66070
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a500
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a5d0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66140 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66070 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a500
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6aeb0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6af80
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a5d0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a500 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6aeb0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6af80 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6aeb0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #488: Object
found, but needs to be refreshed.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_dp] (0x0400): CR #488: Looking up
[user(a)domain.ad] in data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x556495a41530:3:user@domain.ad@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request
for [domain.ad][0x3][BE_REQ_INITGROUPS][name=user@domain.ad:-]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x556496a54e70
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x556495a41530:3:user@domain.ad@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x556496a54e70
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_message_handler] (0x2000): Received SBUS
method org.freedesktop.sssd.nss.MemoryCache.UpdateInitgroups on path
/org/freedesktop/sssd/nss/memcache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_get_sender_id_send] (0x2000): Not a sysbus
message, quit
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_memorycache_update_initgroups] (0x1000):
Updating initgroups memory cache of [user@domain.ad(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6cb60
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6cc30
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6cb60
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6cc30 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6cb60 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5b080
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6cb60
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5b080
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6cb60 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5b080 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a69c40
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a69d10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a69c40
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a710e0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a711b0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69d10 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69c40 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a710e0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a71a20
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a71af0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a711b0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a710e0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a71a20
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a71af0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a71a20 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 0 errno: 0 error message: Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #488: Looking
up [user(a)domain.ad] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a64dc0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a70210
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a64dc0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a70210 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a64dc0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a67c60
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a69ee0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a67c60
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69ee0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a67c60 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a645d0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a646a0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a645d0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a707e0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a708b0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a646a0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a645d0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a707e0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a71120
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a711f0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a708b0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a707e0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a71120
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a711f0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a71120 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #488:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_done] (0x0400): CR #488:
Returning updated object [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#488: Found 3 entries in domain domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:3:user@domain.ad@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #488: Finished:
Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_getby_id] (0x0400): Input ID: 500
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #489: Setting
"Group by ID" plugin
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #489: New request
'Group by ID'
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #489:
Performing a multi-domain search
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #489:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.test type POSIX is valid
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #489: Using
domain [domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #489: Looking
up GID:500@domain.test
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #489:
Checking negative cache for [GID:500@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/domain.test/500]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/500]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #489:
[GID:500@domain.test] is not present in negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #489: Looking
up [GID:500@domain.test] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a62920
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a629f0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a62920
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a629f0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a62920 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #489: Object
[GID:500@domain.test] was not found in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_dp] (0x0400): CR #489: Looking up
[GID:500@domain.test] in data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x556495a41530:2:500@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request
for [domain.test][0x2][BE_REQ_GROUP][idnumber=500:-]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x556496a551a0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x556495a41530:2:500@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x556496a551a0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 0 errno: 0 error message: Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #489: Looking
up [GID:500@domain.test] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5fa20
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5f5c0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5fa20
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5f5c0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5fa20 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #489: Object
[GID:500@domain.test] was not found in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache_add_to_domain] (0x0400):
CR #489: Adding [GID:500@domain.test] to negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_set_str] (0x0400): Adding
[NCE/GID/domain.test/500] to negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #489: Using
domain [domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #489: Looking
up GID:500@domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #489:
Checking negative cache for [GID:500@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/domain.ad/500]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/500]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #489:
[GID:500@domain.ad] is not present in negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #489: Looking
up [GID:500@domain.ad] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5f960
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5fa30
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5f960
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5fa30 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5f960 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a63e40
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5f960
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a63e40
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5f960 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a63e40 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a59640
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a57c60
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a59640
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a57c60 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a59640 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_get_user_members_recursively] (0x0400): No
such entry
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #489: Object
found, but needs to be refreshed.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_dp] (0x0400): CR #489: Looking up
[GID:500@domain.ad] in data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x556495a41530:2:500@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request
for [domain.ad][0x2][BE_REQ_GROUP][idnumber=500:-]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x556496a52d40
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x556495a41530:2:500@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:2:500@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x556496a52d40
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 0 errno: 0 error message: Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #489: Looking
up [GID:500@domain.ad] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a60090
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a59580
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a60090
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a59580 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60090 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a60090
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a63d10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a60090
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a63d10 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60090 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a59580
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a66d40
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a59580
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66d40 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a59580 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a870
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a58060
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a870
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a58060 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a870 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a60090
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a66d40
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a60090
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66d40 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60090 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[user(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[one(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #489:
Filtering out results by negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GROUP/domain.ad/tester(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_done] (0x0400): CR #489:
Returning updated object [GID:500@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#489: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:2:500@domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #489: Finished:
Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/one(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_getby_id] (0x0400): Input ID: 1095200010
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #490: Setting
"Group by ID" plugin
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #490: New request
'Group by ID'
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #490:
Performing a multi-domain search
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #490:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.test type POSIX is valid
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #490: Using
domain [domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #490: Looking
up GID:1095200010@domain.test
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #490:
Checking negative cache for [GID:1095200010@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/domain.test/1095200010]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/1095200010]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #490:
[GID:1095200010@domain.test] is not present in negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #490: Looking
up [GID:1095200010@domain.test] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a62030
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a62100
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a62030
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a62100 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a62030 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a290
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a601b0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a290
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a601b0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a290 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5f8f0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5f9c0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5f8f0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5f9c0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5f8f0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a5e0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a68dd0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a5e0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a68dd0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a5e0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a5e0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a683c0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a5e0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a683c0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a5e0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[one(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[user(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #490: Object
found, but needs to be refreshed.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_dp] (0x0400): CR #490: Looking up
[GID:1095200010@domain.test] in data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x556495a41530:2:1095200010@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request
for [domain.test][0x2][BE_REQ_GROUP][idnumber=1095200010:-]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x556496a69180
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x556495a41530:2:1095200010@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x556496a69180
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 0 errno: 0 error message: Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #490: Looking
up [GID:1095200010@domain.test] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6aec0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a66390
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6aec0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66390 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6aec0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a595d0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6aae0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a595d0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6aae0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a595d0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a69fe0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a59510
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a69fe0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a59510 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69fe0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a57f50
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a5c0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a57f50
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a5c0 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a57f50 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6bb30
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a57f50
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6bb30
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a57f50 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6bb30 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[one(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[user(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #490:
Filtering out results by negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GROUP/domain.test/ad_groups(a)domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_done] (0x0400): CR #490:
Returning updated object [GID:1095200010@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#490: Found 1 entries in domain domain.test
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:2:1095200010@domain.test]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #490: Finished:
Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.test/one(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.test/user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
user(a)domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #491: Setting
"Object by name" plugin
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #491: New request
'Object by name'
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #491:
Parsing input name [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'user(a)domain.ad' matched expression for domain 'domain.ad', user is user
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #491: Setting
name [user]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #491:
Performing a single domain search
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #491:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #491: Using
domain [domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #491:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #491: Looking
up user(a)domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #491:
Checking negative cache for [user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/user(a)domain.ad]
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #491:
[user(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #491: Looking
up [user(a)domain.ad] in cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a140
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a210
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a140
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a210 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a140 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a628a0
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a62970
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a628a0
"ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a62970 "ltdb_timeout"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a628a0 "ltdb_callback"
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #491:
Returning [user(a)domain.ad] from cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #491:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#491: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #491: Finished:
Success
(Mon Oct 28 16:43:27 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
tester(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #492: Setting
"Object by name" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #492: New request
'Object by name'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #492:
Parsing input name [tester(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'tester(a)domain.ad' matched expression for domain 'domain.ad', user is
tester
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #492: Setting
name [tester]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #492:
Performing a single domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #492:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #492: Using
domain [domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #492:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #492: Looking
up tester(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #492:
Checking negative cache for [tester(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/tester(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GROUP/domain.ad/tester(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #492:
[tester(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #492: Looking
up [tester(a)domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a070
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a140
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a070
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a140 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a070 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a64640
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a64710
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a64640
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a64710 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a64640 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #492:
Returning [tester(a)domain.ad] from cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #492:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#492: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #492: Finished:
Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_parse_sid] (0x4000): Input SID
[S-1-5-21-2588721784-3601702760-1703444798-1112]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_sid] (0x0400): Input SID:
S-1-5-21-2588721784-3601702760-1703444798-1112
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #493: Setting
"Object by SID" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #493: New request
'Object by SID'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_object_by_sid_well_known] (0x4000): CR
#493: SID [S-1-5-21-2588721784-3601702760-1703444798-1112] is not a Well-Known SID.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #493:
Performing a multi-domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #493:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.test type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #493: Using
domain [domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #493: Looking
up SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #493:
Checking negative cache for
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/SID/S-1-5-21-2588721784-3601702760-1703444798-1112]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #493:
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test] is not present in
negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #493: Looking
up [SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a610
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a6e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a610
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a6e0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a610 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_search_object_attr] (0x0400): No such
entry.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #493: Object
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test] was not found in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_dp] (0x0400): CR #493: Looking up
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test] in data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x556495a41530:6:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request
for
[domain.test][0x11][BE_REQ_BY_SECID][secid=S-1-5-21-2588721784-3601702760-1703444798-1112:-]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x556496a5f1a0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x556495a41530:6:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x556496a5f1a0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 0 errno: 0 error message: Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #493: Looking
up [SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5afc0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5b090
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5afc0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5b090 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5afc0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_search_object_attr] (0x0400): No such
entry.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #493: Object
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test] was not found in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_add_to_domain] (0x2000):
CR #493: This request type does not support negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #493: Using
domain [domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #493: Looking
up SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #493:
Checking negative cache for
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/SID/S-1-5-21-2588721784-3601702760-1703444798-1112]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #493:
[SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.ad] is not present in negative
cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #493: Looking
up [SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a68210
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a682e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a68210
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a682e0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a68210 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a689c0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a68a90
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a689c0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a68a90 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a689c0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #493:
Returning [SID:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.ad] from cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #493:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:6:S-1-5-21-2588721784-3601702760-1703444798-1112@domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#493: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #493: Finished:
Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
one(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #494: Setting
"User by name" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #494: New request
'User by name'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #494:
Parsing input name [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'one(a)domain.ad' matched expression for domain 'domain.ad', user is one
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #494: Setting
name [one]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #494:
Performing a single domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #494:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #494: Using
domain [domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #494:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #494: Looking
up one(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #494:
Checking negative cache for [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #494:
[one(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #494: Looking
up [one(a)domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5afc0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5b090
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5afc0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5b090 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5afc0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a67e60
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a67f30
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a67e60
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a67f30 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a67e60 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #494:
Returning [one(a)domain.ad] from cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #494:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#494: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #494: Finished:
Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
one(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #495: Setting
"Object by name" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #495: New request
'Object by name'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #495:
Parsing input name [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'one(a)domain.ad' matched expression for domain 'domain.ad', user is one
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #495: Setting
name [one]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #495:
Performing a single domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #495:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #495: Using
domain [domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #495:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #495: Looking
up one(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #495:
Checking negative cache for [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #495:
[one(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #495: Looking
up [one(a)domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a070
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a140
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a070
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a140 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a070 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a62a10
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a69b60
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a62a10
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69b60 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a62a10 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #495:
Returning [one(a)domain.ad] from cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #495:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#495: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #495: Finished:
Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_name] (0x0400): Input name:
one(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #496: Setting
"Initgroups by name" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #496: New request
'Initgroups by name'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_process_input] (0x0400): CR #496:
Parsing input name [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name
'one(a)domain.ad' matched expression for domain 'domain.ad', user is one
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_name] (0x0400): CR #496: Setting
name [one]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #496:
Performing a single domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #496:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #496: Using
domain [domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_prepare_domain_data] (0x0400): CR #496:
Preparing input data for domain [domain.ad] rules
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #496: Looking
up one(a)domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #496:
Checking negative cache for [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #496:
[one(a)domain.ad] is not present in negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #496: Looking
up [one(a)domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5afc0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5b090
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5afc0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5b090 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5afc0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a595d0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a67ed0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a595d0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a67ed0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a595d0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a595d0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a69c80
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a595d0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6af00
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6afd0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69c80 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a595d0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6af00
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6b840
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6b910
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6afd0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6af00 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6b840
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6d9e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6dab0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6b910 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6b840 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6d9e0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6dab0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6d9e0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #496: Object
found, but needs to be refreshed.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_dp] (0x0400): CR #496: Looking up
[one(a)domain.ad] in data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request
for [0x556495a41530:3:one@domain.ad@domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request
for [domain.ad][0x3][BE_REQ_INITGROUPS][name=one@domain.ad:-]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x556496a551a0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering
request [0x556495a41530:3:one@domain.ad@domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x556496a551a0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_message_handler] (0x2000): Received SBUS
method org.freedesktop.sssd.nss.MemoryCache.UpdateInitgroups on path
/org/freedesktop/sssd/nss/memcache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_get_sender_id_send] (0x2000): Not a sysbus
message, quit
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_memorycache_update_initgroups] (0x1000):
Updating initgroups memory cache of [one@domain.ad(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a250
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a620b0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a250
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a620b0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a250 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5afc0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a5b090
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5afc0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5b090 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5afc0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a66c00
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a66cd0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a66c00
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a72b30
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a72c00
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66cd0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a66c00 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a72b30
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a73470
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a73540
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a72c00 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a72b30 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a73470
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a73540 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a73470 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data
Provider - DP error code: 0 errno: 0 error message: Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #496: Looking
up [one(a)domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a60630
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a62c20
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a60630
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a62c20 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60630 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a69db0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6dd00
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a69db0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6dd00 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69db0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a810
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6abd0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a810
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a71c00
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a71cd0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6abd0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a810 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a71c00
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a725b0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a72680
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a71cd0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a71c00 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a725b0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a72680 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a725b0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #496:
This request type does not support filtering result by negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_done] (0x0400): CR #496:
Returning updated object [one(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#496: Found 3 entries in domain domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request:
[0x556495a41530:3:one@domain.ad@domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #496: Finished:
Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn:
0x556496a53e10
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_id] (0x0400): Input ID: 500
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #497: Setting
"Group by ID" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #497: New request
'Group by ID'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #497:
Performing a multi-domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #497:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.test type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #497: Using
domain [domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #497: Looking
up GID:500@domain.test
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #497:
Checking negative cache for [GID:500@domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/domain.test/500]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #497:
[GID:500@domain.test] does not exist (negative cache)
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.ad type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #497: Using
domain [domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #497: Looking
up GID:500@domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #497:
Checking negative cache for [GID:500@domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/domain.ad/500]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/500]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #497:
[GID:500@domain.ad] is not present in negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #497: Looking
up [GID:500@domain.ad] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a671e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a672b0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a671e0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a672b0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a671e0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a4e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a5b0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a4e0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a5b0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a4e0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a628a0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a69f10
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a628a0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69f10 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a628a0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a69fd0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a627e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a69fd0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a627e0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a69fd0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[user(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #497:
Returning [GID:500@domain.ad] from cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #497:
Filtering out results by negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GROUP/domain.ad/tester(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#497: Found 1 entries in domain domain.ad
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #497: Finished:
Success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.ad/user(a)domain.ad]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [nss_getby_id] (0x0400): Input ID: 1095200010
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_plugin] (0x2000): CR #498: Setting
"Group by ID" plugin
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_send] (0x0400): CR #498: New request
'Group by ID'
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_select_domains] (0x0400): CR #498:
Performing a multi-domain search
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_domains] (0x0400): CR #498:
Search will check the cache and check the data provider
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/DOM_LOCATE_TYPE/domain.test/Group by ID]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_validate_domain_type] (0x2000): Request
type POSIX-only for domain domain.test type POSIX is valid
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_set_domain] (0x0400): CR #498: Using
domain [domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #498: Looking
up GID:1095200010@domain.test
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #498:
Checking negative cache for [GID:1095200010@domain.test]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/domain.test/1095200010]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GID/1095200010]
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache] (0x0400): CR #498:
[GID:1095200010@domain.test] is not present in negative cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_cache] (0x0400): CR #498: Looking
up [GID:1095200010@domain.test] in cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a671e0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a672b0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a671e0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a672b0 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a671e0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a5a0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a6a670
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a5a0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a670 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a5a0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a6a670
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a60490
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a6a670
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a60490 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a6a670 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a620f0
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a67e60
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a620f0
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a67e60 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a620f0 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x556496a5ed70
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x556496a67790
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Running timer event 0x556496a5ed70
"ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a67790 "ltdb_timeout"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [ldb] (0x4000): Destroying timer event
0x556496a5ed70 "ltdb_callback"
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[user(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): No
override name available.
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [sysdb_add_group_member_overrides] (0x4000): Added
[one(a)domain.ad] to [overridememberUid].
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_send] (0x0400): CR #498:
Returning [GID:1095200010@domain.test] from cache
(Mon Oct 28 16:43:37 2019) [sssd[nss]] [cache_req_search_ncache_filter] (0x0400): CR #498:
Filtering out results by negative cache
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/GROUP/domain.test/ad_groups(a)domain.test]
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [cache_req_create_and_add_result] (0x0400): CR
#498: Found 1 entries in domain domain.test
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [cache_req_done] (0x0400): CR #498: Finished:
Success
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.test/user(a)domain.ad]
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_ncache_check_str] (0x2000): Checking negative
cache for [NCE/USER/domain.test/one(a)domain.ad]
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.test
is Active
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [sss_domain_get_state] (0x1000): Domain domain.ad
is Active
(Mon Oct 28 16:43:38 2019) [sssd[nss]] [nss_protocol_done] (0x4000): Sending reply:
success
(Mon Oct 28 16:43:46 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a642f0][21]
(Mon Oct 28 16:43:46 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a55990][22]
(Mon Oct 28 16:43:46 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a626b0][23]
(Mon Oct 28 16:43:57 2019) [sssd[nss]] [setup_client_idle_timer] (0x4000): Idle timer
re-set for client [0x556496a64ec0][24]
Regards,
Dani