Attachment 701109 Details for Bug 914433 – sssd_pam.log

sssd_pam.log

sssd_pam.log (text/plain), 146.63 KB, created by Maxim Egorushkin on 2013-02-22 10:17:33 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Maxim Egorushkin

Created: 2013-02-22 10:17:33 UTC

Size: 146.63 KB

patch

obsolete

>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [server_setup] (0x0400): CONFDB: /var/lib/sss/db/config.ldb
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [confdb_get_domain_internal] (0x0400): No enumeration for [xyz.com]!
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_init_connection] (0x0200): Adding connection 1201010
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_add_watch] (0x2000): 0x12027d0/0x11ffba0 (14), -/W (enabled)
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [monitor_common_send_id] (0x0100): Sending ID: (pam,1)
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_names_init] (0x0100): Using re [(((?P<domain>[^\\]+)\\(?P<name>.+$))|((?P<name>[^@]+)@(?P<domain>.+$))|(^(?P<name>[^@\\]+)$))].
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_init_connection] (0x0200): Adding connection 11FFF50
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_add_watch] (0x2000): 0x1200df0/0x1202560 (15), -/W (enabled)
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [dp_common_send_id] (0x0100): Sending ID to DP: (1,PAM)
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1204c20
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sysdb_domain_init_internal] (0x0200): DB File for xyz.com: /var/lib/sss/db/cache_xyz.com.ldb
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [ldb] (0x0400): asq: Unable to register control with rootdse!
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_process_init] (0x0400): Responder Initialization complete
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/xyz.com/root] to negative cache permanently
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sss_ncache_set_str] (0x0400): Adding [NCE/GROUP/xyz.com/root] to negative cache permanently
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [responder_set_fd_limit] (0x0100): Maximum file descriptors set to [8192]
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [id_callback] (0x0100): Got id ack and version (1) from Monitor
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1204c20
>(Fri Feb 22 10:02:04 2013) [sssd[pam]] [dp_id_callback] (0x0100): Got id ack and version (1) from DP
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_cmd_acct_mgmt] (0x0100): entering pam_cmd_acct_mgmt
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'max' matched without domain, user is max
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_ACCT_MGMT
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): user: max
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17645
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/max]
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:max@xyz.com]
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=max]
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x11ffd00
>(Fri Feb 22 10:02:13 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:max@xyz.com]
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x11ffd00
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [max@xyz.com]
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [max@xyz.com]
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [max] added to PAM initgroup cache
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_ACCT_MGMT
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): user: max
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17645
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:max@xyz.com]
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [write_selinux_login_file] (0x0040): creating the temp file for SELinux data failed. /etc/selinux/targeted/logins/maxAYGyki(Fri Feb 22 10:02:14 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:02:14 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1206b30][20]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [max] removed from PAM initgroup cache
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected!
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17593
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17593
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f480
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f480
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:02:19 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1206930][20]
>(Fri Feb 22 10:02:24 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17885
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17885
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1205b90
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1205b90
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:03:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:03:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17885
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 17885
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x11fe910
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:03:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1208b40][20]
>(Fri Feb 22 10:03:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18135
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12066d0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12066d0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18135
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:04:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:04:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18135
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18135
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210010
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210010
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:04:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x120fbb0][20]
>(Fri Feb 22 10:04:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18451
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12104b0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12104b0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18451
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:05:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:05:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18451
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f530
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f530
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 18451
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f480
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f480
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:05:17 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1208b40][20]
>(Fri Feb 22 10:05:22 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19053
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12128b0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12128b0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19053
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1204460
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1204460
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19053
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19053
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1205b50
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1205b50
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:05:52 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x120fbb0][20]
>(Fri Feb 22 10:05:57 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19081
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12104b0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12104b0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19081
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12028b0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12028b0
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:06:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:06:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19081
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12128b0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12128b0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19081
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1204460
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1204460
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:06:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x12100a0][20]
>(Fri Feb 22 10:06:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19375
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19375
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:07:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:07:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19375
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19375
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1204460
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1204460
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:07:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x120f1b0][20]
>(Fri Feb 22 10:07:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19692
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1201600
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1201600
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19692
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:08:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:08:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19692
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:08:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19692
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120e880
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120e880
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:08:19 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1210730][20]
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'hmartay' matched without domain, user is hmartay
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): user: hmartay
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19898
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/hmartay]
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:hmartay@xyz.com]
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=hmartay]
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12128b0
>(Fri Feb 22 10:08:24 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:hmartay@xyz.com]
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12128b0
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [hmartay@xyz.com]
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [hmartay@xyz.com]
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [hmartay] added to PAM initgroup cache
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): user: hmartay
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19898
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f270
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:hmartay@xyz.com]
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f270
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:08:25 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:08:30 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [hmartay] removed from PAM initgroup cache
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'hmartay' matched without domain, user is hmartay
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): user: hmartay
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19918
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/hmartay]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:hmartay@xyz.com]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=hmartay]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:hmartay@xyz.com]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [hmartay@xyz.com]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [hmartay@xyz.com]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [hmartay] added to PAM initgroup cache
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): user: hmartay
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19918
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f710
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:hmartay@xyz.com]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f710
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:08:32 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'hmartay' matched without domain, user is hmartay
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): user: hmartay
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19918
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/hmartay]
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [hmartay@xyz.com]
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [hmartay@xyz.com]
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): user: hmartay
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 85.92.217.89
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 19918
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1205b80
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1205b80
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:08:36 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1213ea0][21]
>(Fri Feb 22 10:08:37 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [hmartay] removed from PAM initgroup cache
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20011
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20011
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12028b0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12028b0
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:09:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:09:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20011
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1201600
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1201600
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20011
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12041b0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12041b0
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:09:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1204410][21]
>(Fri Feb 22 10:09:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:09:25 2013) [sssd[pam]] [idle_handler] (0x2000): Terminating idle client [0x1206620][20]
>(Fri Feb 22 10:09:25 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1206620][20]
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20348
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f530
>(Fri Feb 22 10:10:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f530
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20348
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120ee20
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120ee20
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:10:02 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:10:07 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20348
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:10:16 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12088e0
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20348
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203910
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:10:17 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x120fbb0][20]
>(Fri Feb 22 10:10:22 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20792
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20792
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120fc70
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120fc70
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20792
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): service: sshd
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: ssh
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: 176.74.188.135
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20792
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12041b0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12041b0
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:10:52 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1206620][20]
>(Fri Feb 22 10:10:57 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20820
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1203390
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20820
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210530
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210530
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:11:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:11:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20820
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210090
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210090
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 20820
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x12028b0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x12028b0
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:11:19 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x120fbb0][20]
>(Fri Feb 22 10:11:24 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21073
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21073
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120fda0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120fda0
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:12:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:12:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21073
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21073
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120fc00
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120fc00
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:12:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x12040f0][20]
>(Fri Feb 22 10:12:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21322
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210090
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210090
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21322
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120e7e0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120e7e0
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:13:01 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:13:06 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_cmd_close_session] (0x0100): entering pam_cmd_close_session
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21322
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210090
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210090
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_CLOSE_SESSION
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21322
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x120f220
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x120f220
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [client_recv] (0x0200): Client disconnected!
>(Fri Feb 22 10:13:18 2013) [sssd[pam]] [client_destructor] (0x2000): Terminated client [0x1205dc0][20]
>(Fri Feb 22 10:13:23 2013) [sssd[pam]] [pam_initgr_cache_remove] (0x2000): [prod] removed from PAM initgroup cache
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [accept_fd_handler] (0x0400): Client connected to privileged pipe!
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Received client version [3].
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_cmd_get_version] (0x0200): Offered version [3].
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_cmd_open_session] (0x0100): entering pam_cmd_open_session
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): name 'prod' matched without domain, user is prod
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_parse_name_for_domains] (0x0200): using default domain [(null)]
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: not set
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21581
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_ncache_check_str] (0x2000): Checking negative cache for [NCE/USER/xyz.com/prod]
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_dp_issue_request] (0x0400): Issuing request for [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_dp_get_account_msg] (0x0400): Creating request for [xyz.com][3][1][name=prod]
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:14:01 2013) [sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1210140
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got reply from Data Provider - DP error code: 0 errno: 0 error message: Success
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_check_user_search] (0x0100): Requesting info for [prod@xyz.com]
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_check_user_search] (0x0400): Returning info for user [prod@xyz.com]
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_initgr_cache_set] (0x2000): [prod] added to PAM initgroup cache
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_dp_send_req] (0x0100): Sending request with the following data:
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): domain: xyz.com
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): user: prod
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): service: crond
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): tty: cron
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): ruser: not set
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): rhost: not set
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok type: 0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): authtok size: 0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok type: 0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): newauthtok size: 0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): priv: 1
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_print_data] (0x0100): cli_pid: 21581
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [sbus_add_timeout] (0x2000): 0x1213ff0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req returned 0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x41b300:3:prod@xyz.com]
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [sbus_remove_timeout] (0x2000): 0x1213ff0
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_dp_process_reply] (0x0100): received: [0][xyz.com]
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_reply] (0x0200): pam_reply called with result [0].
>(Fri Feb 22 10:14:02 2013) [sssd[pam]] [pam_reply] (0x0100): blen: 40

Actions: View

Attachments on bug 914433: 701109