[Freeipa-users] IPA Client will authenticate users
Michael Rainey (Contractor)
michael.rainey.ctr at nrlssc.navy.mil
Thu Jan 19 22:33:59 UTC 2017
Hello everyone,
I have come across a problem which you might find interesting. With all
of the systems I have running, there is one system which refuses to
authenticate any user who needs to login. I have deleted and
reinstalled the to the domain in the hopes it would resolve the
problem. I have copied pam files from working systems to the failing
system to see if this action would fix the problem. No such luck.
I've included the sssd_pam.log file starters.
Thanks in advance.
--
*Michael Rainey*
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [server_setup] (0x0400): CONFDB:
/var/lib/sss/db/config.ldb
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [confdb_get_domain_internal]
(0x0400): No enumeration for [domain.example.com]!
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [confdb_get_domain_internal]
(0x1000): pwd_expiration_warning is -1
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_init_connection] (0x0400):
Adding connection 0x7f297b4c37c0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_add_watch] (0x2000):
0x7f297b4c4ec0/0x7f297b4c3670 (15), -/W (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3030 (15), R/- (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_opath_hash_add_iface]
(0x0400): Registering interface org.freedesktop.sssd.service with path
/org/freedesktop/sssd/service
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_conn_register_path]
(0x0400): Registering object path /org/freedesktop/sssd/service with
D-Bus connection
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_opath_hash_add_iface]
(0x0400): Registering interface org.freedesktop.DBus.Properties with
path /org/freedesktop/sssd/service
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_opath_hash_add_iface]
(0x0400): Registering interface org.freedesktop.DBus.Introspectable with
path /org/freedesktop/sssd/service
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [monitor_common_send_id]
(0x0100): Sending ID: (pam,1)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c4360
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3030 (15), R/- (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3670 (15), -/W (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_names_init_from_args]
(0x0100): Using re
[(((?P<domain>[^\\]+)\\(?P<name>.+$))|((?P<name>[^@]+)@(?P<domain>.+$))|(^(?P<name>[^@\\]+)$))].
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_fqnames_init] (0x0100):
Using fq format [%1$s@%2$s].
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_init_connection] (0x0400):
Adding connection 0x7f297b4c6870
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_add_watch] (0x2000):
0x7f297b4c7590/0x7f297b4c6370 (16), -/W (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c63c0 (16), R/- (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [rdp_message_send_internal]
(0x0400): DP Request: /org/freedesktop/sssd/dataprovider
org.freedesktop.sssd.DataProvider.Client.Register
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c7da0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c63c0 (16), R/- (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c6370 (16), -/W (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sysdb_domain_init_internal]
(0x0200): DB File for domain.example.com:
/var/lib/sss/db/cache_domain.example.com.ldb
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sysdb_domain_init_internal]
(0x0200): Timestamp file for domain.example.com:
/var/lib/sss/db/timestamps_domain.example.com.ldb
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4ca6f0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4ca7b0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4ca6f0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4ca7b0 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4ca6f0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x0400): asq: Unable to
register control with rootdse!
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4ca890
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4ca950
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4ca890 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4ca950 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4ca890 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4caaf0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4cabb0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4caaf0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4cabb0 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4caaf0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4cac70
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4cc0f0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4cac70 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4cc0f0 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4cac70 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): no modules
required by the db
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): No modules
specified for this database
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4cc0f0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4cc1b0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4cc0f0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4cc1b0 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4cc0f0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4cc350
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4cc410
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4cc350 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4cc410 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4cc350 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_process_init] (0x0400):
Responder Initialization complete
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [get_trusted_uids] (0x0400): All
UIDs are allowed.
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'root' matched without domain, user is root
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/domain.example.com/root at domain.example.com] to negative
cache permanently
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'root' matched without domain, user is root
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/GROUP/domain.example.com/root at domain.example.com] to
negative cache permanently
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [responder_set_fd_limit]
(0x0100): Maximum file descriptors set to [8192]
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for [0x7f29796044f0:domains at domain.example.com]
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_dp_get_domains_msg]
(0x0400): Sending get domains request for [domain.example.com][]
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4ca780
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request [0x7f29796044f0:domains at domain.example.com]
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c37c0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c37c0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3030 (15), R/- (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3670 (15), -/W (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3030 (15), R/- (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3670 (15), -/W (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3030 (15), R/- (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3670 (15), -/W (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3030 (15), R/- (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c4ec0/0x7f297b4c3670 (15), -/W (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c4360
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c37c0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [id_callback] (0x0100): Got id
ack and version (1) from Monitor
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c63c0 (16), R/- (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c6370 (16), -/W (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c63c0 (16), R/- (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c6370 (16), -/W (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c63c0 (16), R/- (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c6370 (16), -/W (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c63c0 (16), R/- (enabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_toggle_watch] (0x4000):
0x7f297b4c7590/0x7f297b4c6370 (16), -/W (disabled)
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c7da0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [rdp_process_pending_call]
(0x0400): DP Success
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [rdp_register_client_done]
(0x0400): Client is registered with DP
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4ca780
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d14c0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d1580
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d14c0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d1580 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d14c0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d14c0
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d1580
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d14c0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d1580 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d14c0 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4c5020
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4c5830
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4c5020 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4c5830 "ltdb_timeout"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4c5020 "ltdb_callback"
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'root' matched without domain, user is root
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/USER/domain.example.com/root at domain.example.com] to negative
cache permanently
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'root' matched without domain, user is root
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_ncache_set_str] (0x0400):
Adding [NCE/GROUP/domain.example.com/root at domain.example.com] to
negative cache permanently
(Wed Jan 18 15:31:30 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
Deleting request: [0x7f29796044f0:domains at domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [get_client_cred] (0x4000):
Client creds: euid[0] egid[0] pid[4047].
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [get_client_cred] (0x0020):
SELINUX_getpeercon failed [-1][Unknown error -1].
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [accept_fd_handler] (0x0400):
Client connected to privileged pipe!
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_cmd_preauth] (0x0100):
entering pam_cmd_preauth
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_PREAUTH
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4047
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x4000): User [my_username] not found in PAM cache.
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for
[domain.example.com][0x3][BE_REQ_INITGROUPS][1][name=my_username at domain.example.com:-]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c7710
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c7710
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d6950
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d6a10
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d6950 "ltdb_callback"
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d6a10 "ltdb_timeout"
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d6950 "ltdb_callback"
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4ca5b0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d77b0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4ca5b0 "ltdb_callback"
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d77b0 "ltdb_timeout"
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4ca5b0 "ltdb_callback"
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000):
[my_username] added to PAM initgroup cache
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_PREAUTH
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4047
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c5ab0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
Deleting request:
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c5ab0
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [0 (Success)][domain.example.com]
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 49
(Wed Jan 18 15:32:23 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:28 2017) [sssd[pam]] [pam_initgr_cache_remove]
(0x2000): [my_username] removed from PAM initgroup cache
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_cmd_authenticate] (0x0100):
entering pam_cmd_authenticate
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_AUTHENTICATE
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4047
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x4000): User [my_username] not found in PAM cache.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for
[domain.example.com][0x3][BE_REQ_INITGROUPS][1][name=my_username at domain.example.com:-]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c5ab0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c5ab0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d8440
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d8500
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d8440 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d8500 "ltdb_timeout"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d8440 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d8060
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4cc790
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d8060 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4cc790 "ltdb_timeout"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d8060 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000):
[my_username] added to PAM initgroup cache
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_AUTHENTICATE
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4047
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4cb230
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
Deleting request:
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4cb230
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [0 (Success)][domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4ce3a0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4da100
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4ce3a0 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4da100 "ltdb_timeout"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4ce3a0 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): start ldb
transaction (nesting: 0)
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4e53d0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4e5490
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4e53d0 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4e5490 "ltdb_timeout"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4e53d0 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): commit ldb
transaction (nesting: 0)
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 90
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_cmd_acct_mgmt] (0x0100):
entering pam_cmd_acct_mgmt
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_ACCT_MGMT
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4047
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x2000): User [my_username] found in PAM cache.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4c7a00
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d7b20
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4c7a00 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d7b20 "ltdb_timeout"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4c7a00 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d7b20
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d7660
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d7b20 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d7660 "ltdb_timeout"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d7b20 "ltdb_callback"
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_ACCT_MGMT
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4047
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c5ab0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c5ab0
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [4 (System error)][domain.example.com]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [4]: System error.
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 41
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [client_recv] (0x0200): Client
disconnected!
(Wed Jan 18 15:32:30 2017) [sssd[pam]] [client_close_fn] (0x2000):
Terminated client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:35 2017) [sssd[pam]] [pam_initgr_cache_remove]
(0x2000): [my_username] removed from PAM initgroup cache
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [get_client_cred] (0x4000):
Client creds: euid[0] egid[0] pid[4096].
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [get_client_cred] (0x0020):
SELINUX_getpeercon failed [-1][Unknown error -1].
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [accept_fd_handler] (0x0400):
Client connected to privileged pipe!
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_cmd_preauth] (0x0100):
entering pam_cmd_preauth
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_PREAUTH
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4096
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x4000): User [my_username] not found in PAM cache.
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for
[domain.example.com][0x3][BE_REQ_INITGROUPS][1][name=my_username at domain.example.com:-]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4d8240
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4d8240
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d7c50
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d7d10
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d7c50 "ltdb_callback"
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d7d10 "ltdb_timeout"
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d7c50 "ltdb_callback"
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d6700
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d7660
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d6700 "ltdb_callback"
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d7660 "ltdb_timeout"
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d6700 "ltdb_callback"
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000):
[my_username] added to PAM initgroup cache
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_PREAUTH
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4096
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4cca10
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
Deleting request:
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4cca10
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [0 (Success)][domain.example.com]
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 49
(Wed Jan 18 15:32:49 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:54 2017) [sssd[pam]] [pam_initgr_cache_remove]
(0x2000): [my_username] removed from PAM initgroup cache
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_cmd_authenticate] (0x0100):
entering pam_cmd_authenticate
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_AUTHENTICATE
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4096
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x4000): User [my_username] not found in PAM cache.
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for
[domain.example.com][0x3][BE_REQ_INITGROUPS][1][name=my_username at domain.example.com:-]
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4d6da0
(Wed Jan 18 15:32:55 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4d6da0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4cc990
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4c7860
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4cc990 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4c7860 "ltdb_timeout"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4cc990 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4cbf00
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4cb3b0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4cbf00 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4cb3b0 "ltdb_timeout"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4cbf00 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000):
[my_username] added to PAM initgroup cache
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_AUTHENTICATE
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4096
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4e4b80
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
Deleting request:
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4e4b80
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [0 (Success)][domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4e5bf0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4dac00
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4e5bf0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4dac00 "ltdb_timeout"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4e5bf0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): start ldb
transaction (nesting: 0)
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4d1eb0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4e5610
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4d1eb0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4e5610 "ltdb_timeout"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4d1eb0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): commit ldb
transaction (nesting: 0)
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 90
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_cmd_acct_mgmt] (0x0100):
entering pam_cmd_acct_mgmt
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_ACCT_MGMT
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4096
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x2000): User [my_username] found in PAM cache.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4cb3b0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d1eb0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4cb3b0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d1eb0 "ltdb_timeout"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4cb3b0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4ccba0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4db030
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4ccba0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4db030 "ltdb_timeout"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4ccba0 "ltdb_callback"
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_ACCT_MGMT
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 4096
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c9bf0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c9bf0
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [4 (System error)][domain.example.com]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [4]: System error.
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 41
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c0f60][21]
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [client_recv] (0x0200): Client
disconnected!
(Wed Jan 18 15:32:56 2017) [sssd[pam]] [client_close_fn] (0x2000):
Terminated client [0x7f297b4c0f60][21]
(Wed Jan 18 15:33:01 2017) [sssd[pam]] [pam_initgr_cache_remove]
(0x2000): [my_username] removed from PAM initgroup cache
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [get_client_cred] (0x4000):
Client creds: euid[0] egid[0] pid[5545].
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [get_client_cred] (0x0020):
SELINUX_getpeercon failed [-1][Unknown error -1].
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c9b50][21]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [accept_fd_handler] (0x0400):
Client connected to privileged pipe!
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c9b50][21]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c9b50][21]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c9b50][21]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_cmd_preauth] (0x0100):
entering pam_cmd_preauth
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_PREAUTH
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 5545
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x4000): User [my_username] not found in PAM cache.
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for
[domain.example.com][0x3][BE_REQ_INITGROUPS][1][name=my_username at domain.example.com:-]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4d6da0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4d6da0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4db170
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4d6870
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4db170 "ltdb_callback"
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4d6870 "ltdb_timeout"
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4db170 "ltdb_callback"
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4db170
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4daee0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4db170 "ltdb_callback"
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4daee0 "ltdb_timeout"
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4db170 "ltdb_callback"
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pd_set_primary_name] (0x0400):
User's primary name is my_username at domain.example.com
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_initgr_cache_set] (0x2000):
[my_username] added to PAM initgroup cache
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_PREAUTH
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: domain.example.com
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username at domain.example.com
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 5545
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4db5d0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
Deleting request:
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4db5d0
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_dp_process_reply] (0x0200):
received: [0 (Success)][domain.example.com]
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0]: Success.
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [pam_reply] (0x0200): blen: 49
(Wed Jan 18 15:49:07 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c9b50][21]
(Wed Jan 18 15:49:12 2017) [sssd[pam]] [pam_initgr_cache_remove]
(0x2000): [my_username] removed from PAM initgroup cache
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [reset_idle_timer] (0x4000): Idle
timer re-set for client [0x7f297b4c9b50][21]
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_cmd_authenticate] (0x0100):
entering pam_cmd_authenticate
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'my_username' matched without domain, user is my_username
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100):
command: SSS_PAM_AUTHENTICATE
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100): user:
my_username
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100):
service: sshd
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100): ruser:
not set
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100): rhost:
relentless
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 5545
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_print_data] (0x0100): logon
name: my_username
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sss_ncache_check_str] (0x2000):
Checking negative cache for
[NCE/USER/domain.example.com/my_username at domain.example.com]
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_initgr_check_timeout]
(0x4000): User [my_username] not found in PAM cache.
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for
[domain.example.com][0x3][BE_REQ_INITGROUPS][1][name=my_username at domain.example.com:-]
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sbus_add_timeout] (0x2000):
0x7f297b4c8070
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sss_dp_internal_get_send]
(0x0400): Entering request
[0x7f2979602c30:3:my_username at domain.example.com@domain.example.com]
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sbus_remove_timeout] (0x2000):
0x7f297b4c8070
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sbus_dispatch] (0x4000): dbus
conn: 0x7f297b4c6870
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sbus_dispatch] (0x4000):
Dispatching.
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message: Success
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [my_username at domain.example.com]
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_callback": 0x7f297b4c9eb0
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [ldb] (0x4000): Added timed event
"ltdb_timeout": 0x7f297b4c9f70
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [ldb] (0x4000): Running timer
event 0x7f297b4c9eb0 "ltdb_callback"
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [ldb] (0x4000): Destroying timer
event 0x7f297b4c9f70 "ltdb_timeout"
(Wed Jan 18 15:49:15 2017) [sssd[pam]] [ldb] (0x4000): Ending timer
event 0x7f297b4c9eb0 "ltdb_callback"
(Wed Jan
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/freeipa-users/attachments/20170119/4dd9cc50/attachment.htm>
More information about the Freeipa-users
mailing list