Ticket #2030 (closed defect: fixed)

Opened 9 months ago

Last modified 6 months ago

getent response requires sssd restart after trust add

Reported by: steeve Owned by: sbose
Priority: minor Milestone: SSSD 1.11.2
Component: SSSD Version: 1.10.1
Keywords: Cc:
Blocked By: Blocking:
Tests Updated: no Coverity Bug:
Patch Submitted: yes Red Hat Bugzilla: 0
Design link:
Feature Milestone:
Design review: no Fedora test page:
Chosen: Candidate to push out: no
Release Notes: N/A this is mostly a bugfix

Description

getent passwd/group gives no response after trust is added for the first time. It works after sssd is restarted.

==> /var/log/sssd/sssd_nss.log <==
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[29533].
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [33] with input [testgroup@ADLABS.COM].
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f2ad94ece00:domains@ipa.example.org]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [ipa.example.org][forced][ADLABS.COM]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x7f2adae6c790
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): 
==> /var/log/sssd/sssd_ipa.example.org.log <==
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [sbus_dispatch] (0x4000): 
==> /var/log/sssd/sssd_nss.log <==
Entering request [0x7f2ad94ece00:domains@ipa.example.org]

==> /var/log/sssd/sssd_ipa.example.org.log <==
dbus conn: 7F84877CE990
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [sbus_message_handler] (0x4000): Received SBUS method [getDomains]
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [be_get_subdomains] (0x0400): Got get subdomains [forced][ADLABS.COM]
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [be_queue_request] (0x4000): Queue is empty, running request immediately.
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [be_queue_request] (0x4000): Adding request to queue.
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [ipa_subdomains_handler] (0x4000): Subdomain provider disabled.
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [get_subdomains_callback] (0x0400): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jul 25 20:15:40 2013) [sssd[be[ipa.example.org]]] [be_queue_next_request] (0x4000): Request queue is empty.

==> /var/log/sssd/sssd_nss.log <==
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x7f2adae6c790
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn: 7F2ADAE6CD40
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f2adae7e5e0

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f2adae7e710

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Running timer event 0x7f2adae7e5e0 "ltdb_callback"

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x7f2adae7e710 "ltdb_timeout"

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x7f2adae7e5e0 "ltdb_callback"

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f2adae7e710

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f2adae785e0

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Running timer event 0x7f2adae7e710 "ltdb_callback"

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x7f2adae785e0 "ltdb_timeout"

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x7f2adae7e710 "ltdb_callback"

(Thu Jul 25 20:15:40 2013) [sssd[nss]] [nss_cmd_getbynam_done] (0x0040): Invalid name received [testgroup@ADLABS.COM]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f2ad94ece00:domains@ipa.example.org]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [client_recv] (0x0200): Client disconnected!
(Thu Jul 25 20:15:40 2013) [sssd[nss]] [client_destructor] (0x2000): Terminated client [0x7f2adae738b0][23]

==> /var/log/sssd/sssd_ipa.example.org.log <==
(Thu Jul 25 20:15:43 2013) [sssd[be[ipa.example.org]]] [sbus_dispatch] (0x4000): dbus conn: 7F84877B72A0

==> /var/log/sssd/sssd_nss.log <==
(Thu Jul 25 20:15:43 2013) [sssd[nss]] [sbus_dispatch] (0x4000): 
==> /var/log/sssd/sssd_ipa.example.org.log <==
(Thu Jul 25 20:15:43 2013) [sssd[be[ipa.example.org]]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Jul 25 20:15:43 2013) [sssd[be[ipa.example.org]]] [sbus_message_handler] (0x4000): Received SBUS method [ping]

==> /var/log/sssd/sssd_nss.log <==
dbus conn: 7F2ADAE6FEF0
(Thu Jul 25 20:15:43 2013) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Jul 25 20:15:43 2013) [sssd[nss]] [sbus_message_handler] (0x4000): Received SBUS method [ping]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [get_client_cred] (0x4000): Client creds: euid[0] egid[0] pid[29534].
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [testuser@ADLABS.COM].
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f2ad94ece00:domains@ipa.example.org]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [ipa.example.org][forced][ADLABS.COM]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sbus_add_timeout] (0x2000): 0x7f2adae6cfd0
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f2ad94ece00:domains@ipa.example.org]

==> /var/log/sssd/sssd_ipa.example.org.log <==
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [sbus_dispatch] (0x4000): dbus conn: 7F84877CE990
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [sbus_message_handler] (0x4000): Received SBUS method [getDomains]
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [be_get_subdomains] (0x0400): Got get subdomains [forced][ADLABS.COM]
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [be_queue_request] (0x4000): Queue is empty, running request immediately.
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [be_queue_request] (0x4000): Adding request to queue.
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [ipa_subdomains_handler] (0x4000): Subdomain provider disabled.
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [get_subdomains_callback] (0x0400): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jul 25 20:15:45 2013) [sssd[be[ipa.example.org]]] [be_queue_next_request] (0x4000): Request queue is empty.

==> /var/log/sssd/sssd_nss.log <==
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sbus_remove_timeout] (0x2000): 0x7f2adae6cfd0
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sbus_dispatch] (0x4000): dbus conn: 7F2ADAE6CD40
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sbus_dispatch] (0x4000): Dispatching.
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f2adae7e5e0

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f2adae7e710

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Running timer event 0x7f2adae7e5e0 "ltdb_callback"

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x7f2adae7e710 "ltdb_timeout"

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x7f2adae7e5e0 "ltdb_callback"

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x7f2adae7e710

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x7f2adae785e0

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Running timer event 0x7f2adae7e710 "ltdb_callback"

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Destroying timer event 0x7f2adae785e0 "ltdb_timeout"

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [ldb] (0x4000): Ending timer event 0x7f2adae7e710 "ltdb_callback"

(Thu Jul 25 20:15:45 2013) [sssd[nss]] [nss_cmd_getbynam_done] (0x0040): Invalid name received [testuser@ADLABS.COM]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f2ad94ece00:domains@ipa.example.org]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [reset_idle_timer] (0x4000): Idle timer re-set for client [0x7f2adae738b0][23]
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [client_recv] (0x0200): Client disconnected!
(Thu Jul 25 20:15:45 2013) [sssd[nss]] [client_destructor] (0x2000): Terminated client [0x7f2adae738b0][23]

Change History

comment:1 Changed 9 months ago by dpal

  • Red Hat Bugzilla set to 0
  • Milestone changed from NEEDS_TRIAGE to SSSD 1.11

comment:2 Changed 7 months ago by jhrozek

  • Priority changed from major to minor

This is more of a usability/timeout bug.

comment:3 Changed 7 months ago by jhrozek

  • Milestone changed from SSSD 1.11.1 to SSSD 1.11.2

Will be fixed in 1.11.2

comment:4 Changed 7 months ago by dpal

Several use cases:

  • Server case - we need to restart SSSD on servers. Send signal to SSSD or do service restart.
  • Client case - signal clients to restart. This mechanism does not exist. SSSD will re-fetch in a matter of minutes so this is not a big deal. Restarting clients would be another potential use case for OpenLMI.

We are going to focus on the server side case in the scope of this ticket.

comment:5 Changed 6 months ago by sbose

  • Owner changed from somebody to sbose
  • Status changed from new to assigned

comment:6 Changed 6 months ago by sbose

  • Patch Submitted set

comment:8 Changed 6 months ago by jhrozek

  • Release Notes modified (diff)
Note: See TracTickets for help on using tickets.